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: [email protected]
For additional commands, e-mail: [email protected]
