Build: https://jenkins.thetaphi.de/job/Lucene-Solr-BadApples-8.x-Linux/21/ Java: 64bit/jdk-12-ea+shipilev-fastdebug -XX:-UseCompressedOops -XX:+UseSerialGC
2 tests failed. FAILED: junit.framework.TestSuite.org.apache.solr.core.TestLazyCores Error Message: 1 thread leaked from SUITE scope at org.apache.solr.core.TestLazyCores: 1) Thread[id=376, name=Log4j2-TF-2-AsyncLoggerConfig--2, state=TIMED_WAITING, group=TGRP-TestLazyCores] at java.base@12-testing/jdk.internal.misc.Unsafe.park(Native Method) at java.base@12-testing/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:235) at java.base@12-testing/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2123) at app//com.lmax.disruptor.TimeoutBlockingWaitStrategy.waitFor(TimeoutBlockingWaitStrategy.java:38) at app//com.lmax.disruptor.ProcessingSequenceBarrier.waitFor(ProcessingSequenceBarrier.java:56) at app//com.lmax.disruptor.BatchEventProcessor.run(BatchEventProcessor.java:128) at java.base@12-testing/java.lang.Thread.run(Thread.java:835) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.core.TestLazyCores: 1) Thread[id=376, name=Log4j2-TF-2-AsyncLoggerConfig--2, state=TIMED_WAITING, group=TGRP-TestLazyCores] at java.base@12-testing/jdk.internal.misc.Unsafe.park(Native Method) at java.base@12-testing/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:235) at java.base@12-testing/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2123) at app//com.lmax.disruptor.TimeoutBlockingWaitStrategy.waitFor(TimeoutBlockingWaitStrategy.java:38) at app//com.lmax.disruptor.ProcessingSequenceBarrier.waitFor(ProcessingSequenceBarrier.java:56) at app//com.lmax.disruptor.BatchEventProcessor.run(BatchEventProcessor.java:128) at java.base@12-testing/java.lang.Thread.run(Thread.java:835) at __randomizedtesting.SeedInfo.seed([459F38DC97B1C6D0]:0) FAILED: junit.framework.TestSuite.org.apache.solr.core.TestLazyCores Error Message: There are still zombie threads that couldn't be terminated: 1) Thread[id=376, name=Log4j2-TF-2-AsyncLoggerConfig--2, state=TIMED_WAITING, group=TGRP-TestLazyCores] at java.base@12-testing/jdk.internal.misc.Unsafe.park(Native Method) at java.base@12-testing/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:235) at java.base@12-testing/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2123) at app//com.lmax.disruptor.TimeoutBlockingWaitStrategy.waitFor(TimeoutBlockingWaitStrategy.java:38) at app//com.lmax.disruptor.ProcessingSequenceBarrier.waitFor(ProcessingSequenceBarrier.java:56) at app//com.lmax.disruptor.BatchEventProcessor.run(BatchEventProcessor.java:128) at java.base@12-testing/java.lang.Thread.run(Thread.java:835) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: 1) Thread[id=376, name=Log4j2-TF-2-AsyncLoggerConfig--2, state=TIMED_WAITING, group=TGRP-TestLazyCores] at java.base@12-testing/jdk.internal.misc.Unsafe.park(Native Method) at java.base@12-testing/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:235) at java.base@12-testing/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2123) at app//com.lmax.disruptor.TimeoutBlockingWaitStrategy.waitFor(TimeoutBlockingWaitStrategy.java:38) at app//com.lmax.disruptor.ProcessingSequenceBarrier.waitFor(ProcessingSequenceBarrier.java:56) at app//com.lmax.disruptor.BatchEventProcessor.run(BatchEventProcessor.java:128) at java.base@12-testing/java.lang.Thread.run(Thread.java:835) at __randomizedtesting.SeedInfo.seed([459F38DC97B1C6D0]:0) Build Log: [...truncated 12746 lines...] [junit4] Suite: org.apache.solr.core.TestLazyCores [junit4] 2> 6997 INFO (SUITE-TestLazyCores-seed#[459F38DC97B1C6D0]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/init-core-data-001 [junit4] 2> 7362 INFO (SUITE-TestLazyCores-seed#[459F38DC97B1C6D0]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 7492 INFO (SUITE-TestLazyCores-seed#[459F38DC97B1C6D0]-worker) [ ] o.e.j.u.log Logging initialized @7746ms to org.eclipse.jetty.util.log.Slf4jLog [junit4] 2> 7506 INFO (SUITE-TestLazyCores-seed#[459F38DC97B1C6D0]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0) [junit4] 2> 7613 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBadConfigsGenerateErrors [junit4] 2> 7869 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 8218 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@7d3f2cec, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 9240 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 10489 WARN (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@3c7e2b23[provider=null,keyStore=null,trustStore=null] [junit4] 2> 11062 WARN (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@55dc82a3[provider=null,keyStore=null,trustStore=null] [junit4] 2> 11963 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 11972 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 12325 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d3f2cec [junit4] 2> 12328 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d3f2cec [junit4] 2> 12329 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d3f2cec [junit4] 2> 12509 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ ] o.a.s.c.CorePropertiesLocator Found 6 core definitions underneath /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-001 [junit4] 2> 12522 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [badSchema1, core1, badConfig2, badConfig1, badSchema2, core2] [junit4] 2> 12701 INFO (coreLoadExecutor-9-thread-3) [ x:badConfig2] o.a.s.c.XmlConfigFile Ignoring exception matching \QSAXParseException\E [junit4] 2> 12766 INFO (coreLoadExecutor-9-thread-3) [ x:badConfig1] o.a.s.c.XmlConfigFile Ignoring exception matching \QSAXParseException\E [junit4] 2> 12773 INFO (coreLoadExecutor-9-thread-1) [ x:badSchema1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0 [junit4] 2> 12781 INFO (coreLoadExecutor-9-thread-2) [ x:core1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0 [junit4] 2> 12830 INFO (coreLoadExecutor-9-thread-3) [ x:badSchema2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0 [junit4] 2> 12974 INFO (coreLoadExecutor-9-thread-1) [ x:badSchema1] o.a.s.c.XmlConfigFile Ignoring exception matching \QSAXParseException\E [junit4] 2> 12985 INFO (coreLoadExecutor-9-thread-2) [ x:core1] o.a.s.s.IndexSchema [core1] Schema name=tiny [junit4] 2> 12997 INFO (coreLoadExecutor-9-thread-3) [ x:badSchema2] o.a.s.c.XmlConfigFile Ignoring exception matching \QSAXParseException\E [junit4] 2> 13017 INFO (coreLoadExecutor-9-thread-1) [ x:core2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0 [junit4] 2> 13060 INFO (coreLoadExecutor-9-thread-1) [ x:core2] o.a.s.s.IndexSchema [core2] Schema name=tiny [junit4] 2> 13571 INFO (coreLoadExecutor-9-thread-2) [ x:core1] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id [junit4] 2> 13571 INFO (coreLoadExecutor-9-thread-1) [ x:core2] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id [junit4] 2> 13573 INFO (coreLoadExecutor-9-thread-1) [ x:core2] o.a.s.c.CoreContainer Creating SolrCore 'core2' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-001/core2, trusted=true [junit4] 2> 13580 INFO (coreLoadExecutor-9-thread-2) [ x:core1] o.a.s.c.CoreContainer Creating SolrCore 'core1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-001/core1, trusted=true [junit4] 2> 13715 INFO (coreLoadExecutor-9-thread-1) [ x:core2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.core2' (registry 'solr.core.core2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d3f2cec [junit4] 2> 13715 INFO (coreLoadExecutor-9-thread-2) [ x:core1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.core1' (registry 'solr.core.core1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d3f2cec [junit4] 2> 13763 INFO (coreLoadExecutor-9-thread-2) [ x:core1] o.a.s.c.SolrCore [[core1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-001/core1], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-001/core1/data/] [junit4] 2> 13763 INFO (coreLoadExecutor-9-thread-1) [ x:core2] o.a.s.c.SolrCore [[core2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-001/core2], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-001/core2/data/] [junit4] 2> 13910 INFO (coreLoadExecutor-9-thread-2) [ x:core1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=36, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6948321609299785] [junit4] 2> 13910 INFO (coreLoadExecutor-9-thread-1) [ x:core2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=36, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6948321609299785] [junit4] 2> 16479 INFO (coreLoadExecutor-9-thread-2) [ x:core1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 16479 INFO (coreLoadExecutor-9-thread-2) [ x:core1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 16484 INFO (coreLoadExecutor-9-thread-1) [ x:core2] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 16484 INFO (coreLoadExecutor-9-thread-1) [ x:core2] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 16518 INFO (coreLoadExecutor-9-thread-1) [ x:core2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=33, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 16531 INFO (coreLoadExecutor-9-thread-2) [ x:core1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=33, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 16616 INFO (coreLoadExecutor-9-thread-2) [ x:core1] o.a.s.s.SolrIndexSearcher Opening [Searcher@57b00948[core1] main] [junit4] 2> 16616 INFO (coreLoadExecutor-9-thread-1) [ x:core2] o.a.s.s.SolrIndexSearcher Opening [Searcher@7b15e9c4[core2] main] [junit4] 2> 16690 INFO (coreLoadExecutor-9-thread-1) [ x:core2] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-001/core2/conf [junit4] 2> 16692 INFO (coreLoadExecutor-9-thread-2) [ x:core1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-001/core1/conf [junit4] 2> 16833 INFO (coreLoadExecutor-9-thread-2) [ x:core1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 16833 INFO (coreLoadExecutor-9-thread-1) [ x:core2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 16895 INFO (searcherExecutor-10-thread-1-processing-x:core2) [ x:core2] o.a.s.c.SolrCore [core2] Registered new searcher Searcher@7b15e9c4[core2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 16896 INFO (searcherExecutor-11-thread-1-processing-x:core1) [ x:core1] o.a.s.c.SolrCore [core1] Registered new searcher Searcher@57b00948[core1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 17207 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 17207 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@48a66cdc commitCommandVersion:0 [junit4] 2> 18070 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@40066949[core1] main] [junit4] 2> 18077 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 18101 INFO (searcherExecutor-11-thread-1) [ ] o.a.s.c.SolrCore [core1] Registered new searcher Searcher@40066949[core1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.1.0):C7:[diagnostics={os=Linux, java.vendor=Aleksey Shipilev, java.version=12-testing, java.vm.version=12-testing+0-builds.shipilev.net-openjdk-jdk12-b109-20190215-jdk-1229, lucene.version=8.1.0, os.arch=amd64, java.runtime.version=12-testing+0-builds.shipilev.net-openjdk-jdk12-b109-20190215-jdk-1229, source=flush, os.version=4.18.0-15-generic, timestamp=1550989654519}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 18336 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ ] o.a.s.c.S.Request [core1] webapp=null path=null params={q={!prefix+f%3Dv_t}hel&wt=xml} hits=2 status=0 QTime=193 [junit4] 2> 18481 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ ] o.a.s.c.S.Request [core1] webapp=null path=null params={q={!raw+f%3Dv_t}hello&wt=xml} hits=2 status=0 QTime=8 [junit4] 2> 18485 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ ] o.a.s.c.S.Request [core1] webapp=null path=null params={q={!raw+f%3Dv_t}Hello&wt=xml} hits=0 status=0 QTime=0 [junit4] 2> 18493 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ ] o.a.s.c.S.Request [core1] webapp=null path=null params={q={!raw+f%3Dv_f}1.5&wt=xml} hits=0 status=0 QTime=6 [junit4] 2> 18571 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0 [junit4] 2> 18627 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig1] o.a.s.s.IndexSchema [badConfig1] Schema name=tiny [junit4] 2> 18636 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig1] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id [junit4] 2> 18636 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig1] o.a.s.c.CoreContainer Creating SolrCore 'badConfig1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-001/badConfig1, trusted=true [junit4] 2> 18638 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.badConfig1' (registry 'solr.core.badConfig1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d3f2cec [junit4] 2> 18638 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ ] o.a.s.c.SolrCore [[badConfig1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-001/badConfig1], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-001/badConfig1/data/] [junit4] 2> 18649 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=13, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3795491291598244] [junit4] 2> 18832 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 18832 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 18835 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=48.3369140625, floorSegmentMB=1.076171875, forceMergeDeletesPctAllowed=28.643062227928866, segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.568960672322789, deletesPctAllowed=33.59626430041249 [junit4] 2> 18836 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5544cacb[badConfig1] main] [junit4] 2> 18838 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-001/badConfig1/conf [junit4] 2> 18839 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 18854 INFO (searcherExecutor-24-thread-1) [ ] o.a.s.c.SolrCore [badConfig1] Registered new searcher Searcher@5544cacb[badConfig1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 18866 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0 [junit4] 2> 18893 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.s.IndexSchema [badConfig2] Schema name=tiny [junit4] 2> 18901 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id [junit4] 2> 18901 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.c.CoreContainer Creating SolrCore 'badConfig2' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-001/badConfig2, trusted=true [junit4] 2> 18902 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.badConfig2' (registry 'solr.core.badConfig2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d3f2cec [junit4] 2> 18903 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.c.SolrCore [[badConfig2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-001/badConfig2], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-001/badConfig2/data/] [junit4] 2> 18909 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=15, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8217815097278498] [junit4] 2> 19155 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 19155 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 19159 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=68.5888671875, floorSegmentMB=1.8251953125, forceMergeDeletesPctAllowed=17.688509539178643, segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7711594652512755, deletesPctAllowed=28.568205972534216 [junit4] 2> 19160 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ec84d27[badConfig2] main] [junit4] 2> 19162 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-001/badConfig2/conf [junit4] 2> 19163 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 19173 INFO (searcherExecutor-28-thread-1-processing-x:badConfig2) [ x:badConfig2] o.a.s.c.SolrCore [badConfig2] Registered new searcher Searcher@1ec84d27[badConfig2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 19196 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badSchema1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0 [junit4] 2> 19219 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badSchema1] o.a.s.s.IndexSchema [badSchema1] Schema name=tiny [junit4] 2> 19227 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badSchema1] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id [junit4] 2> 19227 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badSchema1] o.a.s.c.CoreContainer Creating SolrCore 'badSchema1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-001/badSchema1, trusted=true [junit4] 2> 19228 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badSchema1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.badSchema1' (registry 'solr.core.badSchema1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d3f2cec [junit4] 2> 19228 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badSchema1] o.a.s.c.SolrCore [[badSchema1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-001/badSchema1], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-001/badSchema1/data/] [junit4] 2> 19234 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badSchema1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=49, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 19421 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badSchema1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 19421 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badSchema1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 19424 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badSchema1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=62.4697265625, floorSegmentMB=1.6796875, forceMergeDeletesPctAllowed=2.499934415844928, segmentsPerTier=13.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=33.90662686903708 [junit4] 2> 19435 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badSchema1] o.a.s.s.SolrIndexSearcher Opening [Searcher@65a98da9[badSchema1] main] [junit4] 2> 19436 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badSchema1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-001/badSchema1/conf [junit4] 2> 19440 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badSchema1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 19443 INFO (searcherExecutor-32-thread-1-processing-x:badSchema1) [ x:badSchema1] o.a.s.c.SolrCore [badSchema1] Registered new searcher Searcher@65a98da9[badSchema1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 19511 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badSchema2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0 [junit4] 2> 19637 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badSchema2] o.a.s.s.IndexSchema [badSchema2] Schema name=tiny [junit4] 2> 19657 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badSchema2] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id [junit4] 2> 19658 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badSchema2] o.a.s.c.CoreContainer Creating SolrCore 'badSchema2' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-001/badSchema2, trusted=true [junit4] 2> 19662 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badSchema2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.badSchema2' (registry 'solr.core.badSchema2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d3f2cec [junit4] 2> 19662 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badSchema2] o.a.s.c.SolrCore [[badSchema2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-001/badSchema2], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-001/badSchema2/data/] [junit4] 2> 19704 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badSchema2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=29, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=55.9150390625, floorSegmentMB=0.69921875, forceMergeDeletesPctAllowed=14.773259281007348, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=37.15747881244149 [junit4] 2> 19940 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badSchema2] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 19940 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badSchema2] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 19943 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badSchema2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=16, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5935376987315183] [junit4] 2> 19945 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badSchema2] o.a.s.s.SolrIndexSearcher Opening [Searcher@5523625a[badSchema2] main] [junit4] 2> 19946 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badSchema2] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-001/badSchema2/conf [junit4] 2> 19947 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badSchema2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 19954 INFO (searcherExecutor-36-thread-1-processing-x:badSchema2) [ x:badSchema2] o.a.s.c.SolrCore [badSchema2] Registered new searcher Searcher@5523625a[badSchema2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 19961 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 19961 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@48a66cdc commitCommandVersion:0 [junit4] 2> 20208 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.s.SolrIndexSearcher Opening [Searcher@9d5f8a8[core1] main] [junit4] 2> 20211 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 20224 INFO (searcherExecutor-11-thread-1-processing-x:badConfig2) [ x:badConfig2] o.a.s.c.SolrCore [core1] Registered new searcher Searcher@9d5f8a8[core1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.1.0):C7:[diagnostics={os=Linux, java.vendor=Aleksey Shipilev, java.version=12-testing, java.vm.version=12-testing+0-builds.shipilev.net-openjdk-jdk12-b109-20190215-jdk-1229, lucene.version=8.1.0, os.arch=amd64, java.runtime.version=12-testing+0-builds.shipilev.net-openjdk-jdk12-b109-20190215-jdk-1229, source=flush, os.version=4.18.0-15-generic, timestamp=1550989656753}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 20263 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.c.S.Request [core1] webapp=null path=null params={q={!prefix+f%3Dv_t}hel&wt=xml} hits=2 status=0 QTime=1 [junit4] 2> 20267 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.c.S.Request [core1] webapp=null path=null params={q={!raw+f%3Dv_t}hello&wt=xml} hits=2 status=0 QTime=0 [junit4] 2> 20269 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.c.S.Request [core1] webapp=null path=null params={q={!raw+f%3Dv_t}Hello&wt=xml} hits=0 status=0 QTime=0 [junit4] 2> 20275 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.c.S.Request [core1] webapp=null path=null params={q={!raw+f%3Dv_f}1.5&wt=xml} hits=0 status=0 QTime=3 [junit4] 2> 20280 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 20280 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@318c578f commitCommandVersion:0 [junit4] 2> 20607 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.s.SolrIndexSearcher Opening [Searcher@1b028e5f[badConfig1] main] [junit4] 2> 20608 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 20614 INFO (searcherExecutor-24-thread-1-processing-x:badConfig2) [ x:badConfig2] o.a.s.c.SolrCore [badConfig1] Registered new searcher Searcher@1b028e5f[badConfig1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.1.0):C7:[diagnostics={os=Linux, java.vendor=Aleksey Shipilev, java.version=12-testing, java.vm.version=12-testing+0-builds.shipilev.net-openjdk-jdk12-b109-20190215-jdk-1229, lucene.version=8.1.0, os.arch=amd64, java.runtime.version=12-testing+0-builds.shipilev.net-openjdk-jdk12-b109-20190215-jdk-1229, source=flush, os.version=4.18.0-15-generic, timestamp=1550989657086}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 20617 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.c.S.Request [badConfig1] webapp=null path=null params={q={!prefix+f%3Dv_t}hel&wt=xml} hits=2 status=0 QTime=1 [junit4] 2> 20620 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.c.S.Request [badConfig1] webapp=null path=null params={q={!raw+f%3Dv_t}hello&wt=xml} hits=2 status=0 QTime=0 [junit4] 2> 20622 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.c.S.Request [badConfig1] webapp=null path=null params={q={!raw+f%3Dv_t}Hello&wt=xml} hits=0 status=0 QTime=0 [junit4] 2> 20632 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.c.S.Request [badConfig1] webapp=null path=null params={q={!raw+f%3Dv_f}1.5&wt=xml} hits=0 status=0 QTime=0 [junit4] 2> 20637 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 20637 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5d726189 commitCommandVersion:0 [junit4] 2> 20815 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.s.SolrIndexSearcher Opening [Searcher@241341fb[badSchema1] main] [junit4] 2> 20816 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 20825 INFO (searcherExecutor-32-thread-1-processing-x:badConfig2) [ x:badConfig2] o.a.s.c.SolrCore [badSchema1] Registered new searcher Searcher@241341fb[badSchema1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.1.0):C7:[diagnostics={os=Linux, java.vendor=Aleksey Shipilev, java.version=12-testing, java.vm.version=12-testing+0-builds.shipilev.net-openjdk-jdk12-b109-20190215-jdk-1229, lucene.version=8.1.0, os.arch=amd64, java.runtime.version=12-testing+0-builds.shipilev.net-openjdk-jdk12-b109-20190215-jdk-1229, source=flush, os.version=4.18.0-15-generic, timestamp=1550989657434}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 20827 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.c.S.Request [badSchema1] webapp=null path=null params={q={!prefix+f%3Dv_t}hel&wt=xml} hits=2 status=0 QTime=1 [junit4] 2> 20830 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.c.S.Request [badSchema1] webapp=null path=null params={q={!raw+f%3Dv_t}hello&wt=xml} hits=2 status=0 QTime=0 [junit4] 2> 20833 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.c.S.Request [badSchema1] webapp=null path=null params={q={!raw+f%3Dv_t}Hello&wt=xml} hits=0 status=0 QTime=0 [junit4] 2> 20835 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.c.S.Request [badSchema1] webapp=null path=null params={q={!raw+f%3Dv_f}1.5&wt=xml} hits=0 status=0 QTime=0 [junit4] 2> 20836 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.c.CoreContainer Shutting down CoreContainer instance=831940120 [junit4] 2> 20888 INFO (coreCloseExecutor-46-thread-1-processing-x:badConfig2) [ x:core2] o.a.s.c.SolrCore [core2] CLOSING SolrCore org.apache.solr.core.SolrCore@349852bf [junit4] 2> 20888 INFO (coreCloseExecutor-46-thread-1-processing-x:badConfig2) [ x:core2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.core2, tag=349852bf [junit4] 2> 20888 INFO (coreCloseExecutor-46-thread-2-processing-x:badConfig2) [ x:core1] o.a.s.c.SolrCore [core1] CLOSING SolrCore org.apache.solr.core.SolrCore@5d906d2e [junit4] 2> 20888 INFO (coreCloseExecutor-46-thread-3-processing-x:badConfig2) [ x:badSchema1] o.a.s.c.SolrCore [badSchema1] CLOSING SolrCore org.apache.solr.core.SolrCore@64555361 [junit4] 2> 20892 INFO (coreCloseExecutor-46-thread-1-processing-x:badConfig2) [ x:core2] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@39d7f59f: rootName = null, domain = solr.core.core2, service url = null, agent id = null] for registry solr.core.core2 / com.codahale.metrics.MetricRegistry@2a997a5f [junit4] 2> 20923 INFO (coreCloseExecutor-46-thread-5-processing-x:badConfig2) [ x:badConfig1] o.a.s.c.SolrCore [badConfig1] CLOSING SolrCore org.apache.solr.core.SolrCore@73be1663 [junit4] 2> 20923 INFO (coreCloseExecutor-46-thread-4-processing-x:badConfig2) [ x:badSchema2] o.a.s.c.SolrCore [badSchema2] CLOSING SolrCore org.apache.solr.core.SolrCore@741f1e16 [junit4] 2> 20955 INFO (coreCloseExecutor-46-thread-6-processing-x:badConfig2) [ x:badConfig2] o.a.s.c.SolrCore [badConfig2] CLOSING SolrCore org.apache.solr.core.SolrCore@5fdc4e6d [junit4] 2> 20976 INFO (coreCloseExecutor-46-thread-2-processing-x:badConfig2) [ x:core1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.core1, tag=5d906d2e [junit4] 2> 20977 INFO (coreCloseExecutor-46-thread-2-processing-x:badConfig2) [ x:core1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@178fb81: rootName = null, domain = solr.core.core1, service url = null, agent id = null] for registry solr.core.core1 / com.codahale.metrics.MetricRegistry@1fc2770c [junit4] 2> 21055 INFO (coreCloseExecutor-46-thread-3-processing-x:badConfig2) [ x:badSchema1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.badSchema1, tag=64555361 [junit4] 2> 21056 INFO (coreCloseExecutor-46-thread-3-processing-x:badConfig2) [ x:badSchema1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7cc78b2c: rootName = null, domain = solr.core.badSchema1, service url = null, agent id = null] for registry solr.core.badSchema1 / com.codahale.metrics.MetricRegistry@3c99f5d6 [junit4] 2> 21110 INFO (coreCloseExecutor-46-thread-5-processing-x:badConfig2) [ x:badConfig1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.badConfig1, tag=73be1663 [junit4] 2> 21110 INFO (coreCloseExecutor-46-thread-5-processing-x:badConfig2) [ x:badConfig1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@701bdbb7: rootName = null, domain = solr.core.badConfig1, service url = null, agent id = null] for registry solr.core.badConfig1 / com.codahale.metrics.MetricRegistry@6d2549ae [junit4] 2> 21220 INFO (coreCloseExecutor-46-thread-4-processing-x:badConfig2) [ x:badSchema2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.badSchema2, tag=741f1e16 [junit4] 2> 21220 INFO (coreCloseExecutor-46-thread-4-processing-x:badConfig2) [ x:badSchema2] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5579fe9d: rootName = null, domain = solr.core.badSchema2, service url = null, agent id = null] for registry solr.core.badSchema2 / com.codahale.metrics.MetricRegistry@40e008f [junit4] 2> 21286 INFO (coreCloseExecutor-46-thread-6-processing-x:badConfig2) [ x:badConfig2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.badConfig2, tag=5fdc4e6d [junit4] 2> 21286 INFO (coreCloseExecutor-46-thread-6-processing-x:badConfig2) [ x:badConfig2] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@145845e3: rootName = null, domain = solr.core.badConfig2, service url = null, agent id = null] for registry solr.core.badConfig2 / com.codahale.metrics.MetricRegistry@2d54573a [junit4] 2> 21420 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 21424 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6087a577: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@2f2ecb80 [junit4] 2> 21443 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 21444 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2e2fb04f: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1d373101 [junit4] 2> 21444 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 21444 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5d10c080: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@29922493 [junit4] 1> Done close org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@4a165089[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1] [junit4] 1> Done close org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@6e80e00b[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1] [junit4] 2> 21577 INFO (TEST-TestLazyCores.testBadConfigsGenerateErrors-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.SolrTestCaseJ4 ###Ending testBadConfigsGenerateErrors [junit4] 2> 21655 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.SolrTestCaseJ4 ###Starting testCreateSame [junit4] 2> 21797 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-002/solr.xml [junit4] 2> 21873 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 21873 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 21897 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@7d3f2cec, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 22310 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 22334 WARN (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@139e4189[provider=null,keyStore=null,trustStore=null] [junit4] 2> 22386 WARN (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@5d3c131c[provider=null,keyStore=null,trustStore=null] [junit4] 2> 22447 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 22448 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 22523 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d3f2cec [junit4] 2> 22523 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:badConfig2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d3f2cec [junit4] 2> 22524 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d3f2cec [junit4] 2> 22577 INFO (coreLoadExecutor-56-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0 [junit4] 2> 22601 INFO (coreLoadExecutor-56-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=tiny [junit4] 2> 22609 INFO (coreLoadExecutor-56-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id [junit4] 2> 22609 INFO (coreLoadExecutor-56-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-002/./collection1, trusted=true [junit4] 2> 22610 INFO (coreLoadExecutor-56-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d3f2cec [junit4] 2> 22610 INFO (coreLoadExecutor-56-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-002/./collection1/data/] [junit4] 2> 22636 INFO (coreLoadExecutor-56-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=36, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6948321609299785] [junit4] 2> 22637 INFO (coreLoadExecutor-56-thread-2) [ x:collection2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0 [junit4] 2> 22639 INFO (coreLoadExecutor-56-thread-3) [ x:collection5] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0 [junit4] 2> 22747 INFO (coreLoadExecutor-56-thread-3) [ x:collection5] o.a.s.s.IndexSchema [collection5] Schema name=tiny [junit4] 2> 22759 INFO (coreLoadExecutor-56-thread-2) [ x:collection2] o.a.s.s.IndexSchema [collection2] Schema name=tiny [junit4] 2> 22761 INFO (coreLoadExecutor-56-thread-3) [ x:collection5] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id [junit4] 2> 22761 INFO (coreLoadExecutor-56-thread-3) [ x:collection5] o.a.s.c.CoreContainer Creating SolrCore 'collection5' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-002/./collection5, trusted=true [junit4] 2> 22762 INFO (coreLoadExecutor-56-thread-3) [ x:collection5] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection5' (registry 'solr.core.collection5') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d3f2cec [junit4] 2> 22773 INFO (coreLoadExecutor-56-thread-3) [ x:collection5] o.a.s.c.SolrCore [[collection5] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-002/collection5], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-002/./collection5/data/] [junit4] 2> 22789 INFO (coreLoadExecutor-56-thread-3) [ x:collection5] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=36, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6948321609299785] [junit4] 2> 22795 INFO (coreLoadExecutor-56-thread-2) [ x:collection2] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id [junit4] 2> 22795 INFO (coreLoadExecutor-56-thread-2) [ x:collection2] o.a.s.c.CoreContainer Creating SolrCore 'collection2' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-002/./collection2, trusted=true [junit4] 2> 22795 INFO (coreLoadExecutor-56-thread-2) [ x:collection2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection2' (registry 'solr.core.collection2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d3f2cec [junit4] 2> 22797 INFO (coreLoadExecutor-56-thread-2) [ x:collection2] o.a.s.c.SolrCore [[collection2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-002/collection2], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-002/./collection2/data/] [junit4] 2> 22812 INFO (coreLoadExecutor-56-thread-2) [ x:collection2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=36, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6948321609299785] [junit4] 2> 23265 INFO (coreLoadExecutor-56-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 23265 INFO (coreLoadExecutor-56-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 23284 INFO (coreLoadExecutor-56-thread-3) [ x:collection5] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 23284 INFO (coreLoadExecutor-56-thread-3) [ x:collection5] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 23288 INFO (coreLoadExecutor-56-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=33, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 23290 INFO (coreLoadExecutor-56-thread-3) [ x:collection5] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=33, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 23335 INFO (coreLoadExecutor-56-thread-3) [ x:collection5] o.a.s.s.SolrIndexSearcher Opening [Searcher@1074103[collection5] main] [junit4] 2> 23335 INFO (coreLoadExecutor-56-thread-3) [ x:collection5] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-002/collection5/conf [junit4] 2> 23336 INFO (coreLoadExecutor-56-thread-3) [ x:collection5] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 23338 INFO (searcherExecutor-58-thread-1-processing-x:collection5) [ x:collection5] o.a.s.c.SolrCore [collection5] Registered new searcher Searcher@1074103[collection5] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 23344 INFO (coreLoadExecutor-56-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4e8e3821[collection1] main] [junit4] 2> 23360 INFO (coreLoadExecutor-56-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-002/collection1/conf [junit4] 2> 23361 INFO (coreLoadExecutor-56-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 23363 INFO (searcherExecutor-57-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4e8e3821[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 23388 INFO (coreLoadExecutor-56-thread-2) [ x:collection2] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 23388 INFO (coreLoadExecutor-56-thread-2) [ x:collection2] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 23390 INFO (coreLoadExecutor-56-thread-2) [ x:collection2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=33, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 23397 INFO (coreLoadExecutor-56-thread-2) [ x:collection2] o.a.s.s.SolrIndexSearcher Opening [Searcher@e3bf7e[collection2] main] [junit4] 2> 23397 INFO (coreLoadExecutor-56-thread-2) [ x:collection2] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-002/collection2/conf [junit4] 2> 23398 INFO (coreLoadExecutor-56-thread-2) [ x:collection2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 23422 INFO (searcherExecutor-59-thread-1-processing-x:collection2) [ x:collection2] o.a.s.c.SolrCore [collection2] Registered new searcher Searcher@e3bf7e[collection2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 23517 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0 [junit4] 2> 23540 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection4] o.a.s.s.IndexSchema [collection4] Schema name=tiny [junit4] 2> 23549 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection4] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id [junit4] 2> 23549 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection4] o.a.s.c.CoreContainer Creating SolrCore 'collection4' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-002/./collection4, trusted=true [junit4] 2> 23557 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection4] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection4' (registry 'solr.core.collection4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d3f2cec [junit4] 2> 23557 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection4] o.a.s.c.SolrCore [[collection4] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-002/collection4], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-002/./collection4/data/] [junit4] 2> 23567 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection4] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=10, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 23764 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection4] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 23764 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection4] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 23766 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection4] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=31, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 23774 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection4] o.a.s.s.SolrIndexSearcher Opening [Searcher@2e239f0b[collection4] main] [junit4] 2> 23775 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection4] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-002/collection4/conf [junit4] 2> 23776 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 23778 INFO (searcherExecutor-70-thread-1-processing-x:collection4) [ x:collection4] o.a.s.c.SolrCore [collection4] Registered new searcher Searcher@2e239f0b[collection4] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 23812 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0 [junit4] 2> 23867 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.s.IndexSchema [collection6] Schema name=tiny [junit4] 2> 23874 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id [junit4] 2> 23874 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.c.CoreContainer Creating SolrCore 'collection6' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-002/./collection6, trusted=true [junit4] 2> 23877 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection6' (registry 'solr.core.collection6') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d3f2cec [junit4] 2> 23892 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.c.SolrCore [[collection6] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-002/collection6], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-002/./collection6/data/] [junit4] 2> 23900 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=90.521484375, floorSegmentMB=0.759765625, forceMergeDeletesPctAllowed=12.816306659687175, segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=28.0676755979377 [junit4] 2> 24298 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 24298 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 24301 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=34, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=3.21875, floorSegmentMB=1.2421875, forceMergeDeletesPctAllowed=7.270568311343444, segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.31708610650266733, deletesPctAllowed=49.40329096231183 [junit4] 2> 24302 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.s.SolrIndexSearcher Opening [Searcher@5d5ceb80[collection6] main] [junit4] 2> 24303 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-002/collection6/conf [junit4] 2> 24304 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 24313 INFO (searcherExecutor-74-thread-1-processing-x:collection6) [ x:collection6] o.a.s.c.SolrCore [collection6] Registered new searcher Searcher@5d5ceb80[collection6] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 24322 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection2] o.a.s.h.a.CoreAdminOperation core create command schema=schema.xml&dataDir=t12&name=collection2&action=CREATE&config=solrconfig.xml&wt=xml [junit4] 2> 24328 WARN (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection2] o.a.s.c.CoreContainer Creating a core with existing name is not allowed [junit4] 2> 24328 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection4] o.a.s.h.a.CoreAdminOperation core create command schema=schema.xml&dataDir=t14&name=collection4&action=CREATE&config=solrconfig.xml&wt=xml [junit4] 2> 24329 WARN (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection4] o.a.s.c.CoreContainer Creating a core with existing name is not allowed [junit4] 2> 24329 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection5] o.a.s.h.a.CoreAdminOperation core create command schema=schema.xml&dataDir=t15&name=collection5&action=CREATE&config=solrconfig.xml&wt=xml [junit4] 2> 24329 WARN (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection5] o.a.s.c.CoreContainer Creating a core with existing name is not allowed [junit4] 2> 24329 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.h.a.CoreAdminOperation core create command schema=schema.xml&dataDir=t16&name=collection6&action=CREATE&config=solrconfig.xml&wt=xml [junit4] 2> 24329 WARN (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.c.CoreContainer Creating a core with existing name is not allowed [junit4] 2> 24330 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.c.CoreContainer Shutting down CoreContainer instance=257866234 [junit4] 2> 24331 INFO (coreCloseExecutor-78-thread-1-processing-x:collection6) [ x:collection5] o.a.s.c.SolrCore [collection5] CLOSING SolrCore org.apache.solr.core.SolrCore@1a864885 [junit4] 2> 24331 INFO (coreCloseExecutor-78-thread-1-processing-x:collection6) [ x:collection5] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection5, tag=1a864885 [junit4] 2> 24331 INFO (coreCloseExecutor-78-thread-1-processing-x:collection6) [ x:collection5] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6e9179fa: rootName = null, domain = solr.core.collection5, service url = null, agent id = null] for registry solr.core.collection5 / com.codahale.metrics.MetricRegistry@772ca600 [junit4] 2> 24356 INFO (coreCloseExecutor-78-thread-2-processing-x:collection6) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@533aa054 [junit4] 2> 24364 INFO (coreCloseExecutor-78-thread-3-processing-x:collection6) [ x:collection4] o.a.s.c.SolrCore [collection4] CLOSING SolrCore org.apache.solr.core.SolrCore@12ac3db5 [junit4] 2> 24384 INFO (coreCloseExecutor-78-thread-4-processing-x:collection6) [ x:collection2] o.a.s.c.SolrCore [collection2] CLOSING SolrCore org.apache.solr.core.SolrCore@18012f51 [junit4] 2> 24384 INFO (coreCloseExecutor-78-thread-5-processing-x:collection6) [ x:collection6] o.a.s.c.SolrCore [collection6] CLOSING SolrCore org.apache.solr.core.SolrCore@24f9c4f7 [junit4] 2> 24462 INFO (coreCloseExecutor-78-thread-2-processing-x:collection6) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=533aa054 [junit4] 2> 24462 INFO (coreCloseExecutor-78-thread-2-processing-x:collection6) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@27e4e550: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@43f46e59 [junit4] 2> 24522 INFO (coreCloseExecutor-78-thread-3-processing-x:collection6) [ x:collection4] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection4, tag=12ac3db5 [junit4] 2> 24522 INFO (coreCloseExecutor-78-thread-3-processing-x:collection6) [ x:collection4] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@41aa1ba8: rootName = null, domain = solr.core.collection4, service url = null, agent id = null] for registry solr.core.collection4 / com.codahale.metrics.MetricRegistry@18b38ed6 [junit4] 2> 24572 INFO (coreCloseExecutor-78-thread-4-processing-x:collection6) [ x:collection2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection2, tag=18012f51 [junit4] 2> 24572 INFO (coreCloseExecutor-78-thread-4-processing-x:collection6) [ x:collection2] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2bafee89: rootName = null, domain = solr.core.collection2, service url = null, agent id = null] for registry solr.core.collection2 / com.codahale.metrics.MetricRegistry@308b86c8 [junit4] 2> 24640 INFO (coreCloseExecutor-78-thread-5-processing-x:collection6) [ x:collection6] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection6, tag=24f9c4f7 [junit4] 2> 24640 INFO (coreCloseExecutor-78-thread-5-processing-x:collection6) [ x:collection6] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@471d48ea: rootName = null, domain = solr.core.collection6, service url = null, agent id = null] for registry solr.core.collection6 / com.codahale.metrics.MetricRegistry@32dca45 [junit4] 2> 24728 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 24728 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4745a64: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@4d9dd237 [junit4] 2> 24758 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 24768 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@63ec1311: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1d373101 [junit4] 2> 24768 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 24768 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3d016f0d: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@29922493 [junit4] 1> Done close org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@4a3ae5dd[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1] [junit4] 1> Done close org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@7cce0f00[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1] [junit4] 2> 24804 INFO (TEST-TestLazyCores.testCreateSame-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.SolrTestCaseJ4 ###Ending testCreateSame [junit4] 2> 24826 INFO (TEST-TestLazyCores.testRace-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.SolrTestCaseJ4 ###Starting testRace [junit4] 2> 24858 INFO (TEST-TestLazyCores.testRace-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-003/solr.xml [junit4] 2> 24867 INFO (TEST-TestLazyCores.testRace-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 24868 INFO (TEST-TestLazyCores.testRace-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 24880 INFO (TEST-TestLazyCores.testRace-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@7d3f2cec, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 25124 INFO (TEST-TestLazyCores.testRace-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 25146 WARN (TEST-TestLazyCores.testRace-seed#[459F38DC97B1C6D0]) [ x:collection6] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@3f3d445a[provider=null,keyStore=null,trustStore=null] [junit4] 2> 25226 WARN (TEST-TestLazyCores.testRace-seed#[459F38DC97B1C6D0]) [ x:collection6] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@4aa2b880[provider=null,keyStore=null,trustStore=null] [junit4] 2> 25280 INFO (TEST-TestLazyCores.testRace-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 25280 INFO (TEST-TestLazyCores.testRace-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 25347 INFO (TEST-TestLazyCores.testRace-seed#[459F38DC97B1C6D0]) [ x:collection6] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d3f2cec [junit4] 2> 25348 INFO (TEST-TestLazyCores.testRace-seed#[459F38DC97B1C6D0]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d3f2cec [junit4] 2> 25350 INFO (TEST-TestLazyCores.testRace-seed#[459F38DC97B1C6D0]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d3f2cec [junit4] 2> 25571 INFO (coreLoadExecutor-88-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0 [junit4] 2> 25582 INFO (coreLoadExecutor-88-thread-3) [ x:collection5] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0 [junit4] 2> 25628 INFO (coreLoadExecutor-88-thread-2) [ x:collection2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0 [junit4] 2> 25661 INFO (coreLoadExecutor-88-thread-2) [ x:collection2] o.a.s.s.IndexSchema [collection2] Schema name=tiny [junit4] 2> 25667 INFO (coreLoadExecutor-88-thread-2) [ x:collection2] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id [junit4] 2> 25667 INFO (coreLoadExecutor-88-thread-2) [ x:collection2] o.a.s.c.CoreContainer Creating SolrCore 'collection2' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-003/./collection2, trusted=true [junit4] 2> 25668 INFO (coreLoadExecutor-88-thread-2) [ x:collection2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection2' (registry 'solr.core.collection2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d3f2cec [junit4] 2> 25668 INFO (coreLoadExecutor-88-thread-2) [ x:collection2] o.a.s.c.SolrCore [[collection2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-003/collection2], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-003/./collection2/data/] [junit4] 2> 25672 INFO (coreLoadExecutor-88-thread-3) [ x:collection5] o.a.s.s.IndexSchema [collection5] Schema name=tiny [junit4] 2> 25673 INFO (coreLoadExecutor-88-thread-2) [ x:collection2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=36, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6948321609299785] [junit4] 2> 25678 INFO (coreLoadExecutor-88-thread-3) [ x:collection5] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id [junit4] 2> 25678 INFO (coreLoadExecutor-88-thread-3) [ x:collection5] o.a.s.c.CoreContainer Creating SolrCore 'collection5' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-003/./collection5, trusted=true [junit4] 2> 25678 INFO (coreLoadExecutor-88-thread-3) [ x:collection5] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection5' (registry 'solr.core.collection5') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d3f2cec [junit4] 2> 25679 INFO (coreLoadExecutor-88-thread-3) [ x:collection5] o.a.s.c.SolrCore [[collection5] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-003/collection5], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-003/./collection5/data/] [junit4] 2> 25705 INFO (coreLoadExecutor-88-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=tiny [junit4] 2> 25723 INFO (coreLoadExecutor-88-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id [junit4] 2> 25723 INFO (coreLoadExecutor-88-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-003/./collection1, trusted=true [junit4] 2> 25723 INFO (coreLoadExecutor-88-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d3f2cec [junit4] 2> 25724 INFO (coreLoadExecutor-88-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-003/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-003/./collection1/data/] [junit4] 2> 25758 INFO (coreLoadExecutor-88-thread-3) [ x:collection5] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=36, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6948321609299785] [junit4] 2> 25763 INFO (coreLoadExecutor-88-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=36, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6948321609299785] [junit4] 2> 26044 INFO (coreLoadExecutor-88-thread-2) [ x:collection2] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 26044 INFO (coreLoadExecutor-88-thread-2) [ x:collection2] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 26046 INFO (coreLoadExecutor-88-thread-2) [ x:collection2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=33, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 26047 INFO (coreLoadExecutor-88-thread-2) [ x:collection2] o.a.s.s.SolrIndexSearcher Opening [Searcher@3c2e27d4[collection2] main] [junit4] 2> 26048 INFO (coreLoadExecutor-88-thread-2) [ x:collection2] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-003/collection2/conf [junit4] 2> 26049 INFO (coreLoadExecutor-88-thread-2) [ x:collection2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 26057 INFO (searcherExecutor-89-thread-1-processing-x:collection2) [ x:collection2] o.a.s.c.SolrCore [collection2] Registered new searcher Searcher@3c2e27d4[collection2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 26116 INFO (coreLoadExecutor-88-thread-3) [ x:collection5] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 26116 INFO (coreLoadExecutor-88-thread-3) [ x:collection5] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 26119 INFO (coreLoadExecutor-88-thread-3) [ x:collection5] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=33, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 26120 INFO (coreLoadExecutor-88-thread-3) [ x:collection5] o.a.s.s.SolrIndexSearcher Opening [Searcher@562e3f82[collection5] main] [junit4] 2> 26124 INFO (coreLoadExecutor-88-thread-3) [ x:collection5] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_459F38DC97B1C6D0-001/tempDir-003/collection5/conf [junit4] 2> 26125 INFO (coreLoadExecutor-88-thread-3) [ x:collection5] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 26127 INFO (searcherExecutor-90-thread-1-processing-x:collection5) [ x:collection5] o.a.s.c.SolrCore [collection5] Registered new searcher Searcher@562e3f82[collection5] main{ExitableDirectoryReader(UninvertingDirectoryReader()) [...truncated too long message...] M com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll [junit4] 2> INFO: Starting to interrupt leaked threads: [junit4] 2> 1) Thread[id=376, name=Log4j2-TF-2-AsyncLoggerConfig--2, state=TIMED_WAITING, group=TGRP-TestLazyCores] [junit4] 2> AsyncLogger error handling event seq=0, value='null': [junit4] 2> java.lang.InterruptedException [junit4] 2> at java.base/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2056) [junit4] 2> at java.base/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2133) [junit4] 2> at com.lmax.disruptor.TimeoutBlockingWaitStrategy.waitFor(TimeoutBlockingWaitStrategy.java:38) [junit4] 2> at com.lmax.disruptor.ProcessingSequenceBarrier.waitFor(ProcessingSequenceBarrier.java:56) [junit4] 2> at com.lmax.disruptor.BatchEventProcessor.run(BatchEventProcessor.java:128) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:835) [junit4] 2> AsyncLogger error handling event seq=1, value='null': [junit4] 2> java.lang.InterruptedException [junit4] 2> at java.base/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2056) [junit4] 2> at java.base/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2133) [junit4] 2> at com.lmax.disruptor.TimeoutBlockingWaitStrategy.waitFor(TimeoutBlockingWaitStrategy.java:38) [junit4] 2> at com.lmax.disruptor.ProcessingSequenceBarrier.waitFor(ProcessingSequenceBarrier.java:56) [junit4] 2> at com.lmax.disruptor.BatchEventProcessor.run(BatchEventProcessor.java:128) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:835) [junit4] 2> AsyncLogger error handling event seq=2, value='null': [junit4] 2> java.lang.InterruptedException [junit4] 2> at java.base/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2056) [junit4] 2> at java.base/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2133) [junit4] 2> at com.lmax.disruptor.TimeoutBlockingWaitStrategy.waitFor(TimeoutBlockingWaitStrategy.java:38) [junit4] 2> at com.lmax.disruptor.ProcessingSequenceBarrier.waitFor(ProcessingSequenceBarrier.java:56) [junit4] 2> at com.lmax.disruptor.BatchEventProcessor.run(BatchEventProcessor.java:128) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:835) [junit4] 2> AsyncLogger error handling event seq=3, value='null': [junit4] 2> java.lang.InterruptedException [junit4] 2> at java.base/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2056) [junit4] 2> at java.base/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2133) [junit4] 2> at com.lmax.disruptor.TimeoutBlockingWaitStrategy.waitFor(TimeoutBlockingWaitStrategy.java:38) [junit4] 2> at com.lmax.disruptor.ProcessingSequenceBarrier.waitFor(ProcessingSequenceBarrier.java:56) [junit4] 2> at com.lmax.disruptor.BatchEventProcessor.run(BatchEventProcessor.java:128) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:835) [junit4] 2> AsyncLogger error handling event seq=4, value='null': [junit4] 2> java.lang.InterruptedException [junit4] 2> at java.base/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2056) [junit4] 2> at java.base/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2133) [junit4] 2> at com.lmax.disruptor.TimeoutBlockingWaitStrategy.waitFor(TimeoutBlockingWaitStrategy.java:38) [junit4] 2> at com.lmax.disruptor.ProcessingSequenceBarrier.waitFor(ProcessingSequenceBarrier.java:56) [junit4] 2> at com.lmax.disruptor.BatchEventProcessor.run(BatchEventProcessor.java:128) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:835) [junit4] 2> Feb 24, 2019 6:28:35 AM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll [junit4] 2> SEVERE: There are still zombie threads that couldn't be terminated: [junit4] 2> 1) Thread[id=376, name=Log4j2-TF-2-AsyncLoggerConfig--2, state=TIMED_WAITING, group=TGRP-TestLazyCores] [junit4] 2> at java.base@12-testing/jdk.internal.misc.Unsafe.park(Native Method) [junit4] 2> at java.base@12-testing/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:235) [junit4] 2> at java.base@12-testing/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2123) [junit4] 2> at app//com.lmax.disruptor.TimeoutBlockingWaitStrategy.waitFor(TimeoutBlockingWaitStrategy.java:38) [junit4] 2> at app//com.lmax.disruptor.ProcessingSequenceBarrier.waitFor(ProcessingSequenceBarrier.java:56) [junit4] 2> at app//com.lmax.disruptor.BatchEventProcessor.run(BatchEventProcessor.java:128) [junit4] 2> at java.base@12-testing/java.lang.Thread.run(Thread.java:835) [junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): {_root_=Lucene50(blocksize=128), v_t=BlockTreeOrds(blocksize=128), id=BlockTreeOrds(blocksize=128), v_s=FSTOrd50, v_f=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128)))}, docValues:{}, maxPointsInLeafNode=100, maxMBSortInHeap=5.75135668237395, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@410e72ba), locale=lag, timezone=Europe/Gibraltar [junit4] 2> NOTE: Linux 4.18.0-15-generic amd64/Aleksey Shipilev 12-testing (64-bit)/cpus=8,threads=3,free=424819912,total=518979584 [junit4] 2> NOTE: All tests run in this JVM: [TestLazyCores] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestLazyCores -Dtests.seed=459F38DC97B1C6D0 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=lag -Dtests.timezone=Europe/Gibraltar -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 0.00s J1 | TestLazyCores (suite) <<< [junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.core.TestLazyCores: [junit4] > 1) Thread[id=376, name=Log4j2-TF-2-AsyncLoggerConfig--2, state=TIMED_WAITING, group=TGRP-TestLazyCores] [junit4] > at java.base@12-testing/jdk.internal.misc.Unsafe.park(Native Method) [junit4] > at java.base@12-testing/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:235) [junit4] > at java.base@12-testing/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2123) [junit4] > at app//com.lmax.disruptor.TimeoutBlockingWaitStrategy.waitFor(TimeoutBlockingWaitStrategy.java:38) [junit4] > at app//com.lmax.disruptor.ProcessingSequenceBarrier.waitFor(ProcessingSequenceBarrier.java:56) [junit4] > at app//com.lmax.disruptor.BatchEventProcessor.run(BatchEventProcessor.java:128) [junit4] > at java.base@12-testing/java.lang.Thread.run(Thread.java:835) [junit4] > at __randomizedtesting.SeedInfo.seed([459F38DC97B1C6D0]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: [junit4] > 1) Thread[id=376, name=Log4j2-TF-2-AsyncLoggerConfig--2, state=TIMED_WAITING, group=TGRP-TestLazyCores] [junit4] > at java.base@12-testing/jdk.internal.misc.Unsafe.park(Native Method) [junit4] > at java.base@12-testing/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:235) [junit4] > at java.base@12-testing/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2123) [junit4] > at app//com.lmax.disruptor.TimeoutBlockingWaitStrategy.waitFor(TimeoutBlockingWaitStrategy.java:38) [junit4] > at app//com.lmax.disruptor.ProcessingSequenceBarrier.waitFor(ProcessingSequenceBarrier.java:56) [junit4] > at app//com.lmax.disruptor.BatchEventProcessor.run(BatchEventProcessor.java:128) [junit4] > at java.base@12-testing/java.lang.Thread.run(Thread.java:835) [junit4] > at __randomizedtesting.SeedInfo.seed([459F38DC97B1C6D0]:0) [junit4] Completed [6/844 (1!)] on J1 in 76.15s, 9 tests, 2 errors <<< FAILURES! [...truncated 45203 lines...] [repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-BadApples-8.x-Linux/21/consoleText [repro] Revision: 4aa0645ea6216f556ffd8c3ad6fcb276a0cc796d [repro] Repro line: ant test -Dtestcase=TestLazyCores -Dtests.seed=459F38DC97B1C6D0 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=lag -Dtests.timezone=Europe/Gibraltar -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [repro] ant clean [...truncated 6 lines...] [repro] Test suites by module: [repro] solr/core [repro] TestLazyCores [repro] ant compile-test [...truncated 3580 lines...] [repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestLazyCores" -Dtests.showOutput=onerror -Dtests.seed=459F38DC97B1C6D0 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=lag -Dtests.timezone=Europe/Gibraltar -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [...truncated 115 lines...] [repro] Failures: [repro] 0/5 failed: org.apache.solr.core.TestLazyCores [repro] Exiting with code 0 [...truncated 43 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org