Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2269/
2 tests failed. FAILED: junit.framework.TestSuite.org.apache.solr.core.OpenCloseCoreStressTest Error Message: 1 thread leaked from SUITE scope at org.apache.solr.core.OpenCloseCoreStressTest: 1) Thread[id=112, name=qtp329524253-112, state=TIMED_WAITING, group=TGRP-OpenCloseCoreStressTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.core.OpenCloseCoreStressTest: 1) Thread[id=112, name=qtp329524253-112, state=TIMED_WAITING, group=TGRP-OpenCloseCoreStressTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) at __randomizedtesting.SeedInfo.seed([6553BF52F8187A4B]:0) FAILED: junit.framework.TestSuite.org.apache.solr.core.OpenCloseCoreStressTest Error Message: There are still zombie threads that couldn't be terminated: 1) Thread[id=112, name=qtp329524253-112, state=TIMED_WAITING, group=TGRP-OpenCloseCoreStressTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: 1) Thread[id=112, name=qtp329524253-112, state=TIMED_WAITING, group=TGRP-OpenCloseCoreStressTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) at __randomizedtesting.SeedInfo.seed([6553BF52F8187A4B]:0) Build Log: [...truncated 11775 lines...] [junit4] Suite: org.apache.solr.core.OpenCloseCoreStressTest [junit4] 2> Creating dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.core.OpenCloseCoreStressTest_6553BF52F8187A4B-001/init-core-data-001 [junit4] 2> 155473 INFO (SUITE-OpenCloseCoreStressTest-seed#[6553BF52F8187A4B]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 155475 INFO (SUITE-OpenCloseCoreStressTest-seed#[6553BF52F8187A4B]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 155475 INFO (SUITE-OpenCloseCoreStressTest-seed#[6553BF52F8187A4B]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] IGNOR/A 0.00s J0 | OpenCloseCoreStressTest.test15Seconds [junit4] > Assumption #1: 'nightly' test group is disabled (@Nightly()) [junit4] IGNOR/A 0.00s J0 | OpenCloseCoreStressTest.test10Minutes [junit4] > Assumption #1: 'nightly' test group is disabled (@Nightly()) [junit4] IGNOR/A 0.00s J0 | OpenCloseCoreStressTest.test1Hour [junit4] > Assumption #1: 'weekly' test group is disabled (@Weekly()) [junit4] 2> 155511 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test5Seconds [junit4] 2> 156107 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T12:27:37-09:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 156172 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 156172 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 156175 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 156213 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4d8a1002{/solr,null,AVAILABLE} [junit4] 2> 156241 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@61ed29e0{HTTP/1.1,[http/1.1]}{127.0.0.1:41974} [junit4] 2> 156267 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.e.j.s.Server Started @166660ms [junit4] 2> 156267 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=41974} [junit4] 2> 156301 ERROR (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 156301 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 156301 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 156301 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 156302 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-22T17:11:39.212Z [junit4] 2> 156302 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 156304 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.core.OpenCloseCoreStressTest_6553BF52F8187A4B-001/tempDir-001/solr.xml [junit4] 2> 156353 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@4d2b2c4c, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 156809 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d2b2c4c [junit4] 2> 156841 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d2b2c4c [junit4] 2> 156842 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d2b2c4c [junit4] 2> 156897 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.a.s.c.CorePropertiesLocator Found 5 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.core.OpenCloseCoreStressTest_6553BF52F8187A4B-001/tempDir-001 [junit4] 2> 156897 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [00000_core, 00002_core, 00004_core, 00003_core, 00001_core] [junit4] 2> 156897 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 157442 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.a.s.c.OpenCloseCoreStressTest Starting indexing and querying [junit4] 2> 157442 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.a.s.c.OpenCloseCoreStressTest [junit4] 2> [junit4] 2> [junit4] 2> [junit4] 2> [junit4] 2> Starting a 5 second cycle, seconds left: 5. Seconds run so far: 0. [junit4] 2> 157443 INFO (Thread-16) [ ] o.a.s.c.OneIndexer Starting indexing thread: 96 [junit4] 2> 157446 INFO (Thread-17) [ ] o.a.s.c.OneIndexer Starting indexing thread: 97 [junit4] 2> 157466 INFO (Thread-18) [ ] o.a.s.c.OneIndexer Starting indexing thread: 98 [junit4] 2> 157466 INFO (Thread-19) [ ] o.a.s.c.OneIndexer Starting indexing thread: 99 [junit4] 2> 157467 INFO (Thread-20) [ ] o.a.s.c.OneIndexer Starting indexing thread: 100 [junit4] 2> 157467 INFO (Thread-22) [ ] o.a.s.c.OneQuery Starting query thread: 102 [junit4] 2> 157468 INFO (Thread-23) [ ] o.a.s.c.OneQuery Starting query thread: 103 [junit4] 2> 157482 INFO (Thread-24) [ ] o.a.s.c.OneQuery Starting query thread: 104 [junit4] 2> 157559 INFO (Thread-25) [ ] o.a.s.c.OneQuery Starting query thread: 105 [junit4] 2> 157467 INFO (Thread-21) [ ] o.a.s.c.OneQuery Starting query thread: 101 [junit4] 2> 159378 INFO (qtp329524253-73) [ x:00003_core] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 159414 INFO (qtp329524253-73) [ x:00003_core] o.a.s.s.IndexSchema [00003_core] Schema name=tiny [junit4] 2> 159430 INFO (qtp329524253-79) [ x:00001_core] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 159438 INFO (qtp329524253-109) [ x:00000_core] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 159466 INFO (qtp329524253-111) [ x:00004_core] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 159470 INFO (qtp329524253-73) [ x:00003_core] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id [junit4] 2> 159470 INFO (qtp329524253-73) [ x:00003_core] o.a.s.c.CoreContainer Creating SolrCore '00003_core' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.core.OpenCloseCoreStressTest_6553BF52F8187A4B-001/tempDir-001/00003_core, trusted=true [junit4] 2> 159583 INFO (qtp329524253-73) [ x:00003_core] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.00003_core' (registry 'solr.core.00003_core') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d2b2c4c [junit4] 2> 159583 INFO (qtp329524253-73) [ x:00003_core] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 159583 INFO (qtp329524253-73) [ x:00003_core] o.a.s.c.SolrCore [[00003_core] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.core.OpenCloseCoreStressTest_6553BF52F8187A4B-001/tempDir-001/00003_core], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.core.OpenCloseCoreStressTest_6553BF52F8187A4B-001/tempDir-001/00003_core/data/] [junit4] 2> 159646 INFO (qtp329524253-112) [ x:00002_core] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 159837 INFO (qtp329524253-109) [ x:00000_core] o.a.s.s.IndexSchema [00000_core] Schema name=tiny [junit4] 2> 159839 INFO (qtp329524253-109) [ x:00000_core] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id [junit4] 2> 159839 INFO (qtp329524253-109) [ x:00000_core] o.a.s.c.CoreContainer Creating SolrCore '00000_core' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.core.OpenCloseCoreStressTest_6553BF52F8187A4B-001/tempDir-001/00000_core, trusted=true [junit4] 2> 159840 INFO (qtp329524253-109) [ x:00000_core] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.00000_core' (registry 'solr.core.00000_core') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d2b2c4c [junit4] 2> 159840 INFO (qtp329524253-109) [ x:00000_core] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 159840 INFO (qtp329524253-109) [ x:00000_core] o.a.s.c.SolrCore [[00000_core] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.core.OpenCloseCoreStressTest_6553BF52F8187A4B-001/tempDir-001/00000_core], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.core.OpenCloseCoreStressTest_6553BF52F8187A4B-001/tempDir-001/00000_core/data/] [junit4] 2> 159864 INFO (qtp329524253-111) [ x:00004_core] o.a.s.s.IndexSchema [00004_core] Schema name=tiny [junit4] 2> 159871 INFO (qtp329524253-79) [ x:00001_core] o.a.s.s.IndexSchema [00001_core] Schema name=tiny [junit4] 2> 159890 INFO (qtp329524253-79) [ x:00001_core] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id [junit4] 2> 159890 INFO (qtp329524253-79) [ x:00001_core] o.a.s.c.CoreContainer Creating SolrCore '00001_core' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.core.OpenCloseCoreStressTest_6553BF52F8187A4B-001/tempDir-001/00001_core, trusted=true [junit4] 2> 159890 INFO (qtp329524253-79) [ x:00001_core] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.00001_core' (registry 'solr.core.00001_core') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d2b2c4c [junit4] 2> 159891 INFO (qtp329524253-79) [ x:00001_core] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 159891 INFO (qtp329524253-79) [ x:00001_core] o.a.s.c.SolrCore [[00001_core] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.core.OpenCloseCoreStressTest_6553BF52F8187A4B-001/tempDir-001/00001_core], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.core.OpenCloseCoreStressTest_6553BF52F8187A4B-001/tempDir-001/00001_core/data/] [junit4] 2> 159991 INFO (qtp329524253-111) [ x:00004_core] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id [junit4] 2> 159992 INFO (qtp329524253-111) [ x:00004_core] o.a.s.c.CoreContainer Creating SolrCore '00004_core' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.core.OpenCloseCoreStressTest_6553BF52F8187A4B-001/tempDir-001/00004_core, trusted=true [junit4] 2> 159992 INFO (qtp329524253-111) [ x:00004_core] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.00004_core' (registry 'solr.core.00004_core') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d2b2c4c [junit4] 2> 159992 INFO (qtp329524253-111) [ x:00004_core] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 159992 INFO (qtp329524253-111) [ x:00004_core] o.a.s.c.SolrCore [[00004_core] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.core.OpenCloseCoreStressTest_6553BF52F8187A4B-001/tempDir-001/00004_core], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.core.OpenCloseCoreStressTest_6553BF52F8187A4B-001/tempDir-001/00004_core/data/] [junit4] 2> 159996 INFO (qtp329524253-79) [ x:00001_core] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=42, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 160003 INFO (qtp329524253-73) [ x:00003_core] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=42, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 160037 INFO (qtp329524253-112) [ x:00002_core] o.a.s.s.IndexSchema [00002_core] Schema name=tiny [junit4] 2> 160053 INFO (qtp329524253-111) [ x:00004_core] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=42, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 160088 INFO (qtp329524253-112) [ x:00002_core] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id [junit4] 2> 160088 INFO (qtp329524253-112) [ x:00002_core] o.a.s.c.CoreContainer Creating SolrCore '00002_core' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.core.OpenCloseCoreStressTest_6553BF52F8187A4B-001/tempDir-001/00002_core, trusted=true [junit4] 2> 160088 INFO (qtp329524253-112) [ x:00002_core] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.00002_core' (registry 'solr.core.00002_core') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d2b2c4c [junit4] 2> 160088 INFO (qtp329524253-112) [ x:00002_core] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 160089 INFO (qtp329524253-112) [ x:00002_core] o.a.s.c.SolrCore [[00002_core] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.core.OpenCloseCoreStressTest_6553BF52F8187A4B-001/tempDir-001/00002_core], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.core.OpenCloseCoreStressTest_6553BF52F8187A4B-001/tempDir-001/00002_core/data/] [junit4] 2> 160197 INFO (qtp329524253-109) [ x:00000_core] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=42, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 160300 INFO (qtp329524253-112) [ x:00002_core] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=42, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 160980 INFO (qtp329524253-109) [ x:00000_core] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 160981 INFO (qtp329524253-109) [ x:00000_core] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 160982 INFO (qtp329524253-109) [ x:00000_core] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=7.5849609375, floorSegmentMB=2.185546875, forceMergeDeletesPctAllowed=16.904402272709163, segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4576399215342377 [junit4] 2> 160983 INFO (qtp329524253-109) [ x:00000_core] o.a.s.s.SolrIndexSearcher Opening [Searcher@6a6eb008[00000_core] main] [junit4] 2> 161056 INFO (qtp329524253-109) [ x:00000_core] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.core.OpenCloseCoreStressTest_6553BF52F8187A4B-001/tempDir-001/00000_core/conf [junit4] 2> 161057 INFO (qtp329524253-109) [ x:00000_core] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 161257 INFO (searcherExecutor-59-thread-1-processing-x:00000_core) [ x:00000_core] o.a.s.c.SolrCore [00000_core] Registered new searcher Searcher@6a6eb008[00000_core] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 161271 INFO (qtp329524253-111) [ x:00004_core] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 161271 INFO (qtp329524253-111) [ x:00004_core] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 161262 INFO (qtp329524253-109) [ x:00000_core] o.a.s.c.S.Request [00000_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=0 status=0 QTime=2012 [junit4] 2> 161352 INFO (qtp329524253-73) [ x:00003_core] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 161352 INFO (qtp329524253-73) [ x:00003_core] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 161353 INFO (qtp329524253-73) [ x:00003_core] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=7.5849609375, floorSegmentMB=2.185546875, forceMergeDeletesPctAllowed=16.904402272709163, segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4576399215342377 [junit4] 2> 161354 INFO (qtp329524253-73) [ x:00003_core] o.a.s.s.SolrIndexSearcher Opening [Searcher@71664cb7[00003_core] main] [junit4] 2> 161354 INFO (qtp329524253-73) [ x:00003_core] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.core.OpenCloseCoreStressTest_6553BF52F8187A4B-001/tempDir-001/00003_core/conf [junit4] 2> 161380 INFO (qtp329524253-111) [ x:00004_core] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=7.5849609375, floorSegmentMB=2.185546875, forceMergeDeletesPctAllowed=16.904402272709163, segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4576399215342377 [junit4] 2> 161491 INFO (qtp329524253-111) [ x:00004_core] o.a.s.s.SolrIndexSearcher Opening [Searcher@71ab9829[00004_core] main] [junit4] 2> 161492 INFO (qtp329524253-111) [ x:00004_core] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.core.OpenCloseCoreStressTest_6553BF52F8187A4B-001/tempDir-001/00004_core/conf [junit4] 2> 161493 INFO (qtp329524253-111) [ x:00004_core] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 161498 INFO (qtp329524253-73) [ x:00003_core] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 161506 INFO (qtp329524253-79) [ x:00001_core] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 161506 INFO (qtp329524253-79) [ x:00001_core] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 161509 INFO (qtp329524253-79) [ x:00001_core] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=7.5849609375, floorSegmentMB=2.185546875, forceMergeDeletesPctAllowed=16.904402272709163, segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4576399215342377 [junit4] 2> 161509 INFO (qtp329524253-79) [ x:00001_core] o.a.s.s.SolrIndexSearcher Opening [Searcher@3aad7cc2[00001_core] main] [junit4] 2> 161526 INFO (qtp329524253-79) [ x:00001_core] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.core.OpenCloseCoreStressTest_6553BF52F8187A4B-001/tempDir-001/00001_core/conf [junit4] 2> 161529 INFO (qtp329524253-112) [ x:00002_core] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 161529 INFO (qtp329524253-112) [ x:00002_core] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 161624 INFO (qtp329524253-79) [ x:00001_core] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 161729 INFO (qtp329524253-112) [ x:00002_core] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=7.5849609375, floorSegmentMB=2.185546875, forceMergeDeletesPctAllowed=16.904402272709163, segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4576399215342377 [junit4] 2> 161730 INFO (qtp329524253-112) [ x:00002_core] o.a.s.s.SolrIndexSearcher Opening [Searcher@50acc869[00002_core] main] [junit4] 2> 161730 INFO (qtp329524253-112) [ x:00002_core] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.core.OpenCloseCoreStressTest_6553BF52F8187A4B-001/tempDir-001/00002_core/conf [junit4] 2> 161731 INFO (qtp329524253-112) [ x:00002_core] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 161891 INFO (searcherExecutor-58-thread-1-processing-x:00003_core) [ x:00003_core] o.a.s.c.SolrCore [00003_core] Registered new searcher Searcher@71664cb7[00003_core] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 161892 INFO (searcherExecutor-61-thread-1-processing-x:00004_core) [ x:00004_core] o.a.s.c.SolrCore [00004_core] Registered new searcher Searcher@71ab9829[00004_core] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 161893 INFO (searcherExecutor-60-thread-1-processing-x:00001_core) [ x:00001_core] o.a.s.c.SolrCore [00001_core] Registered new searcher Searcher@3aad7cc2[00001_core] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 161894 INFO (qtp329524253-76) [ x:00001_core] o.a.s.c.S.Request [00001_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=0 status=0 QTime=2632 [junit4] 2> 161903 INFO (qtp329524253-73) [ x:00003_core] o.a.s.c.S.Request [00003_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=0 status=0 QTime=2641 [junit4] 2> 161904 INFO (qtp329524253-78) [ x:00003_core] o.a.s.c.S.Request [00003_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=0 status=0 QTime=2641 [junit4] 2> 161911 INFO (searcherExecutor-62-thread-1-processing-x:00002_core) [ x:00002_core] o.a.s.c.SolrCore [00002_core] Registered new searcher Searcher@50acc869[00002_core] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 161912 INFO (qtp329524253-112) [ x:00002_core] o.a.s.c.S.Request [00002_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=0 status=0 QTime=2650 [junit4] 2> 161986 INFO (qtp329524253-79) [ x:00001_core] o.a.s.u.p.LogUpdateProcessorFactory [00001_core] webapp=/solr path=/update params={wt=javabin&version=2}{add=[id4]} 0 2724 [junit4] 2> 162057 INFO (qtp329524253-111) [ x:00004_core] o.a.s.u.p.LogUpdateProcessorFactory [00004_core] webapp=/solr path=/update params={wt=javabin&version=2}{add=[id2]} 0 2795 [junit4] 2> 162132 INFO (qtp329524253-112) [ x:00001_core] o.a.s.c.S.Request [00001_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 162292 INFO (qtp329524253-107) [ x:00003_core] o.a.s.c.S.Request [00003_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 162293 INFO (qtp329524253-112) [ x:00003_core] o.a.s.c.S.Request [00003_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 162295 INFO (qtp329524253-111) [ x:00002_core] o.a.s.c.S.Request [00002_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 162366 INFO (qtp329524253-117) [ x:00003_core] o.a.s.u.p.LogUpdateProcessorFactory [00003_core] webapp=/solr path=/update params={wt=javabin&version=2}{add=[id5]} 0 3103 [junit4] 2> 162368 INFO (qtp329524253-78) [ x:00004_core] o.a.s.c.S.Request [00004_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=0 status=0 QTime=59 [junit4] 2> 162381 INFO (qtp329524253-111) [ x:00003_core] o.a.s.c.S.Request [00003_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=0 status=0 QTime=15 [junit4] 2> 162403 INFO (qtp329524253-117) [ x:00002_core] o.a.s.c.S.Request [00002_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 162417 INFO (qtp329524253-111) [ x:00003_core] o.a.s.c.S.Request [00003_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=0 status=0 QTime=13 [junit4] 2> 162418 INFO (qtp329524253-74) [ x:00001_core] o.a.s.u.p.LogUpdateProcessorFactory [00001_core] webapp=/solr path=/update params={wt=javabin&version=2}{add=[id3]} 0 3155 [junit4] 2> 162453 INFO (qtp329524253-112) [ x:00002_core] o.a.s.u.p.LogUpdateProcessorFactory [00002_core] webapp=/solr path=/update params={wt=javabin&version=2}{add=[id8]} 0 85 [junit4] 2> 162565 INFO (Thread-18) [ ] o.a.s.c.OneIndexer Leaving indexing thread 98 [junit4] 2> 162566 INFO (qtp329524253-106) [ x:00003_core] o.a.s.u.p.LogUpdateProcessorFactory [00003_core] webapp=/solr path=/update params={wt=javabin&version=2}{add=[id1]} 0 3304 [junit4] 2> 162575 INFO (Thread-16) [ ] o.a.s.c.OneIndexer Leaving indexing thread 96 [junit4] 2> 162583 INFO (qtp329524253-80) [ x:00003_core] o.a.s.u.p.LogUpdateProcessorFactory [00003_core] webapp=/solr path=/update params={wt=javabin&version=2}{add=[id6]} 0 562 [junit4] 2> 162584 INFO (Thread-19) [ ] o.a.s.c.OneIndexer Leaving indexing thread 99 [junit4] 2> 162592 INFO (qtp329524253-107) [ x:00004_core] o.a.s.u.p.LogUpdateProcessorFactory [00004_core] webapp=/solr path=/update params={wt=javabin&version=2}{add=[id7]} 0 227 [junit4] 2> 162593 INFO (Thread-17) [ ] o.a.s.c.OneIndexer Leaving indexing thread 97 [junit4] 2> 162608 INFO (qtp329524253-109) [ x:00002_core] o.a.s.c.S.Request [00002_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=0 status=0 QTime=154 [junit4] 2> 162609 INFO (qtp329524253-74) [ x:00001_core] o.a.s.c.S.Request [00001_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 162610 INFO (qtp329524253-117) [ x:00004_core] o.a.s.c.S.Request [00004_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 162611 INFO (qtp329524253-112) [ x:00001_core] o.a.s.c.S.Request [00001_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 162611 INFO (Thread-20) [ ] o.a.s.c.OneIndexer Leaving indexing thread 100 [junit4] 2> 162734 INFO (qtp329524253-74) [ x:00004_core] o.a.s.c.S.Request [00004_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 162774 INFO (qtp329524253-117) [ x:00003_core] o.a.s.c.S.Request [00003_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 162775 INFO (qtp329524253-74) [ x:00003_core] o.a.s.c.S.Request [00003_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 162775 INFO (qtp329524253-112) [ x:00003_core] o.a.s.c.S.Request [00003_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 162777 INFO (qtp329524253-117) [ x:00002_core] o.a.s.c.S.Request [00002_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 162885 INFO (qtp329524253-111) [ x:00002_core] o.a.s.c.S.Request [00002_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 162885 INFO (qtp329524253-74) [ x:00003_core] o.a.s.c.S.Request [00003_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 162886 INFO (qtp329524253-117) [ x:00002_core] o.a.s.c.S.Request [00002_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 162886 INFO (qtp329524253-78) [ x:00002_core] o.a.s.c.S.Request [00002_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 162886 INFO (Thread-21) [ ] o.a.s.c.OneQuery Leaving query thread: 101 [junit4] 2> 162887 INFO (Thread-22) [ ] o.a.s.c.OneQuery Leaving query thread: 102 [junit4] 2> 162885 INFO (qtp329524253-112) [ x:00002_core] o.a.s.c.S.Request [00002_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 163013 INFO (Thread-24) [ ] o.a.s.c.OneQuery Leaving query thread: 104 [junit4] 2> 162973 INFO (Thread-25) [ ] o.a.s.c.OneQuery Leaving query thread: 105 [junit4] 2> 163025 INFO (Thread-23) [ ] o.a.s.c.OneQuery Leaving query thread: 103 [junit4] 2> 163061 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.a.s.c.OpenCloseCoreStressTest Checking if indexes have all the documents they should... [junit4] 2> 163156 INFO (qtp329524253-111) [ x:00000_core] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 163156 INFO (qtp329524253-111) [ x:00000_core] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 163158 INFO (qtp329524253-111) [ x:00000_core] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 163158 INFO (qtp329524253-111) [ x:00000_core] o.a.s.u.p.LogUpdateProcessorFactory [00000_core] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 2 [junit4] 2> 163171 INFO (qtp329524253-111) [ x:00000_core] o.a.s.c.S.Request [00000_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 163187 INFO (qtp329524253-111) [ x:00001_core] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 163187 INFO (qtp329524253-111) [ x:00001_core] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@486e51ed commitCommandVersion:0 [junit4] 2> 163319 INFO (qtp329524253-111) [ x:00001_core] o.a.s.s.SolrIndexSearcher Opening [Searcher@20ce39e4[00001_core] main] [junit4] 2> 163320 INFO (qtp329524253-111) [ x:00001_core] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 163378 INFO (searcherExecutor-60-thread-1-processing-x:00001_core) [ x:00001_core] o.a.s.c.SolrCore [00001_core] Registered new searcher Searcher@20ce39e4[00001_core] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c2)))} [junit4] 2> 163378 INFO (qtp329524253-111) [ x:00001_core] o.a.s.u.p.LogUpdateProcessorFactory [00001_core] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 190 [junit4] 2> 163380 INFO (qtp329524253-112) [ x:00001_core] o.a.s.c.S.Request [00001_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=2 status=0 QTime=0 [junit4] 2> 163382 INFO (qtp329524253-111) [ x:00002_core] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 163406 INFO (qtp329524253-111) [ x:00002_core] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@755d4a2e commitCommandVersion:0 [junit4] 2> 163456 INFO (qtp329524253-111) [ x:00002_core] o.a.s.s.SolrIndexSearcher Opening [Searcher@32da5e5b[00002_core] main] [junit4] 2> 163456 INFO (qtp329524253-111) [ x:00002_core] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 163476 INFO (searcherExecutor-62-thread-1-processing-x:00002_core) [ x:00002_core] o.a.s.c.SolrCore [00002_core] Registered new searcher Searcher@32da5e5b[00002_core] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c1)))} [junit4] 2> 163476 INFO (qtp329524253-111) [ x:00002_core] o.a.s.u.p.LogUpdateProcessorFactory [00002_core] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 93 [junit4] 2> 163478 INFO (qtp329524253-112) [ x:00002_core] o.a.s.c.S.Request [00002_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=1 status=0 QTime=0 [junit4] 2> 163492 INFO (qtp329524253-111) [ x:00003_core] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 163492 INFO (qtp329524253-111) [ x:00003_core] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@98c02bd commitCommandVersion:0 [junit4] 2> 163682 INFO (qtp329524253-111) [ x:00003_core] o.a.s.s.SolrIndexSearcher Opening [Searcher@2c1e5e78[00003_core] main] [junit4] 2> 163683 INFO (qtp329524253-111) [ x:00003_core] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 163698 INFO (searcherExecutor-58-thread-1-processing-x:00003_core) [ x:00003_core] o.a.s.c.SolrCore [00003_core] Registered new searcher Searcher@2c1e5e78[00003_core] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c1) Uninverting(_1(8.0.0):c1) Uninverting(_2(8.0.0):c1)))} [junit4] 2> 163698 INFO (qtp329524253-111) [ x:00003_core] o.a.s.u.p.LogUpdateProcessorFactory [00003_core] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 206 [junit4] 2> 163700 INFO (qtp329524253-112) [ x:00003_core] o.a.s.c.S.Request [00003_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=3 status=0 QTime=0 [junit4] 2> 163735 INFO (qtp329524253-111) [ x:00004_core] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 163735 INFO (qtp329524253-111) [ x:00004_core] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4b1ea822 commitCommandVersion:0 [junit4] 2> 163737 INFO (qtp329524253-111) [ x:00004_core] o.a.s.s.SolrIndexSearcher Opening [Searcher@24803f0f[00004_core] main] [junit4] 2> 163773 INFO (searcherExecutor-61-thread-1-processing-x:00004_core) [ x:00004_core] o.a.s.c.SolrCore [00004_core] Registered new searcher Searcher@24803f0f[00004_core] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c2)))} [junit4] 2> 163774 INFO (qtp329524253-111) [ x:00004_core] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 163774 INFO (qtp329524253-111) [ x:00004_core] o.a.s.u.p.LogUpdateProcessorFactory [00004_core] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 39 [junit4] 2> 163808 INFO (qtp329524253-112) [ x:00004_core] o.a.s.c.S.Request [00004_core] webapp=/solr path=/select params={q=*:*&qt=/select&wt=javabin&version=2} hits=2 status=0 QTime=0 [junit4] 2> 163809 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.a.s.c.OpenCloseCoreStressTest [junit4] 2> [junit4] 2> Docs indexed (cumulative, all cycles): 8, total docs: 8: Cycle stats: updates: 8: qtimes: 15,958 [junit4] 2> 163888 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@61ed29e0{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 163888 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1462886606 [junit4] 2> 163940 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 163941 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1b49114a: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@2f58d591 [junit4] 2> 164017 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 164017 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7d722e57: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@3eee565c [junit4] 2> 164145 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 164145 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7f9ee04c: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@5806c58e [junit4] 2> 164261 INFO (coreCloseExecutor-78-thread-1) [ x:00000_core] o.a.s.c.SolrCore [00000_core] CLOSING SolrCore org.apache.solr.core.SolrCore@3f1a9152 [junit4] 2> 164261 INFO (coreCloseExecutor-78-thread-1) [ x:00000_core] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.00000_core, tag=1058705746 [junit4] 2> 164261 INFO (coreCloseExecutor-78-thread-1) [ x:00000_core] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@15a50295: rootName = null, domain = solr.core.00000_core, service url = null, agent id = null] for registry solr.core.00000_core / com.codahale.metrics.MetricRegistry@9608ef5 [junit4] 2> 164261 INFO (coreCloseExecutor-78-thread-2) [ x:00001_core] o.a.s.c.SolrCore [00001_core] CLOSING SolrCore org.apache.solr.core.SolrCore@367bab83 [junit4] 2> 164261 INFO (coreCloseExecutor-78-thread-3) [ x:00002_core] o.a.s.c.SolrCore [00002_core] CLOSING SolrCore org.apache.solr.core.SolrCore@5183445a [junit4] 2> 164262 INFO (coreCloseExecutor-78-thread-4) [ x:00003_core] o.a.s.c.SolrCore [00003_core] CLOSING SolrCore org.apache.solr.core.SolrCore@2647a68e [junit4] 2> 164277 INFO (coreCloseExecutor-78-thread-5) [ x:00004_core] o.a.s.c.SolrCore [00004_core] CLOSING SolrCore org.apache.solr.core.SolrCore@389c44ad [junit4] 2> 164743 INFO (coreCloseExecutor-78-thread-5) [ x:00004_core] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.00004_core, tag=949765293 [junit4] 2> 164743 INFO (coreCloseExecutor-78-thread-5) [ x:00004_core] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@57c9ef51: rootName = null, domain = solr.core.00004_core, service url = null, agent id = null] for registry solr.core.00004_core / com.codahale.metrics.MetricRegistry@4584bf21 [junit4] 2> 164954 INFO (coreCloseExecutor-78-thread-4) [ x:00003_core] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.00003_core, tag=642229902 [junit4] 2> 164974 INFO (coreCloseExecutor-78-thread-4) [ x:00003_core] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6aea10dc: rootName = null, domain = solr.core.00003_core, service url = null, agent id = null] for registry solr.core.00003_core / com.codahale.metrics.MetricRegistry@11cb8b0f [junit4] 2> 165112 INFO (coreCloseExecutor-78-thread-3) [ x:00002_core] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.00002_core, tag=1367557210 [junit4] 2> 165112 INFO (coreCloseExecutor-78-thread-3) [ x:00002_core] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@38a22991: rootName = null, domain = solr.core.00002_core, service url = null, agent id = null] for registry solr.core.00002_core / com.codahale.metrics.MetricRegistry@1083bfbb [junit4] 2> 165226 INFO (coreCloseExecutor-78-thread-2) [ x:00001_core] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.00001_core, tag=914074499 [junit4] 2> 165226 INFO (coreCloseExecutor-78-thread-2) [ x:00001_core] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@bc13b63: rootName = null, domain = solr.core.00001_core, service url = null, agent id = null] for registry solr.core.00001_core / com.codahale.metrics.MetricRegistry@1700139c [junit4] 2> 165574 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4d8a1002{/solr,null,UNAVAILABLE} [junit4] 2> 165574 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 195637 WARN (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.e.j.u.t.QueuedThreadPool QueuedThreadPool@qtp329524253{STOPPING,8<=18<=10000,i=0,q=1} Couldn't stop Thread[qtp329524253-112,5,TGRP-OpenCloseCoreStressTest] [junit4] 2> 195640 INFO (TEST-OpenCloseCoreStressTest.test5Seconds-seed#[6553BF52F8187A4B]) [ ] o.a.s.SolrTestCaseJ4 ###Ending test5Seconds [junit4] 2> 195641 INFO (SUITE-OpenCloseCoreStressTest-seed#[6553BF52F8187A4B]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released [junit4] 2> Jan 22, 2018 5:12:18 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> Jan 22, 2018 5:12:38 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> SEVERE: 1 thread leaked from SUITE scope at org.apache.solr.core.OpenCloseCoreStressTest: [junit4] 2> 1) Thread[id=112, name=qtp329524253-112, state=TIMED_WAITING, group=TGRP-OpenCloseCoreStressTest] [junit4] 2> at sun.misc.Unsafe.park(Native Method) [junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) [junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) [junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> Jan 22, 2018 5:12:38 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll [junit4] 2> INFO: Starting to interrupt leaked threads: [junit4] 2> 1) Thread[id=112, name=qtp329524253-112, state=TIMED_WAITING, group=TGRP-OpenCloseCoreStressTest] [junit4] 2> Jan 22, 2018 5:12:41 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll [junit4] 2> SEVERE: There are still zombie threads that couldn't be terminated: [junit4] 2> 1) Thread[id=112, name=qtp329524253-112, state=TIMED_WAITING, group=TGRP-OpenCloseCoreStressTest] [junit4] 2> at sun.misc.Unsafe.park(Native Method) [junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) [junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) [junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {id=BlockTreeOrds(blocksize=128), text=PostingsFormat(name=Asserting)}, docValues:{}, maxPointsInLeafNode=2002, maxMBSortInHeap=5.918005571541725, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@323b4751), locale=pl-PL, timezone=America/Nome [junit4] 2> NOTE: Linux 4.4.0-104-generic amd64/Oracle Corporation 1.8.0_152 (64-bit)/cpus=4,threads=2,free=211440168,total=298319872 [junit4] 2> NOTE: All tests run in this JVM: [SecurityConfHandlerTest, TestCollationField, UpdateParamsTest, DebugComponentTest, ConcurrentDeleteAndCreateCollectionTest, TestSort, TestSizeLimitedDistributedMap, OpenCloseCoreStressTest] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=OpenCloseCoreStressTest -Dtests.seed=6553BF52F8187A4B -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=pl-PL -Dtests.timezone=America/Nome -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] ERROR 0.00s J0 | OpenCloseCoreStressTest (suite) <<< [junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.core.OpenCloseCoreStressTest: [junit4] > 1) Thread[id=112, name=qtp329524253-112, state=TIMED_WAITING, group=TGRP-OpenCloseCoreStressTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) [junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) [junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) [junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) [junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] > at __randomizedtesting.SeedInfo.seed([6553BF52F8187A4B]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: [junit4] > 1) Thread[id=112, name=qtp329524253-112, state=TIMED_WAITING, group=TGRP-OpenCloseCoreStressTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) [junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) [junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) [junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) [junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] > at __randomizedtesting.SeedInfo.seed([6553BF52F8187A4B]:0) [junit4] Completed [38/768 (1!)] on J0 in 63.40s, 4 tests, 2 errors, 3 skipped <<< FAILURES! [...truncated 50479 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org