Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/767/
4 tests failed.
FAILED:
junit.framework.TestSuite.org.apache.solr.cloud.FullSolrCloudDistribCmdsTest
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([8856DE01B6BF11DD]:0)
FAILED: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.test
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([8856DE01B6BF11DD]:0)
FAILED: org.apache.solr.search.TestReloadDeadlock.testReloadDeadlock
Error Message:
Captured an uncaught exception in thread: Thread[id=24109, name=WRITER4,
state=RUNNABLE, group=TGRP-TestReloadDeadlock]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=24109, name=WRITER4, state=RUNNABLE,
group=TGRP-TestReloadDeadlock]
at
__randomizedtesting.SeedInfo.seed([8856DE01B6BF11DD:B5D77AA0E2F4F29F]:0)
Caused by: java.lang.RuntimeException: org.apache.solr.common.SolrException:
Error opening new searcher
at __randomizedtesting.SeedInfo.seed([8856DE01B6BF11DD]:0)
at
org.apache.solr.search.TestReloadDeadlock$1.run(TestReloadDeadlock.java:166)
Caused by: org.apache.solr.common.SolrException: Error opening new searcher
at org.apache.solr.core.SolrCore.openNewSearcher(SolrCore.java:1673)
at org.apache.solr.core.SolrCore.getRealtimeSearcher(SolrCore.java:1530)
at
org.apache.solr.update.VersionInfo.getVersionFromIndex(VersionInfo.java:202)
at org.apache.solr.update.UpdateLog.lookupVersion(UpdateLog.java:783)
at
org.apache.solr.update.VersionInfo.lookupVersion(VersionInfo.java:195)
at
org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:1088)
at
org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:705)
at
org.apache.solr.update.processor.LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:104)
at
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.handleAdds(JsonLoader.java:470)
at
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.processUpdate(JsonLoader.java:134)
at
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.load(JsonLoader.java:113)
at org.apache.solr.handler.loader.JsonLoader.load(JsonLoader.java:76)
at
org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:98)
at
org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:74)
at
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:151)
at org.apache.solr.core.SolrCore.execute(SolrCore.java:2079)
at
org.apache.solr.servlet.DirectSolrConnection.request(DirectSolrConnection.java:131)
at org.apache.solr.SolrTestCaseJ4.updateJ(SolrTestCaseJ4.java:1104)
at
org.apache.solr.SolrTestCaseJ4.addAndGetVersion(SolrTestCaseJ4.java:1250)
at
org.apache.solr.search.TestReloadDeadlock.addDoc(TestReloadDeadlock.java:200)
at
org.apache.solr.search.TestReloadDeadlock.access$100(TestReloadDeadlock.java:46)
at
org.apache.solr.search.TestReloadDeadlock$1.run(TestReloadDeadlock.java:156)
Caused by: java.lang.NullPointerException
at org.apache.solr.core.SolrCore.openNewSearcher(SolrCore.java:1642)
... 21 more
FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test
Error Message:
Captured an uncaught exception in thread: Thread[id=40026, name=collection5,
state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=40026, name=collection5, state=RUNNABLE,
group=TGRP-HdfsCollectionsAPIDistributedZkTest]
Caused by:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error
from server at http://127.0.0.1:43978/wq/j: Could not find collection :
awholynewstresscollection_collection5_1
at __randomizedtesting.SeedInfo.seed([8856DE01B6BF11DD]:0)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:857)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:800)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:895)
Build Log:
[...truncated 10120 lines...]
[junit4] Suite: org.apache.solr.search.TestReloadDeadlock
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.TestReloadDeadlock_8856DE01B6BF11DD-001/init-core-data-001
[junit4] 2> 1937857 INFO
(SUITE-TestReloadDeadlock-seed#[8856DE01B6BF11DD]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
[junit4] 2> 1937857 INFO
(SUITE-TestReloadDeadlock-seed#[8856DE01B6BF11DD]-worker) [ ]
o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 1937858 INFO
(SUITE-TestReloadDeadlock-seed#[8856DE01B6BF11DD]-worker) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 1937858 INFO
(SUITE-TestReloadDeadlock-seed#[8856DE01B6BF11DD]-worker) [ ]
o.a.s.c.SolrResourceLoader Adding
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/.svn/'
to classloader
[junit4] 2> 1937858 INFO
(SUITE-TestReloadDeadlock-seed#[8856DE01B6BF11DD]-worker) [ ]
o.a.s.c.SolrResourceLoader Adding
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/classes/'
to classloader
[junit4] 2> 1937858 INFO
(SUITE-TestReloadDeadlock-seed#[8856DE01B6BF11DD]-worker) [ ]
o.a.s.c.SolrResourceLoader Adding
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/README'
to classloader
[junit4] 2> 1937881 INFO
(SUITE-TestReloadDeadlock-seed#[8856DE01B6BF11DD]-worker) [ ]
o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1937885 INFO
(SUITE-TestReloadDeadlock-seed#[8856DE01B6BF11DD]-worker) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
[junit4] 2> 1937893 INFO
(SUITE-TestReloadDeadlock-seed#[8856DE01B6BF11DD]-worker) [ ] o.a.s.c.Config
Loaded SolrConfig: solrconfig-tlog.xml
[junit4] 2> 1937894 INFO
(SUITE-TestReloadDeadlock-seed#[8856DE01B6BF11DD]-worker) [ ]
o.a.s.s.IndexSchema Reading Solr Schema from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema15.xml
[junit4] 2> 1937898 INFO
(SUITE-TestReloadDeadlock-seed#[8856DE01B6BF11DD]-worker) [ ]
o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 1937987 INFO
(SUITE-TestReloadDeadlock-seed#[8856DE01B6BF11DD]-worker) [ ]
o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 1937988 INFO
(SUITE-TestReloadDeadlock-seed#[8856DE01B6BF11DD]-worker) [ ]
o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1937989 INFO
(SUITE-TestReloadDeadlock-seed#[8856DE01B6BF11DD]-worker) [ ]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1937991 INFO
(SUITE-TestReloadDeadlock-seed#[8856DE01B6BF11DD]-worker) [ ]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1937997 INFO
(SUITE-TestReloadDeadlock-seed#[8856DE01B6BF11DD]-worker) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1937998 INFO
(SUITE-TestReloadDeadlock-seed#[8856DE01B6BF11DD]-worker) [ ]
o.a.s.c.SolrResourceLoader using system property solr.solr.home:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr
[junit4] 2> 1937998 INFO
(SUITE-TestReloadDeadlock-seed#[8856DE01B6BF11DD]-worker) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/'
[junit4] 2> 1938011 INFO
(SUITE-TestReloadDeadlock-seed#[8856DE01B6BF11DD]-worker) [ ]
o.a.s.c.CoreContainer New CoreContainer 451531080
[junit4] 2> 1938011 INFO
(SUITE-TestReloadDeadlock-seed#[8856DE01B6BF11DD]-worker) [ ]
o.a.s.c.CoreContainer Loading cores into CoreContainer
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/]
[junit4] 2> 1938012 INFO
(SUITE-TestReloadDeadlock-seed#[8856DE01B6BF11DD]-worker) [ ]
o.a.s.c.CoreContainer loading shared library:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/lib
[junit4] 2> 1938012 WARN
(SUITE-TestReloadDeadlock-seed#[8856DE01B6BF11DD]-worker) [ ]
o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to
classloader: lib (resolved as:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/lib).
[junit4] 2> 1938019 INFO
(SUITE-TestReloadDeadlock-seed#[8856DE01B6BF11DD]-worker) [ ]
o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout :
600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections :
10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime :
5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 1938022 INFO
(SUITE-TestReloadDeadlock-seed#[8856DE01B6BF11DD]-worker) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=30000&connTimeout=30000&retry=true
[junit4] 2> 1938022 INFO
(SUITE-TestReloadDeadlock-seed#[8856DE01B6BF11DD]-worker) [ ]
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1938022 INFO
(SUITE-TestReloadDeadlock-seed#[8856DE01B6BF11DD]-worker) [ ]
o.a.s.l.LogWatcher Registering Log Listener [Log4j
(org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1938022 INFO
(SUITE-TestReloadDeadlock-seed#[8856DE01B6BF11DD]-worker) [ ]
o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for
authorization module.
[junit4] 2> 1938022 INFO
(SUITE-TestReloadDeadlock-seed#[8856DE01B6BF11DD]-worker) [ ]
o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1938023 INFO
(SUITE-TestReloadDeadlock-seed#[8856DE01B6BF11DD]-worker) [ ]
o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1,
config=solrconfig-tlog.xml, transient=false, schema=schema15.xml,
loadOnStartup=true, configSetProperties=configsetprops.json,
instanceDir=collection1, collection=collection1,
absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/,
dataDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.TestReloadDeadlock_8856DE01B6BF11DD-001/init-core-data-001,
shard=shard1}
[junit4] 2> 1938024 INFO (coreLoadExecutor-3495-thread-1) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 1938025 INFO (coreLoadExecutor-3495-thread-1) [ ]
o.a.s.c.SolrResourceLoader Adding
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/.svn/'
to classloader
[junit4] 2> 1938025 INFO (coreLoadExecutor-3495-thread-1) [ ]
o.a.s.c.SolrResourceLoader Adding
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/classes/'
to classloader
[junit4] 2> 1938025 INFO (coreLoadExecutor-3495-thread-1) [ ]
o.a.s.c.SolrResourceLoader Adding
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/README'
to classloader
[junit4] 2> 1938042 INFO (coreLoadExecutor-3495-thread-1) [ ]
o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1938045 INFO (coreLoadExecutor-3495-thread-1) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
[junit4] 2> 1938053 INFO (coreLoadExecutor-3495-thread-1) [ ]
o.a.s.c.Config Loaded SolrConfig: solrconfig-tlog.xml
[junit4] 2> 1938053 INFO (coreLoadExecutor-3495-thread-1) [ ]
o.a.s.s.IndexSchema Reading Solr Schema from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema15.xml
[junit4] 2> 1938057 INFO (coreLoadExecutor-3495-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1938133 INFO (coreLoadExecutor-3495-thread-1) [ ]
o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 1938134 INFO (coreLoadExecutor-3495-thread-1) [ ]
o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1938135 INFO (coreLoadExecutor-3495-thread-1) [ ]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1938137 INFO (coreLoadExecutor-3495-thread-1) [ ]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1938145 INFO (coreLoadExecutor-3495-thread-1) [ ]
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default
properties: Can't find resource 'configsetprops.json' in classpath or
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf'
[junit4] 2> 1938145 INFO (coreLoadExecutor-3495-thread-1) [ ]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
instancedir
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/
[junit4] 2> 1938145 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1938145 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/],
dataDir=[null]
[junit4] 2> 1938145 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@565bc5c7
[junit4] 2> 1938146 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.TestReloadDeadlock_8856DE01B6BF11DD-001/init-core-data-001
[junit4] 2> 1938146 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null
new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.TestReloadDeadlock_8856DE01B6BF11DD-001/init-core-data-001/index/
[junit4] 2> 1938146 WARN (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.TestReloadDeadlock_8856DE01B6BF11DD-001/init-core-data-001/index'
doesn't exist. Creating new index...
[junit4] 2> 1938147 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.TestReloadDeadlock_8856DE01B6BF11DD-001/init-core-data-001/index
[junit4] 2> 1938147 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=36, maxMergeAtOnceExplicit=23, maxMergedSegmentMB=0.2783203125,
floorSegmentMB=1.1630859375, forceMergeDeletesPctAllowed=9.71391799779356,
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1938147 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2ea85306
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fc70690),segFN=segments_1,generation=1}
[junit4] 2> 1938147 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 1938148 INFO
(OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1]
o.a.s.c.SolrCore Looking for old index directories to cleanup for core
collection1 in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.TestReloadDeadlock_8856DE01B6BF11DD-001/init-core-data-001/
[junit4] 2> 1938151 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 1938151 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 1938151 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1938152 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 1938152 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1938152 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1938152 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1938152 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 1938152 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as
default, creating implicit default
[junit4] 2> 1938154 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1938155 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1938156 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1938157 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1938160 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.c.RequestHandlers Registered paths:
/admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 1938160 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.c.SolrCore Using default statsCache cache:
org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1938161 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1938161 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 1938162 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
[junit4] 2> 1938162 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
[junit4] 2> 1938162 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=46, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=1.0]
[junit4] 2> 1938163 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2ea85306
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fc70690),segFN=segments_1,generation=1}
[junit4] 2> 1938163 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 1938163 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@21c3b634[collection1]
main
[junit4] 2> 1938163 WARN (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf;
switching to use InMemory storage instead.
[junit4] 2> 1938163 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 1938163 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using
InMemoryStorage
[junit4] 2> 1938164 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1938164 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1938164 INFO
(searcherExecutor-3496-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@21c3b634[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1938164 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed
version buckets
[junit4] 2> 1938165 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for
65536 version buckets from index
[junit4] 2> 1938165 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed
version bucket highest value from index
[junit4] 2> 1938165 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent
updates, using new clock 1509802301295951872
[junit4] 2> 1938167 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with
highest version 1509802301295951872
[junit4] 2> 1938167 INFO (coreLoadExecutor-3495-thread-1) [
x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 1938168 INFO
(SUITE-TestReloadDeadlock-seed#[8856DE01B6BF11DD]-worker) [ ]
o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 1938172 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testReloadDeadlock
[junit4] 2> 1938173 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [ ]
o.a.s.c.SolrCore [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1938174 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [ ]
o.a.s.s.SolrIndexSearcher Opening Searcher@6ebad5b3[collection1] realtime
[junit4] 2> 1938174 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [ ]
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null
params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=*:* (-9223372036854775807)} 0 1
[junit4] 2> 1938174 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [ ]
o.a.s.u.UpdateHandler start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1938175 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [ ]
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2ea85306
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fc70690),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2ea85306
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fc70690),segFN=segments_2,generation=2}
[junit4] 2> 1938175 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [ ]
o.a.s.c.SolrCore newest commit generation = 2
[junit4] 2> 1938176 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [ ]
o.a.s.s.SolrIndexSearcher Opening Searcher@3ab003dd[collection1] main
[junit4] 2> 1938176 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [ ]
o.a.s.u.UpdateHandler end_commit_flush
[junit4] 2> 1938176 INFO (searcherExecutor-3496-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@3ab003dd[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1938177 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [ ]
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={}
{commit=} 0 2
[junit4] 2> 1938184 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-3&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:2 (-3)} 0 2
[junit4] 2> 1938185 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-2&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:19 (-2)} 0 3
[junit4] 2> 1938186 INFO (WRITER1) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-1&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:19 (-1)} 0 4
[junit4] 2> 1938186 INFO (WRITER4) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-7&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:4 (-7)} 0 3
[junit4] 2> 1938187 INFO (WRITER6) [ ] o.a.s.u.UpdateHandler Reordered
DBQs detected. Update=add{_version_=5,id=5} DBQs=[DBQ{version=7,q=id:4}]
[junit4] 2> 1938191 INFO (WRITER6) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@5b3abf27[collection1] realtime
[junit4] 2> 1938191 INFO (WRITER6) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5
(5)]} 0 9
[junit4] 2> 1938193 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-6&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:10 (-6)} 0 10
[junit4] 2> 1938194 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-4&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:9 (-4)} 0 11
[junit4] 2> 1938195 INFO (WRITER4) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[2
(8)]} 0 8
[junit4] 2> 1938197 INFO (WRITER1) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@255bde7[collection1] realtime
[junit4] 2> 1938198 INFO (WRITER1) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-10&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:12 (-10)} 0 11
[junit4] 2> 1938198 INFO (WRITER0) [ ] o.a.s.u.UpdateHandler start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1938198 INFO (WRITER5) [ ] o.a.s.u.UpdateHandler Reordered
DBQs detected. Update=add{_version_=9,id=12} DBQs=[DBQ{version=10,q=id:12}]
[junit4] 2> 1938198 INFO (WRITER0) [ ] o.a.s.c.SolrCore
SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2ea85306
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fc70690),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2ea85306
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fc70690),segFN=segments_3,generation=3}
[junit4] 2> 1938198 INFO (WRITER0) [ ] o.a.s.c.SolrCore newest commit
generation = 3
[junit4] 2> 1938199 INFO (WRITER6) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[8
(11)]} 0 7
[junit4] 2> 1938204 INFO (WRITER0) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@c040a76[collection1] main
[junit4] 2> 1938204 INFO (WRITER0) [ ] o.a.s.u.UpdateHandler
end_commit_flush
[junit4] 2> 1938205 INFO (searcherExecutor-3496-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@c040a76[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c1)
Uninverting(_1(6.0.0):c1) Uninverting(_2(6.0.0):c1)))}
[junit4] 2> 1938205 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[12
(9)]} 0 18
[junit4] 2> 1938205 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null params={} {commit=} 0 18
[junit4] 2> 1938206 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[9
(13)]} 0 12
[junit4] 2> 1938207 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-12&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:12 (-12)} 0 13
[junit4] 2> 1938208 INFO (WRITER4) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[2
(14)]} 0 13
[junit4] 2> 1938214 INFO (WRITER1) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@19d7c50b[collection1] realtime
[junit4] 2> 1938215 INFO (WRITER1) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-15&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:2 (-15)} 0 16
[junit4] 2> 1938215 INFO (WRITER6) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[9
(16)]} 0 15
[junit4] 2> 1938218 INFO (WRITER5) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@395f2ee0[collection1] realtime
[junit4] 2> 1938218 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-17&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:19 (-17)} 0 12
[junit4] 2> 1938219 INFO (WRITER2) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@1b763f9c[collection1] realtime
[junit4] 2> 1938219 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-18&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:9 (-18)} 0 12
[junit4] 2> 1938220 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[2
(19)]} 0 12
[junit4] 2> 1938221 INFO (WRITER4) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@2efc5f99[collection1] realtime
[junit4] 2> 1938222 INFO (WRITER4) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-20&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:12 (-20)} 0 13
[junit4] 2> 1938222 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[12
(21)]} 0 13
[junit4] 2> 1938224 INFO (WRITER1) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@5c0b5a3b[collection1] realtime
[junit4] 2> 1938224 INFO (WRITER1) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-22&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:12 (-22)} 0 8
[junit4] 2> 1938224 INFO (WRITER6) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-23&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:9 (-23)} 0 8
[junit4] 2> 1938224 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[19
(24)]} 0 5
[junit4] 2> 1938224 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[11
(25)]} 0 4
[junit4] 2> 1938225 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[19
(26)]} 0 4
[junit4] 2> 1938225 INFO (WRITER4) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[19
(27)]} 0 2
[junit4] 2> 1938225 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[19
(28)]} 0 2
[junit4] 2> 1938228 INFO (WRITER1) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@15eee50b[collection1] realtime
[junit4] 2> 1938228 INFO (WRITER1) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-29&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:5 (-29)} 0 4
[junit4] 2> 1938228 INFO (WRITER6) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[3
(30)]} 0 4
[junit4] 2> 1938228 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0
(31)]} 0 3
[junit4] 2> 1938231 INFO (WRITER2) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@48e75370[collection1] realtime
[junit4] 2> 1938231 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-32&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:2 (-32)} 0 6
[junit4] 2> 1938231 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[11
(33)]} 0 6
[junit4] 2> 1938232 INFO (WRITER4) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[8
(34)]} 0 6
[junit4] 2> 1938235 INFO (WRITER0) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@13c46894[collection1] realtime
[junit4] 2> 1938236 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-35&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:8 (-35)} 0 10
[junit4] 2> 1938236 INFO (WRITER1) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5
(36)]} 0 7
[junit4] 2> 1938238 INFO (WRITER6) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@3cfacfc8[collection1] realtime
[junit4] 2> 1938238 INFO (WRITER6) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-37&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:19 (-37)} 0 9
[junit4] 2> 1938238 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[3
(38)]} 0 9
[junit4] 2> 1938240 INFO (WRITER2) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@2d47a129[collection1] realtime
[junit4] 2> 1938240 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-39&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:2 (-39)} 0 8
[junit4] 2> 1938240 INFO (WRITER4) [ ] o.a.s.u.UpdateHandler start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1938241 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0
(40)]} 0 9
[junit4] 2> 1938249 INFO (WRITER4) [ ] o.a.s.c.SolrCore
SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2ea85306
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fc70690),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2ea85306
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fc70690),segFN=segments_4,generation=4}
[junit4] 2> 1938250 INFO (WRITER4) [ ] o.a.s.c.SolrCore newest commit
generation = 4
[junit4] 2> 1938250 INFO (WRITER4) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@3f6cef20[collection1] main
[junit4] 2> 1938251 INFO (searcherExecutor-3496-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@3f6cef20[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.0.0):c2/1:delGen=1)
Uninverting(_c(6.0.0):c1) Uninverting(_d(6.0.0):c1)
Uninverting(_e(6.0.0):c1)))}
[junit4] 2> 1938251 INFO (WRITER4) [ ] o.a.s.u.UpdateHandler
end_commit_flush
[junit4] 2> 1938252 INFO (WRITER4) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null params={} {commit=} 0 18
[junit4] 2> 1938254 INFO (WRITER1) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@28697441[collection1] realtime
[junit4] 2> 1938255 INFO (WRITER1) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-42&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:11 (-42)} 0 18
[junit4] 2> 1938256 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-41&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:11 (-41)} 0 20
[junit4] 2> 1938257 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[9
(44)]} 0 18
[junit4] 2> 1938257 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[11
(45)]} 0 16
[junit4] 2> 1938256 INFO (WRITER6) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-43&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:19 (-43)} 0 17
[junit4] 2> 1938261 INFO (WRITER3) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@5dc92f1f[collection1] realtime
[junit4] 2> 1938261 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-46&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:8 (-46)} 0 19
[junit4] 2> 1938262 INFO (WRITER4) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0
(47)]} 0 7
[junit4] 2> 1938265 INFO (WRITER1) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@6406bf51[collection1] realtime
[junit4] 2> 1938265 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-49&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:11 (-49)} 0 8
[junit4] 2> 1938266 INFO (WRITER6) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[19
(52)]} 0 8
[junit4] 2> 1938266 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[9
(50)]} 0 9
[junit4] 2> 1938266 INFO (WRITER1) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-48&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:11 (-48)} 0 10
[junit4] 2> 1938275 INFO (WRITER3) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@1ff43058[collection1] realtime
[junit4] 2> 1938275 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-53&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:5 (-53)} 0 13
[junit4] 2> 1938275 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[9
(51)]} 0 17
[junit4] 2> 1938276 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[11
(55)]} 0 10
[junit4] 2> 1938276 INFO (WRITER4) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[8
(54)]} 0 13
[junit4] 2> 1938279 INFO (WRITER6) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@42677558[collection1] realtime
[junit4] 2> 1938279 INFO (WRITER6) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-56&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:11 (-56)} 0 12
[junit4] 2> 1938279 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[6
(57)]} 0 12
[junit4] 2> 1938281 INFO (WRITER1) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@36ffc399[collection1] realtime
[junit4] 2> 1938282 INFO (WRITER1) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-58&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:9 (-58)} 0 15
[junit4] 2> 1938282 INFO (WRITER5) [ ] o.a.s.u.UpdateHandler start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1938282 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[9
(59)]} 0 7
[junit4] 2> 1938284 INFO (WRITER1) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[9
(64)]} 0 2
[junit4] 2> 1938285 INFO (WRITER5) [ ] o.a.s.c.SolrCore
SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2ea85306
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fc70690),segFN=segments_4,generation=4}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2ea85306
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fc70690),segFN=segments_5,generation=5}
[junit4] 2> 1938285 INFO (WRITER5) [ ] o.a.s.c.SolrCore newest commit
generation = 5
[junit4] 2> 1938338 INFO (WRITER5) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@45322351[collection1] main
[junit4] 2> 1938338 INFO (WRITER5) [ ] o.a.s.u.UpdateHandler
end_commit_flush
[junit4] 2> 1938339 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[11
(61)]} 0 62
[junit4] 2> 1938339 INFO (WRITER6) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[11
(63)]} 0 59
[junit4] 2> 1938339 INFO (searcherExecutor-3496-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@45322351[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_d(6.0.0):c1)
Uninverting(_h(6.0.0):c1) Uninverting(_i(6.0.0):c1)
Uninverting(_k(6.0.0):c2/1:delGen=1) Uninverting(_l(6.0.0):c1)
Uninverting(_n(6.0.0):c1)))}
[junit4] 2> 1938339 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[15
(60)]} 0 62
[junit4] 2> 1938339 INFO (WRITER4) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[16
(62)]} 0 62
[junit4] 2> 1938340 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null params={} {commit=} 0 60
[junit4] 2> 1938341 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 1938342 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [ ]
o.a.s.c.SolrResourceLoader Adding
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/.svn/'
to classloader
[junit4] 2> 1938342 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [ ]
o.a.s.c.SolrResourceLoader Adding
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/classes/'
to classloader
[junit4] 2> 1938342 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [ ]
o.a.s.c.SolrResourceLoader Adding
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/README'
to classloader
[junit4] 2> 1938345 INFO (WRITER3) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@17abf96b[collection1] realtime
[junit4] 2> 1938345 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-65&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:9 (-65)} 0 62
[junit4] 2> 1938350 INFO (WRITER0) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@940090b[collection1] realtime
[junit4] 2> 1938350 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-67&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:11 (-67)} 0 10
[junit4] 2> 1938352 INFO (WRITER1) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[9
(66)]} 0 67
[junit4] 2> 1938352 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[15
(69)]} 0 12
[junit4] 2> 1938353 INFO (WRITER5) [ ] o.a.s.u.UpdateHandler start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1938353 INFO (WRITER6) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-68&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:11 (-68)} 0 13
[junit4] 2> 1938353 INFO (WRITER4) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[15
(70)]} 0 12
[junit4] 2> 1938355 INFO (WRITER5) [ ] o.a.s.c.SolrCore
SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2ea85306
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fc70690),segFN=segments_5,generation=5}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2ea85306
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fc70690),segFN=segments_6,generation=6}
[junit4] 2> 1938355 INFO (WRITER5) [ ] o.a.s.c.SolrCore newest commit
generation = 6
[junit4] 2> 1938355 INFO (WRITER5) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@189a8cd7[collection1] main
[junit4] 2> 1938356 INFO (WRITER5) [ ] o.a.s.u.UpdateHandler
end_commit_flush
[junit4] 2> 1938356 INFO (searcherExecutor-3496-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@189a8cd7[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_d(6.0.0):c1)
Uninverting(_h(6.0.0):c1) Uninverting(_i(6.0.0):c1)
Uninverting(_k(6.0.0):c2/1:delGen=1) Uninverting(_l(6.0.0):c1)
Uninverting(_p(6.0.0):c3/2:delGen=2) Uninverting(_q(6.0.0):c1)
Uninverting(_r(6.0.0):c2/1:delGen=1)))}
[junit4] 2> 1938357 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null params={} {commit=} 0 14
[junit4] 2> 1938361 INFO (WRITER3) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@3963a168[collection1] realtime
[junit4] 2> 1938362 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-71&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:9 (-71)} 0 16
[junit4] 2> 1938362 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[11
(72)]} 0 11
[junit4] 2> 1938365 INFO (WRITER2) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@7fb1baf[collection1] realtime
[junit4] 2> 1938365 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-74&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:12 (-74)} 0 12
[junit4] 2> 1938366 INFO (WRITER4) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[12
(76)]} 0 12
[junit4] 2> 1938366 INFO (WRITER0) [ ] o.a.s.u.UpdateHandler start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1938366 INFO (WRITER6) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[1
(75)]} 0 12
[junit4] 2> 1938366 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[12
(77)]} 0 8
[junit4] 2> 1938366 INFO (WRITER1) [ ] o.a.s.u.UpdateHandler Reordered
DBQs detected. Update=add{_version_=73,id=16} DBQs=[DBQ{version=74,q=id:12}]
[junit4] 2> 1938371 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [ ]
o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1938375 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
[junit4] 2> 1938379 INFO (WRITER0) [ ] o.a.s.c.SolrCore
SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2ea85306
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fc70690),segFN=segments_6,generation=6}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2ea85306
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fc70690),segFN=segments_7,generation=7}
[junit4] 2> 1938380 INFO (WRITER0) [ ] o.a.s.c.SolrCore newest commit
generation = 7
[junit4] 2> 1938386 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [ ]
o.a.s.c.Config Loaded SolrConfig: solrconfig-tlog.xml
[junit4] 2> 1938386 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [ ]
o.a.s.s.IndexSchema Reading Solr Schema from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema15.xml
[junit4] 2> 1938391 INFO (WRITER1) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@1df484c8[collection1] realtime
[junit4] 2> 1938392 INFO (WRITER0) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@2c1dd287[collection1] main
[junit4] 2> 1938392 INFO (WRITER0) [ ] o.a.s.u.UpdateHandler
end_commit_flush
[junit4] 2> 1938392 INFO (WRITER1) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[16
(73)]} 0 35
[junit4] 2> 1938393 INFO (searcherExecutor-3496-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@2c1dd287[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_d(6.0.0):c1)
Uninverting(_h(6.0.0):c1) Uninverting(_i(6.0.0):c1)
Uninverting(_k(6.0.0):c2/1:delGen=1) Uninverting(_l(6.0.0):c1)
Uninverting(_r(6.0.0):c2/1:delGen=1) Uninverting(_s(6.0.0):c1)
Uninverting(_t(6.0.0):c2/1:delGen=1) Uninverting(_u(6.0.0):c1)
Uninverting(_v(6.0.0):c1)))}
[junit4] 2> 1938393 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null params={} {commit=} 0 30
[junit4] 2> 1938395 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-79&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:4 (-79)} 0 29
[junit4] 2> 1938396 INFO (WRITER4) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[12
(80)]} 0 29
[junit4] 2> 1938396 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [ ]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1938399 INFO (WRITER6) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@26f0e6b[collection1] realtime
[junit4] 2> 1938399 INFO (WRITER6) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-81&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:1 (-81)} 0 33
[junit4] 2> 1938399 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[1
(82)]} 0 33
[junit4] 2> 1938402 INFO (WRITER3) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@3ec798e1[collection1] realtime
[junit4] 2> 1938402 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-78&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:9 (-78)} 0 34
[junit4] 2> 1938404 INFO (WRITER1) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@7647c367[collection1] realtime
[junit4] 2> 1938404 INFO (WRITER1) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-83&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:16 (-83)} 0 11
[junit4] 2> 1938404 INFO (WRITER2) [ ] o.a.s.u.UpdateHandler start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1938405 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[3
(84)]} 0 11
[junit4] 2> 1938405 INFO (WRITER4) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[16
(85)]} 0 9
[junit4] 2> 1938406 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[9
(88)]} 0 3
[junit4] 2> 1938406 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[11
(87)]} 0 6
[junit4] 2> 1938407 INFO (WRITER1) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[9
(89)]} 0 2
[junit4] 2> 1938407 INFO (WRITER6) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[16
(86)]} 0 7
[junit4] 2> 1938409 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[3
(90)]} 0 3
[junit4] 2> 1938410 INFO (WRITER2) [ ] o.a.s.c.SolrCore
SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2ea85306
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fc70690),segFN=segments_7,generation=7}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2ea85306
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fc70690),segFN=segments_8,generation=8}
[junit4] 2> 1938411 INFO (WRITER2) [ ] o.a.s.c.SolrCore newest commit
generation = 8
[junit4] 2> 1938416 INFO (WRITER2) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@6561487a[collection1] main
[junit4] 2> 1938416 INFO (WRITER2) [ ] o.a.s.u.UpdateHandler
end_commit_flush
[junit4] 2> 1938417 INFO (searcherExecutor-3496-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@6561487a[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_h(6.0.0):c1)
Uninverting(_i(6.0.0):c1) Uninverting(_k(6.0.0):c2/1:delGen=1)
Uninverting(_l(6.0.0):c1) Uninverting(_r(6.0.0):c2/1:delGen=1)
Uninverting(_w(6.0.0):c1) Uninverting(_x(6.0.0):c1)
Uninverting(_z(6.0.0):c4/2:delGen=1) Uninverting(_10(6.0.0):c1)
Uninverting(_11(6.0.0):c1)))}
[junit4] 2> 1938417 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null params={} {commit=} 0 21
[junit4] 2> 1938419 INFO (WRITER3) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@5853f654[collection1] realtime
[junit4] 2> 1938419 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-92&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:9 (-92)} 0 12
[junit4] 2> 1938421 INFO (WRITER5) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@2d78347[collection1] realtime
[junit4] 2> 1938421 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-93&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:11 (-93)} 0 14
[junit4] 2> 1938422 INFO (WRITER4) [ ] o.a.s.u.UpdateHandler Reordered
DBQs detected. Update=add{_version_=91,id=16} DBQs=[DBQ{version=93,q=id:11},
DBQ{version=92,q=id:9}]
[junit4] 2> 1938426 INFO (WRITER4) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@4f6d5e26[collection1] realtime
[junit4] 2> 1938427 INFO (WRITER4) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[16
(91)]} 0 19
[junit4] 2> 1938428 INFO (WRITER1) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-94&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:11 (-94)} 0 20
[junit4] 2> 1938428 INFO (WRITER6) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[16
(95)]} 0 20
[junit4] 2> 1938429 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[16
(96)]} 0 19
[junit4] 2> 1938429 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[2
(99)]} 0 7
[junit4] 2> 1938429 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[2
(98)]} 0 5
[junit4] 2> 1938429 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[9
(97)]} 0 9
[junit4] 2> 1938430 INFO (WRITER4) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[2
(100)]} 0 2
[junit4] 2> 1938433 INFO (WRITER1) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@1e37749a[collection1] realtime
[junit4] 2> 1938433 INFO (WRITER1) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-101&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:2 (-101)} 0 4
[junit4] 2> 1938434 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[16
(104)]} 0 4
[junit4] 2> 1938434 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[16
(105)]} 0 4
[junit4] 2> 1938438 INFO (WRITER0) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@4bbefeac[collection1] realtime
[junit4] 2> 1938439 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-103&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:16 (-103)} 0 9
[junit4] 2> 1938440 INFO (WRITER6) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-102&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:16 (-102)} 0 10
[junit4] 2> 1938440 INFO (WRITER4) [ ] o.a.s.u.UpdateHandler start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1938441 INFO (WRITER1) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[6
(107)]} 0 7
[junit4] 2> 1938441 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[9
(106)]} 0 11
[junit4] 2> 1938444 INFO (WRITER4) [ ] o.a.s.c.SolrCore
SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2ea85306
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fc70690),segFN=segments_8,generation=8}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2ea85306
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fc70690),segFN=segments_9,generation=9}
[junit4] 2> 1938445 INFO (WRITER4) [ ] o.a.s.c.SolrCore newest commit
generation = 9
[junit4] 2> 1938445 INFO (WRITER4) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@3e475dd3[collection1] main
[junit4] 2> 1938445 INFO (WRITER4) [ ] o.a.s.u.UpdateHandler
end_commit_flush
[junit4] 2> 1938446 INFO (searcherExecutor-3496-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@3e475dd3[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_h(6.0.0):c1)
Uninverting(_i(6.0.0):c1) Uninverting(_k(6.0.0):c2/1:delGen=1)
Uninverting(_r(6.0.0):c2/1:delGen=1) Uninverting(_w(6.0.0):c1)
Uninverting(_x(6.0.0):c1) Uninverting(_11(6.0.0):c1)
Uninverting(_14(6.0.0):c2/1:delGen=1) Uninverting(_16(6.0.0):c1)
Uninverting(_15(6.0.0):c1)))}
[junit4] 2> 1938447 INFO (WRITER4) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null params={} {commit=} 0 16
[junit4] 2> 1938450 INFO (WRITER5) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@f353524[collection1] realtime
[junit4] 2> 1938450 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-108&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:1 (-108)} 0 14
[junit4] 2> 1938448 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-109&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:10 (-109)} 0 13
[junit4] 2> 1938450 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[16
(110)]} 0 11
[junit4] 2> 1938450 INFO (WRITER6) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[12
(111)]} 0 10
[junit4] 2> 1938451 INFO (WRITER1) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[12
(112)]} 0 9
[junit4] 2> 1938455 INFO (WRITER3) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@32030546[collection1] realtime
[junit4] 2> 1938455 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-113&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:12 (-113)} 0 13
[junit4] 2> 1938456 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-115&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:14 (-115)} 0 5
[junit4] 2> 1938457 INFO (WRITER5) [ ] o.a.s.u.UpdateHandler Reordered
DBQs detected. Update=add{_version_=114,id=0} DBQs=[DBQ{version=115,q=id:14}]
[junit4] 2> 1938457 INFO (WRITER6) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[19
(117)]} 0 6
[junit4] 2> 1938457 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[1
(116)]} 0 6
[junit4] 2> 1938469 INFO (WRITER5) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@1a19fcd2[collection1] realtime
[junit4] 2> 1938469 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0
(114)]} 0 18
[junit4] 2> 1938470 INFO (WRITER4) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[1
(118)]} 0 18
[junit4] 2> 1938473 INFO (WRITER1) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@21fc492[collection1] realtime
[junit4] 2> 1938473 INFO (WRITER1) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-119&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:1 (-119)} 0 21
[junit4] 2> 1938474 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-120&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:1 (-120)} 0 18
[junit4] 2> 1938475 INFO (WRITER6) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@240a3815[collection1] realtime
[junit4] 2> 1938476 INFO (WRITER6) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-122&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:19 (-122)} 0 18
[junit4] 2> 1938476 INFO (WRITER2) [ ] o.a.s.u.UpdateHandler Reordered
DBQs detected. Update=add{_version_=121,id=1} DBQs=[DBQ{version=122,q=id:19}]
[junit4] 2> 1938476 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[19
(123)]} 0 19
[junit4] 2> 1938478 INFO (WRITER2) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@360d2311[collection1] realtime
[junit4] 2> 1938479 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[1
(121)]} 0 21
[junit4] 2> 1938479 INFO (WRITER4) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[2
(125)]} 0 8
[junit4] 2> 1938480 INFO (WRITER5) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@78f5f5d[collection1] realtime
[junit4] 2> 1938480 INFO (WRITER1) [ ] o.a.s.u.UpdateHandler start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1938480 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-124&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:16 (-124)} 0 10
[junit4] 2> 1938482 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0
(126)]} 0 7
[junit4] 2> 1938483 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0
(132)]} 0 0
[junit4] 2> 1938483 INFO (WRITER1) [ ] o.a.s.c.SolrCore
SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2ea85306
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fc70690),segFN=segments_9,generation=9}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2ea85306
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fc70690),segFN=segments_a,generation=10}
[junit4] 2> 1938483 INFO (WRITER1) [ ] o.a.s.c.SolrCore newest commit
generation = 10
[junit4] 2> 1938484 INFO (WRITER4) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[19
(130)]} 0 5
[junit4] 2> 1938484 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[2
(128)]} 0 7
[junit4] 2> 1938484 INFO (WRITER6) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[2
(127)]} 0 7
[junit4] 2> 1938484 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[19
(131)]} 0 3
[junit4] 2> 1938484 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[19
(129)]} 0 4
[junit4] 2> 1938490 INFO (WRITER1) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@57163675[collection1] main
[junit4] 2> 1938490 INFO (WRITER1) [ ] o.a.s.u.UpdateHandler
end_commit_flush
[junit4] 2> 1938491 INFO (searcherExecutor-3496-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@57163675[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_k(6.0.0):c2/1:delGen=1)
Uninverting(_r(6.0.0):c2/1:delGen=1) Uninverting(_11(6.0.0):c1)
Uninverting(_16(6.0.0):c1) Uninverting(_15(6.0.0):c1)
Uninverting(_1c(6.0.0):c3/2) Uninverting(_1e(6.0.0):c3/1:delGen=1)
Uninverting(_1d(6.0.0):c2/1:delGen=1)))}
[junit4] 2> 1938492 INFO (WRITER1) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null params={} {commit=} 0 18
[junit4] 2> 1938493 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-133&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:4 (-133)} 0 9
[junit4] 2> 1938493 INFO (WRITER4) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[4
(134)]} 0 7
[junit4] 2> 1938518 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [ ]
o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 1938519 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [ ]
o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1938520 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [ ]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1938522 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [ ]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1938530 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [ ]
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default
properties: Can't find resource 'configsetprops.json' in classpath or
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf'
[junit4] 2> 1938530 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [ ]
o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from
instancedir
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/
[junit4] 2> 1938531 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.TestReloadDeadlock_8856DE01B6BF11DD-001/init-core-data-001/]
[junit4] 2> 1938531 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@565bc5c7
[junit4] 2> 1938532 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null
new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.TestReloadDeadlock_8856DE01B6BF11DD-001/init-core-data-001/index/
[junit4] 2> 1938537 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 1938537 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 1938537 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1938537 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 1938538 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1938538 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1938538 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1938538 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 1938539 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as
default, creating implicit default
[junit4] 2> 1938540 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1938541 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1938542 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1938543 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1938544 INFO
(OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1]
o.a.s.c.SolrCore Looking for old index directories to cleanup for core
collection1 in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.TestReloadDeadlock_8856DE01B6BF11DD-001/init-core-data-001/
[junit4] 2> 1938546 INFO (WRITER0) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@77ee0993[collection1] realtime
[junit4] 2> 1938547 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-135&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:2 (-135)} 0 61
[junit4] 2> 1938548 INFO (WRITER5) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@53b0447[collection1] realtime
[junit4] 2> 1938548 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-137&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:19 (-137)} 0 62
[junit4] 2> 1938549 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-138&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:14 (-138)} 0 62
[junit4] 2> 1938549 INFO (WRITER6) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-136&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:10 (-136)} 0 63
[junit4] 2> 1938551 INFO (WRITER3) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@49489afc[collection1] realtime
[junit4] 2> 1938551 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-139&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:4 (-139)} 0 58
[junit4] 2> 1938552 INFO (WRITER4) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-140&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:7 (-140)} 0 58
[junit4] 2> 1938552 INFO (WRITER1) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-141&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:4 (-141)} 0 58
[junit4] 2> 1938552 INFO (WRITER0) [ ] o.a.s.u.UpdateHandler start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1938553 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [
x:collection1] o.a.s.c.RequestHandlers Registered paths:
/admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 1938554 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [
x:collection1] o.a.s.c.SolrCore Using default statsCache cache:
org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1938554 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [
x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
[junit4] 2> 1938555 INFO (WRITER1) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[4
(147)]} 0 2
[junit4] 2> 1938555 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [
x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
[junit4] 2> 1938556 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[6
(142)]} 0 5
[junit4] 2> 1938556 INFO (WRITER4) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[7
(146)]} 0 4
[junit4] 2> 1938557 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[14
(143)]} 0 6
[junit4] 2> 1938557 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[14
(145)]} 0 5
[junit4] 2> 1938557 INFO (WRITER6) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[14
(144)]} 0 6
[junit4] 2> 1938610 INFO (WRITER0) [ ] o.a.s.c.SolrCore
SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2ea85306
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fc70690),segFN=segments_a,generation=10}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2ea85306
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fc70690),segFN=segments_b,generation=11}
[junit4] 2> 1938610 INFO (WRITER0) [ ] o.a.s.c.SolrCore newest commit
generation = 11
[junit4] 2> 1938612 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@6fbcfe53[collection1]
main
[junit4] 2> 1938613 WARN
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf;
switching to use InMemory storage instead.
[junit4] 2> 1938614 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 1938614 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using
InMemoryStorage
[junit4] 2> 1938614 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1938614 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1938613 INFO (WRITER0) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@633800f6[collection1] main
[junit4] 2> 1938615 INFO
(searcherExecutor-3501-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@6fbcfe53[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_k(6.0.0):c2/1:delGen=1)
Uninverting(_r(6.0.0):c2/1:delGen=1) Uninverting(_11(6.0.0):c1)
Uninverting(_15(6.0.0):c1) Uninverting(_1c(6.0.0):c3/2:delGen=1)
Uninverting(_1d(6.0.0):c2/1:delGen=1) Uninverting(_1i(6.0.0):c1)
Uninverting(_1j(6.0.0):c2) Uninverting(_1k(6.0.0):c1)))}
[junit4] 2> 1938615 INFO (WRITER0) [ ] o.a.s.u.UpdateHandler
end_commit_flush
[junit4] 2> 1938615 INFO
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[8856DE01B6BF11DD]) [
x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed
version buckets
[junit4] 2> 1938615 INFO (searcherExecutor-3496-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@633800f6[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_k(6.0.0):c2/1:delGen=1)
Uninverting(_r(6.0.0):c2/1:delGen=1) Uninverting(_11(6.0.0):c1)
Uninverting(_15(6.0.0):c1) Uninverting(_1c(6.0.0):c3/2:delGen=1)
Uninverting(_1d(6.0.0):c2/1:delGen=1) Uninverting(_1i(6.0.0):c1)
Uninverting(_1j(6.0.0):c2) Uninverting(_1k(6.0.0):c1)))}
[junit4] 2> 1938617 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null params={} {commit=} 0 70
[junit4] 2> 1938619 INFO (WRITER1) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@3ed2a016[collection1] realtime
[junit4] 2> 1938619 INFO (WRITER1) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={_version_=-148&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
{deleteByQuery=id:4 (-148)} 0 64
[junit4] 2> 1938620 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[8
(149)]} 0 63
[junit4] 2> 1938620 INFO (WRITER4) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[8
(150)]} 0 62
[junit4] 2> 1938620 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessor
[collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[14
(151)]} 0 63
[junit4] 2> 1938623 INFO (WRITER3) [ ] o.a.s.s.SolrIndexSearcher
Opening Searcher@4b1d82d1[collection1] realtime
[junit4] 2> 1938623 INFO (WRITER3) [ ]
[...truncated too long message...]
nizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] 2> at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 18) Thread[id=906, name=searcherExecutor-354-thread-1,
state=WAITING, group=TGRP-FullSolrCloudDistribCmdsTest]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] 2> at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 19) Thread[id=903, name=searcherExecutor-362-thread-1,
state=WAITING, group=TGRP-FullSolrCloudDistribCmdsTest]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] 2> at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 20) Thread[id=929, name=searcherExecutor-370-thread-1,
state=WAITING, group=TGRP-FullSolrCloudDistribCmdsTest]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] 2> at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 21) Thread[id=650, name=Scheduler-636326393,
state=TIMED_WAITING, group=TGRP-FullSolrCloudDistribCmdsTest]
[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.awaitNanos(AbstractQueuedSynchronizer.java:2078)
[junit4] 2> at
java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093)
[junit4] 2> at
java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 22) Thread[id=895, name=searcherExecutor-348-thread-1,
state=WAITING, group=TGRP-FullSolrCloudDistribCmdsTest]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] 2> at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 23) Thread[id=868, name=searcherExecutor-307-thread-1,
state=WAITING, group=TGRP-FullSolrCloudDistribCmdsTest]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] 2> at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 24) Thread[id=1014,
name=zkCallback-125-thread-3-processing-n:127.0.0.1:37621_-SendThread(127.0.0.1:39187),
state=TIMED_WAITING, group=TGRP-FullSolrCloudDistribCmdsTest]
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:994)
[junit4] 2> NOTE: test params are: codec=SimpleText,
sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=de_GR,
timezone=Pacific/Tongatapu
[junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation
1.8.0_45 (64-bit)/cpus=4,threads=39,free=100564488,total=498597888
[junit4] 2> NOTE: All tests run in this JVM: [ResponseLogComponentTest,
SOLR749Test, SearchHandlerTest, TestBulkSchemaConcurrent,
PreAnalyzedUpdateProcessorTest, TestCrossCoreJoin,
DistributedQueryComponentOptimizationTest,
SharedFSAutoReplicaFailoverUtilsTest, TestExtendedDismaxParser, DocValuesTest,
FullSolrCloudDistribCmdsTest]
[junit4] 2> NOTE: download the large Jenkins line-docs file by running
'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=FullSolrCloudDistribCmdsTest -Dtests.seed=8856DE01B6BF11DD
-Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true
-Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt
-Dtests.locale=de_GR -Dtests.timezone=Pacific/Tongatapu -Dtests.asserts=true
-Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s J2 | FullSolrCloudDistribCmdsTest (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>=
7200000 msec).
[junit4] > at
__randomizedtesting.SeedInfo.seed([8856DE01B6BF11DD]:0)
[junit4] Completed [461/540] on J2 in 7223.69s, 1 test, 2 errors <<<
FAILURES!
[...truncated 234 lines...]
[junit4] JVM J2: stdout was not empty, see:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/temp/junit4-J2-20150817_202855_683.sysout
[junit4] >>> JVM J2: stdout (verbatim) ----
[junit4] java.lang.OutOfMemoryError: GC overhead limit exceeded
[junit4] Dumping heap to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/heapdumps/java_pid20026.hprof
...
[junit4] Heap dump file created [603908727 bytes in 7.996 secs]
[junit4] <<< JVM J2: EOF ----
[...truncated 13 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:782:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:719:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:59:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:233:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:514:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1429:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:984:
There were test failures: 540 suites, 2131 tests, 1 suite-level error, 3
errors, 385 ignored (14 assumptions)
Total time: 233 minutes 22 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
No prior successful build to compare, so performing full copy of artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
