Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.x/925/

2 tests failed.
FAILED:  org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test

Error Message:
Captured an uncaught exception in thread: Thread[id=8286, name=collection2, 
state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=8286, name=collection2, state=RUNNABLE, 
group=TGRP-CollectionsAPIDistributedZkTest]
Caused by: 
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error 
from server at http://127.0.0.1:37696/m: Could not find collection : 
awholynewstresscollection_collection2_1
        at __randomizedtesting.SeedInfo.seed([43D7C7E8846AE831]: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:376)
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1085)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799)
        at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:894)


FAILED:  org.apache.solr.search.TestReloadDeadlock.testReloadDeadlock

Error Message:
Captured an uncaught exception in thread: Thread[id=5371, name=WRITER0, 
state=RUNNABLE, group=TGRP-TestReloadDeadlock]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=5371, name=WRITER0, state=RUNNABLE, 
group=TGRP-TestReloadDeadlock]
        at 
__randomizedtesting.SeedInfo.seed([43D7C7E8846AE831:7E566349D0210B73]:0)
Caused by: java.lang.RuntimeException: org.apache.solr.common.SolrException: 
Error opening new searcher
        at __randomizedtesting.SeedInfo.seed([43D7C7E8846AE831]: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:1662)
        at org.apache.solr.core.SolrCore.getRealtimeSearcher(SolrCore.java:1519)
        at 
org.apache.solr.update.VersionInfo.getVersionFromIndex(VersionInfo.java:201)
        at org.apache.solr.update.UpdateLog.lookupVersion(UpdateLog.java:778)
        at 
org.apache.solr.update.VersionInfo.lookupVersion(VersionInfo.java:194)
        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:1089)
        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:706)
        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:143)
        at org.apache.solr.core.SolrCore.execute(SolrCore.java:2068)
        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:1631)
        ... 21 more




Build Log:
[...truncated 10719 lines...]
   [junit4] Suite: org.apache.solr.search.TestReloadDeadlock
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J1/temp/solr.search.TestReloadDeadlock_43D7C7E8846AE831-001/init-core-data-001
   [junit4]   2> 529176 INFO  
(SUITE-TestReloadDeadlock-seed#[43D7C7E8846AE831]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 529176 INFO  
(SUITE-TestReloadDeadlock-seed#[43D7C7E8846AE831]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 529177 INFO  
(SUITE-TestReloadDeadlock-seed#[43D7C7E8846AE831]-worker) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 529177 INFO  
(SUITE-TestReloadDeadlock-seed#[43D7C7E8846AE831]-worker) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/.svn/'
 to classloader
   [junit4]   2> 529177 INFO  
(SUITE-TestReloadDeadlock-seed#[43D7C7E8846AE831]-worker) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 529178 INFO  
(SUITE-TestReloadDeadlock-seed#[43D7C7E8846AE831]-worker) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 529214 INFO  
(SUITE-TestReloadDeadlock-seed#[43D7C7E8846AE831]-worker) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 529231 INFO  
(SUITE-TestReloadDeadlock-seed#[43D7C7E8846AE831]-worker) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.4.0
   [junit4]   2> 529259 INFO  
(SUITE-TestReloadDeadlock-seed#[43D7C7E8846AE831]-worker) [    ] o.a.s.c.Config 
Loaded SolrConfig: solrconfig-tlog.xml
   [junit4]   2> 529260 INFO  
(SUITE-TestReloadDeadlock-seed#[43D7C7E8846AE831]-worker) [    ] 
o.a.s.s.IndexSchema Reading Solr Schema from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/schema15.xml
   [junit4]   2> 529266 INFO  
(SUITE-TestReloadDeadlock-seed#[43D7C7E8846AE831]-worker) [    ] 
o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 529504 INFO  
(SUITE-TestReloadDeadlock-seed#[43D7C7E8846AE831]-worker) [    ] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 529506 INFO  
(SUITE-TestReloadDeadlock-seed#[43D7C7E8846AE831]-worker) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 529507 INFO  
(SUITE-TestReloadDeadlock-seed#[43D7C7E8846AE831]-worker) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 529510 INFO  
(SUITE-TestReloadDeadlock-seed#[43D7C7E8846AE831]-worker) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 529521 INFO  
(SUITE-TestReloadDeadlock-seed#[43D7C7E8846AE831]-worker) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 529521 INFO  
(SUITE-TestReloadDeadlock-seed#[43D7C7E8846AE831]-worker) [    ] 
o.a.s.c.SolrResourceLoader using system property solr.solr.home: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr
   [junit4]   2> 529521 INFO  
(SUITE-TestReloadDeadlock-seed#[43D7C7E8846AE831]-worker) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/'
   [junit4]   2> 529545 INFO  
(SUITE-TestReloadDeadlock-seed#[43D7C7E8846AE831]-worker) [    ] 
o.a.s.c.CoreContainer New CoreContainer 1367684093
   [junit4]   2> 529546 INFO  
(SUITE-TestReloadDeadlock-seed#[43D7C7E8846AE831]-worker) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/]
   [junit4]   2> 529546 INFO  
(SUITE-TestReloadDeadlock-seed#[43D7C7E8846AE831]-worker) [    ] 
o.a.s.c.CoreContainer loading shared library: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/lib
   [junit4]   2> 529546 WARN  
(SUITE-TestReloadDeadlock-seed#[43D7C7E8846AE831]-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-5.x/solr/core/src/test-files/solr/lib).
   [junit4]   2> 529556 INFO  
(SUITE-TestReloadDeadlock-seed#[43D7C7E8846AE831]-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> 529558 INFO  
(SUITE-TestReloadDeadlock-seed#[43D7C7E8846AE831]-worker) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 529559 INFO  
(SUITE-TestReloadDeadlock-seed#[43D7C7E8846AE831]-worker) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 529559 INFO  
(SUITE-TestReloadDeadlock-seed#[43D7C7E8846AE831]-worker) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 529559 INFO  
(SUITE-TestReloadDeadlock-seed#[43D7C7E8846AE831]-worker) [    ] 
o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 529559 INFO  
(SUITE-TestReloadDeadlock-seed#[43D7C7E8846AE831]-worker) [    ] 
o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 529560 INFO  
(SUITE-TestReloadDeadlock-seed#[43D7C7E8846AE831]-worker) [    ] 
o.a.s.c.SolrCore Created CoreDescriptor: 
{configSetProperties=configsetprops.json, 
dataDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J1/temp/solr.search.TestReloadDeadlock_43D7C7E8846AE831-001/init-core-data-001,
 loadOnStartup=true, 
absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/,
 shard=shard1, transient=false, collection=collection1, schema=schema15.xml, 
name=collection1, config=solrconfig-tlog.xml, instanceDir=collection1}
   [junit4]   2> 529561 INFO  (coreLoadExecutor-2039-thread-1) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 529562 INFO  (coreLoadExecutor-2039-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/.svn/'
 to classloader
   [junit4]   2> 529563 INFO  (coreLoadExecutor-2039-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 529563 INFO  (coreLoadExecutor-2039-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 529612 INFO  (coreLoadExecutor-2039-thread-1) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 529635 INFO  (coreLoadExecutor-2039-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.4.0
   [junit4]   2> 529664 INFO  (coreLoadExecutor-2039-thread-1) [    ] 
o.a.s.c.Config Loaded SolrConfig: solrconfig-tlog.xml
   [junit4]   2> 529664 INFO  (coreLoadExecutor-2039-thread-1) [    ] 
o.a.s.s.IndexSchema Reading Solr Schema from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/schema15.xml
   [junit4]   2> 529673 INFO  (coreLoadExecutor-2039-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 529919 INFO  (coreLoadExecutor-2039-thread-1) [    ] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 529921 INFO  (coreLoadExecutor-2039-thread-1) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 529922 INFO  (coreLoadExecutor-2039-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 529924 INFO  (coreLoadExecutor-2039-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 529933 INFO  (coreLoadExecutor-2039-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-5.x/solr/core/src/test-files/solr/collection1/conf'
   [junit4]   2> 529934 INFO  (coreLoadExecutor-2039-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/
   [junit4]   2> 529934 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 529934 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/],
 dataDir=[null]
   [junit4]   2> 529934 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4be82e3a
   [junit4]   2> 529935 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J1/temp/solr.search.TestReloadDeadlock_43D7C7E8846AE831-001/init-core-data-001
   [junit4]   2> 529935 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null 
new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J1/temp/solr.search.TestReloadDeadlock_43D7C7E8846AE831-001/init-core-data-001/index/
   [junit4]   2> 529935 WARN  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J1/temp/solr.search.TestReloadDeadlock_43D7C7E8846AE831-001/init-core-data-001/index'
 doesn't exist. Creating new index...
   [junit4]   2> 529935 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J1/temp/solr.search.TestReloadDeadlock_43D7C7E8846AE831-001/init-core-data-001/index
   [junit4]   2> 529936 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=18, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 529936 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3eb11dcd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e1b9de2),segFN=segments_1,generation=1}
   [junit4]   2> 529936 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 529941 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "nodistrib"
   [junit4]   2> 529941 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 529941 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 529942 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 529942 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 529942 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 529942 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 529942 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 529942 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 529945 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 529947 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 529948 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 529949 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 529949 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-5.x/solr/build/solr-core/test/J1/temp/solr.search.TestReloadDeadlock_43D7C7E8846AE831-001/init-core-data-001/
   [junit4]   2> 529953 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers Registered paths: 
/update,/admin/system,/update/csv,/admin/properties,standard,/admin/segments,/replication,/admin/file,/admin/plugins,/update/json,/admin/mbeans,/schema,/get,/admin/ping,/admin/threads,/config,/update/json/docs,/admin/luke,/admin/logging
   [junit4]   2> 529955 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 529955 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 529955 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 529956 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 529956 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 529956 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=6, maxMergeAtOnceExplicit=7, maxMergedSegmentMB=53.986328125, 
floorSegmentMB=1.8056640625, forceMergeDeletesPctAllowed=18.352471163119944, 
segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.82753605065073
   [junit4]   2> 529957 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3eb11dcd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e1b9de2),segFN=segments_1,generation=1}
   [junit4]   2> 529957 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 529957 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@6abb9692[collection1] 
main
   [junit4]   2> 529958 WARN  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 529958 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 529958 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
InMemoryStorage
   [junit4]   2> 529958 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 529958 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 529958 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed 
version buckets
   [junit4]   2> 529959 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 
65536 version buckets from index
   [junit4]   2> 529959 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed 
version bucket highest value from index
   [junit4]   2> 529959 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1509191054468841472
   [junit4]   2> 529961 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Took 3 ms to seed version buckets with highest 
version 1509191054468841472
   [junit4]   2> 529961 INFO  (coreLoadExecutor-2039-thread-1) [    
x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 529962 INFO  
(searcherExecutor-2040-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6abb9692[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 529962 INFO  
(SUITE-TestReloadDeadlock-seed#[43D7C7E8846AE831]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 529966 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testReloadDeadlock
   [junit4]   2> 529967 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    ] 
o.a.s.c.SolrCore [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 529968 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@4b27539[collection1] realtime
   [junit4]   2> 529968 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    ] 
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> 529968 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    ] 
o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 529969 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    ] 
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3eb11dcd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e1b9de2),segFN=segments_1,generation=1}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3eb11dcd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e1b9de2),segFN=segments_2,generation=2}
   [junit4]   2> 529969 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    ] 
o.a.s.c.SolrCore newest commit generation = 2
   [junit4]   2> 529970 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@1f9b6891[collection1] main
   [junit4]   2> 529970 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    ] 
o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 529970 INFO  (searcherExecutor-2040-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1f9b6891[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 529971 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{commit=} 0 2
   [junit4]   2> 529989 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:6 (-1)} 0 13
   [junit4]   2> 529990 INFO  (WRITER2) [    ] 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:0 (-3)} 0 14
   [junit4]   2> 529991 INFO  (WRITER4) [    ] 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:0 (-4)} 0 14
   [junit4]   2> 529991 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:0 (-2)} 0 15
   [junit4]   2> 530013 INFO  (WRITER3) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@16a8966[collection1] realtime
   [junit4]   2> 530013 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-5&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:0 (-5)} 0 35
   [junit4]   2> 530014 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 
(7)]} 0 36
   [junit4]   2> 530014 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 
(6)]} 0 29
   [junit4]   2> 530015 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(8)]} 0 29
   [junit4]   2> 530015 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(10)]} 0 23
   [junit4]   2> 530015 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(12)]} 0 23
   [junit4]   2> 530015 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 
(11)]} 0 23
   [junit4]   2> 530015 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 
(9)]} 0 25
   [junit4]   2> 530016 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 
(13)]} 0 1
   [junit4]   2> 530025 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 
(14)]} 0 11
   [junit4]   2> 530026 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[14 
(15)]} 0 11
   [junit4]   2> 530038 INFO  (WRITER2) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@6035e75d[collection1] realtime
   [junit4]   2> 530039 INFO  (WRITER2) [    ] 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:0 (-17)} 0 23
   [junit4]   2> 530039 INFO  (WRITER7) [    ] o.a.s.u.UpdateHandler Reordered 
DBQs detected.  Update=add{_version_=16,id=13} DBQs=[DBQ{version=17,q=id:0}]
   [junit4]   2> 530048 INFO  (WRITER7) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@7aac62fa[collection1] realtime
   [junit4]   2> 530048 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[13 
(16)]} 0 33
   [junit4]   2> 530048 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(18)]} 0 32
   [junit4]   2> 530049 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(20)]} 0 33
   [junit4]   2> 530049 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 
(19)]} 0 33
   [junit4]   2> 530055 INFO  (WRITER3) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@673e560a[collection1] realtime
   [junit4]   2> 530056 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-21&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:9 (-21)} 0 39
   [junit4]   2> 530057 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 
(22)]} 0 31
   [junit4]   2> 530057 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[8 
(24)]} 0 18
   [junit4]   2> 530058 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 
(23)]} 0 32
   [junit4]   2> 530067 INFO  (WRITER0) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@34d66a18[collection1] realtime
   [junit4]   2> 530068 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[7 
(26)]} 0 18
   [junit4]   2> 530075 INFO  (WRITER4) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@352bea02[collection1] realtime
   [junit4]   2> 530075 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-27&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:12 (-27)} 0 25
   [junit4]   2> 530075 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-25&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:0 (-25)} 0 26
   [junit4]   2> 530076 INFO  (WRITER7) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 530076 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 530077 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/.svn/'
 to classloader
   [junit4]   2> 530077 INFO  (WRITER7) [    ] o.a.s.c.SolrCore 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3eb11dcd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e1b9de2),segFN=segments_2,generation=2}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3eb11dcd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e1b9de2),segFN=segments_3,generation=3}
   [junit4]   2> 530077 INFO  (WRITER7) [    ] o.a.s.c.SolrCore newest commit 
generation = 3
   [junit4]   2> 530077 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 530078 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 530078 INFO  (WRITER7) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@57eeec82[collection1] main
   [junit4]   2> 530082 INFO  (WRITER7) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 530083 INFO  (searcherExecutor-2040-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@57eeec82[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(5.4.0):c4/2:delGen=2)
 Uninverting(_3(5.4.0):c1) Uninverting(_6(5.4.0):c1) Uninverting(_8(5.4.0):c1) 
Uninverting(_9(5.4.0):c1)))}
   [junit4]   2> 530083 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null params={} {commit=} 0 33
   [junit4]   2> 530088 INFO  (WRITER6) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@2ac8100f[collection1] realtime
   [junit4]   2> 530088 INFO  (WRITER6) [    ] 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 31
   [junit4]   2> 530089 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[8 
(30)]} 0 31
   [junit4]   2> 530093 INFO  (WRITER3) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@2f01c641[collection1] realtime
   [junit4]   2> 530094 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-28&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:9 (-28)} 0 36
   [junit4]   2> 530095 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[7 
(32)]} 0 26
   [junit4]   2> 530095 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 36
   [junit4]   2> 530102 INFO  (WRITER4) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@2573e21[collection1] realtime
   [junit4]   2> 530102 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-33&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:15 (-33)} 0 26
   [junit4]   2> 530103 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-34&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:11 (-34)} 0 26
   [junit4]   2> 530103 INFO  (WRITER7) [    ] 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:11 (-35)} 0 15
   [junit4]   2> 530104 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-36&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:2 (-36)} 0 15
   [junit4]   2> 530105 INFO  (WRITER2) [    ] 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:10 (-37)} 0 15
   [junit4]   2> 530105 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[8 
(39)]} 0 10
   [junit4]   2> 530106 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 
(40)]} 0 10
   [junit4]   2> 530109 INFO  (WRITER3) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@3b9a35ac[collection1] realtime
   [junit4]   2> 530109 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-38&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:8 (-38)} 0 13
   [junit4]   2> 530110 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 
(41)]} 0 8
   [junit4]   2> 530113 INFO  (WRITER0) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@25f2caf9[collection1] realtime
   [junit4]   2> 530114 INFO  (WRITER0) [    ] 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:0 (-42)} 0 10
   [junit4]   2> 530114 INFO  (WRITER7) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@40cbfc92[collection1] realtime
   [junit4]   2> 530115 INFO  (WRITER7) [    ] 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:7 (-43)} 0 11
   [junit4]   2> 530116 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 
(44)]} 0 11
   [junit4]   2> 530116 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(45)]} 0 10
   [junit4]   2> 530116 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(46)]} 0 10
   [junit4]   2> 530121 INFO  (WRITER5) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@6f7eec50[collection1] realtime
   [junit4]   2> 530121 INFO  (WRITER3) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 530121 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-47&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:4 (-47)} 0 15
   [junit4]   2> 530123 INFO  (WRITER3) [    ] o.a.s.c.SolrCore 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3eb11dcd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e1b9de2),segFN=segments_3,generation=3}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3eb11dcd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e1b9de2),segFN=segments_4,generation=4}
   [junit4]   2> 530123 INFO  (WRITER3) [    ] o.a.s.c.SolrCore newest commit 
generation = 4
   [junit4]   2> 530123 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[15 
(49)]} 0 8
   [junit4]   2> 530123 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 
(48)]} 0 12
   [junit4]   2> 530128 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 530131 INFO  (WRITER3) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@738fa6d0[collection1] main
   [junit4]   2> 530131 INFO  (WRITER3) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 530132 INFO  (searcherExecutor-2040-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@738fa6d0[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(5.4.0):c4/2:delGen=2)
 Uninverting(_3(5.4.0):c1) Uninverting(_d(5.4.0):c2/1:delGen=1) 
Uninverting(_f(5.4.0):c2) Uninverting(_i(5.4.0):c1) Uninverting(_h(5.4.0):c1)))}
   [junit4]   2> 530132 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null params={} {commit=} 0 22
   [junit4]   2> 530133 INFO  (WRITER7) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@3a87efd0[collection1] realtime
   [junit4]   2> 530134 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-50&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:11 (-50)} 0 18
   [junit4]   2> 530134 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-51&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:4 (-51)} 0 18
   [junit4]   2> 530135 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[15 
(53)]} 0 18
   [junit4]   2> 530135 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 
(52)]} 0 18
   [junit4]   2> 530140 INFO  (WRITER5) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@7499614b[collection1] realtime
   [junit4]   2> 530140 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-54&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:3 (-54)} 0 18
   [junit4]   2> 530141 INFO  (WRITER0) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@124351bb[collection1] realtime
   [junit4]   2> 530141 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-55&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:7 (-55)} 0 17
   [junit4]   2> 530141 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[11 
(56)]} 0 18
   [junit4]   2> 530142 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(57)]} 0 7
   [junit4]   2> 530146 INFO  (WRITER6) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@14e5ae2[collection1] realtime
   [junit4]   2> 530147 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 
(60)]} 0 12
   [junit4]   2> 530148 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[8 
(59)]} 0 12
   [junit4]   2> 530149 INFO  (WRITER6) [    ] 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:10 (-58)} 0 14
   [junit4]   2> 530149 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 
(61)]} 0 12
   [junit4]   2> 530150 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 
(62)]} 0 9
   [junit4]   2> 530150 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(63)]} 0 8
   [junit4]   2> 530150 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 
(64)]} 0 8
   [junit4]   2> 530151 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(65)]} 0 8
   [junit4]   2> 530151 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.4.0
   [junit4]   2> 530156 INFO  (WRITER2) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@66c077a1[collection1] realtime
   [junit4]   2> 530157 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-66&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:2 (-66)} 0 8
   [junit4]   2> 530157 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[8 
(67)]} 0 8
   [junit4]   2> 530164 INFO  (WRITER3) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@5ae9bc2f[collection1] realtime
   [junit4]   2> 530164 INFO  (WRITER6) [    ] o.a.s.u.UpdateHandler Reordered 
DBQs detected.  Update=add{_version_=68,id=8} DBQs=[DBQ{version=69,q=id:1}]
   [junit4]   2> 530165 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-69&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:1 (-69)} 0 15
   [junit4]   2> 530165 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 
(72)]} 0 14
   [junit4]   2> 530172 INFO  (WRITER6) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@cbe217c[collection1] realtime
   [junit4]   2> 530172 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(73)]} 0 21
   [junit4]   2> 530173 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 
(68)]} 0 23
   [junit4]   2> 530173 INFO  (WRITER0) [    ] 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:12 (-71)} 0 22
   [junit4]   2> 530174 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[14 
(70)]} 0 24
   [junit4]   2> 530175 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 
(74)]} 0 18
   [junit4]   2> 530175 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[10 
(76)]} 0 9
   [junit4]   2> 530176 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[8 
(75)]} 0 18
   [junit4]   2> 530208 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    ] 
o.a.s.c.Config Loaded SolrConfig: solrconfig-tlog.xml
   [junit4]   2> 530209 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    ] 
o.a.s.s.IndexSchema Reading Solr Schema from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/schema15.xml
   [junit4]   2> 530216 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 530240 INFO  (WRITER4) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@3bae6f77[collection1] realtime
   [junit4]   2> 530240 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-77&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:8 (-77)} 0 73
   [junit4]   2> 530242 INFO  (WRITER7) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@7230d16a[collection1] realtime
   [junit4]   2> 530242 INFO  (WRITER7) [    ] 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:0 (-78)} 0 69
   [junit4]   2> 530243 INFO  (WRITER6) [    ] 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:0 (-79)} 0 70
   [junit4]   2> 530244 INFO  (WRITER0) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 530245 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[7 
(80)]} 0 70
   [junit4]   2> 530245 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 
(81)]} 0 69
   [junit4]   2> 530246 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[10 
(83)]} 0 69
   [junit4]   2> 530248 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[4 
(82)]} 0 72
   [junit4]   2> 530249 INFO  (WRITER0) [    ] o.a.s.c.SolrCore 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3eb11dcd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e1b9de2),segFN=segments_4,generation=4}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3eb11dcd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e1b9de2),segFN=segments_5,generation=5}
   [junit4]   2> 530250 INFO  (WRITER0) [    ] o.a.s.c.SolrCore newest commit 
generation = 5
   [junit4]   2> 530313 INFO  (WRITER0) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@67cfee89[collection1] main
   [junit4]   2> 530314 INFO  (searcherExecutor-2040-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@67cfee89[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_r(5.4.0):c9/3:delGen=1)
 Uninverting(_u(5.4.0):c1) Uninverting(_x(5.4.0):c1) Uninverting(_z(5.4.0):c1) 
Uninverting(_y(5.4.0):c1) Uninverting(_10(5.4.0):c1)))}
   [junit4]   2> 530315 INFO  (WRITER0) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 530315 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 
(84)]} 0 74
   [junit4]   2> 530315 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null params={} {commit=} 0 141
   [junit4]   2> 530318 INFO  (WRITER7) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@28342326[collection1] realtime
   [junit4]   2> 530319 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-85&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:8 (-85)} 0 76
   [junit4]   2> 530319 INFO  (WRITER6) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@547a9662[collection1] realtime
   [junit4]   2> 530320 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-86&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:11 (-86)} 0 76
   [junit4]   2> 530320 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[7 
(87)]} 0 75
   [junit4]   2> 530321 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[4 
(90)]} 0 72
   [junit4]   2> 530321 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[4 
(92)]} 0 2
   [junit4]   2> 530321 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[7 
(89)]} 0 74
   [junit4]   2> 530321 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[7 
(88)]} 0 75
   [junit4]   2> 530321 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 
(91)]} 0 5
   [junit4]   2> 530329 INFO  (WRITER7) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@744168c4[collection1] realtime
   [junit4]   2> 530330 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[7 
(95)]} 0 9
   [junit4]   2> 530330 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 
(94)]} 0 10
   [junit4]   2> 530330 INFO  (WRITER7) [    ] 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:7 (-93)} 0 11
   [junit4]   2> 530331 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[7 
(97)]} 0 9
   [junit4]   2> 530336 INFO  (WRITER1) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@1e344836[collection1] realtime
   [junit4]   2> 530336 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-98&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:7 (-98)} 0 15
   [junit4]   2> 530337 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-99&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:7 (-99)} 0 15
   [junit4]   2> 530337 INFO  (WRITER3) [    ] o.a.s.u.UpdateHandler Reordered 
DBQs detected.  Update=add{_version_=96,id=7} DBQs=[DBQ{version=99,q=id:7}, 
DBQ{version=98,q=id:7}]
   [junit4]   2> 530343 INFO  (WRITER3) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@45bfc898[collection1] realtime
   [junit4]   2> 530344 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[7 
(96)]} 0 22
   [junit4]   2> 530344 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 
(100)]} 0 22
   [junit4]   2> 530344 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(102)]} 0 13
   [junit4]   2> 530344 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 
(101)]} 0 13
   [junit4]   2> 530351 INFO  (WRITER7) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@4216073f[collection1] realtime
   [junit4]   2> 530352 INFO  (WRITER0) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 530352 INFO  (WRITER7) [    ] 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:1 (-103)} 0 20
   [junit4]   2> 530353 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[4 
(105)]} 0 15
   [junit4]   2> 530353 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[7 
(104)]} 0 16
   [junit4]   2> 530354 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[7 
(106)]} 0 10
   [junit4]   2> 530360 INFO  (WRITER0) [    ] o.a.s.c.SolrCore 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3eb11dcd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e1b9de2),segFN=segments_5,generation=5}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3eb11dcd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e1b9de2),segFN=segments_6,generation=6}
   [junit4]   2> 530361 INFO  (WRITER0) [    ] o.a.s.c.SolrCore newest commit 
generation = 6
   [junit4]   2> 530367 INFO  (WRITER0) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@1d7603a7[collection1] main
   [junit4]   2> 530367 INFO  (WRITER0) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 530368 INFO  (searcherExecutor-2040-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1d7603a7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_12(5.4.0):c11/3:delGen=1)
 Uninverting(_15(5.4.0):c2/1:delGen=1) Uninverting(_19(5.4.0):c1) 
Uninverting(_1c(5.4.0):c1) Uninverting(_1b(5.4.0):c1)))}
   [junit4]   2> 530369 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null params={} {commit=} 0 36
   [junit4]   2> 530370 INFO  (WRITER4) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@6c06f162[collection1] realtime
   [junit4]   2> 530371 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-107&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:7 (-107)} 0 26
   [junit4]   2> 530371 INFO  (WRITER6) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@53af1add[collection1] realtime
   [junit4]   2> 530372 INFO  (WRITER6) [    ] 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:0 (-108)} 0 27
   [junit4]   2> 530372 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 
(109)]} 0 27
   [junit4]   2> 530373 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(110)]} 0 20
   [junit4]   2> 530426 INFO  (WRITER1) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@7bc5e60c[collection1] realtime
   [junit4]   2> 530427 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-112&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:4 (-112)} 0 73
   [junit4]   2> 530427 INFO  (WRITER0) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 530427 INFO  (WRITER2) [    ] o.a.s.u.UpdateHandler Reordered 
DBQs detected.  Update=add{_version_=111,id=4} DBQs=[DBQ{version=112,q=id:4}]
   [junit4]   2> 530431 INFO  (WRITER0) [    ] o.a.s.c.SolrCore 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3eb11dcd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e1b9de2),segFN=segments_6,generation=6}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3eb11dcd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e1b9de2),segFN=segments_7,generation=7}
   [junit4]   2> 530431 INFO  (WRITER0) [    ] o.a.s.c.SolrCore newest commit 
generation = 7
   [junit4]   2> 530433 INFO  (WRITER2) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@52298efb[collection1] realtime
   [junit4]   2> 530434 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(113)]} 0 79
   [junit4]   2> 530434 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[4 
(111)]} 0 80
   [junit4]   2> 530436 INFO  (WRITER0) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@5ea1a720[collection1] main
   [junit4]   2> 530437 INFO  (WRITER0) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 530437 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(116)]} 0 64
   [junit4]   2> 530437 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(114)]} 0 66
   [junit4]   2> 530437 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 
(115)]} 0 64
   [junit4]   2> 530437 INFO  (searcherExecutor-2040-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5ea1a720[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_12(5.4.0):c11/4:delGen=2)
 Uninverting(_15(5.4.0):c2/1:delGen=1) Uninverting(_1d(5.4.0):c2/1:delGen=1) 
Uninverting(_1g(5.4.0):c1)))}
   [junit4]   2> 530438 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null params={} {commit=} 0 67
   [junit4]   2> 530439 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-117&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:8 (-117)} 0 65
   [junit4]   2> 530440 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-118&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:4 (-118)} 0 12
   [junit4]   2> 530440 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 
(119)]} 0 6
   [junit4]   2> 530454 INFO  (WRITER5) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@a14d437[collection1] realtime
   [junit4]   2> 530454 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-121&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:6 (-121)} 0 16
   [junit4]   2> 530455 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 
(122)]} 0 17
   [junit4]   2> 530455 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 
(123)]} 0 17
   [junit4]   2> 530455 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 
(120)]} 0 20
   [junit4]   2> 530456 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(125)]} 0 16
   [junit4]   2> 530456 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[10 
(124)]} 0 17
   [junit4]   2> 530460 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    ] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 530462 INFO  (WRITER1) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@9a10a6d[collection1] realtime
   [junit4]   2> 530462 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 530462 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-126&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:0 (-126)} 0 22
   [junit4]   2> 530463 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 530463 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 
(128)]} 0 9
   [junit4]   2> 530463 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[3 
(127)]} 0 13
   [junit4]   2> 530466 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 530472 INFO  (WRITER6) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@3e06f62d[collection1] realtime
   [junit4]   2> 530472 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-129&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:8 (-129)} 0 17
   [junit4]   2> 530473 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 
(130)]} 0 17
   [junit4]   2> 530476 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    ] 
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-5.x/solr/core/src/test-files/solr/collection1/conf'
   [junit4]   2> 530476 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    ] 
o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from 
instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/
   [junit4]   2> 530476 INFO  (WRITER2) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@1fa88631[collection1] realtime
   [junit4]   2> 530476 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J1/temp/solr.search.TestReloadDeadlock_43D7C7E8846AE831-001/init-core-data-001/]
   [junit4]   2> 530476 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4be82e3a
   [junit4]   2> 530476 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-131&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:5 (-131)} 0 21
   [junit4]   2> 530477 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-132&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:5 (-132)} 0 21
   [junit4]   2> 530477 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null 
new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J1/temp/solr.search.TestReloadDeadlock_43D7C7E8846AE831-001/init-core-data-001/index/
   [junit4]   2> 530478 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[10 
(133)]} 0 21
   [junit4]   2> 530478 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-5.x/solr/build/solr-core/test/J1/temp/solr.search.TestReloadDeadlock_43D7C7E8846AE831-001/init-core-data-001/
   [junit4]   2> 530483 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "nodistrib"
   [junit4]   2> 530483 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 530483 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 530483 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 530484 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 530484 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 530484 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 530484 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 530484 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 530487 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 530489 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 530490 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 530491 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 530497 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.c.RequestHandlers Registered paths: 
/update,/admin/system,/update/csv,/admin/properties,standard,/admin/segments,/replication,/admin/file,/admin/plugins,/update/json,/admin/mbeans,/schema,/get,/admin/ping,/admin/threads,/config,/update/json/docs,/admin/luke,/admin/logging
   [junit4]   2> 530498 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 530498 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 530499 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 530531 INFO  (WRITER1) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@7ee10224[collection1] realtime
   [junit4]   2> 530531 INFO  (WRITER5) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 530531 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-134&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:8 (-134)} 0 68
   [junit4]   2> 530532 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@4f750679[collection1] 
main
   [junit4]   2> 530532 INFO  (WRITER5) [    ] o.a.s.c.SolrCore 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3eb11dcd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e1b9de2),segFN=segments_7,generation=7}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3eb11dcd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e1b9de2),segFN=segments_8,generation=8}
   [junit4]   2> 530532 INFO  (WRITER5) [    ] o.a.s.c.SolrCore newest commit 
generation = 8
   [junit4]   2> 530532 WARN  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 530533 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 530533 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
InMemoryStorage
   [junit4]   2> 530533 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 530533 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 530534 INFO  
(searcherExecutor-2045-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4f750679[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1m(5.4.0):c9/2:delGen=1)
 Uninverting(_1n(5.4.0):c1) Uninverting(_1o(5.4.0):c1)))}
   [junit4]   2> 530534 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed 
version buckets
   [junit4]   2> 530536 INFO  (WRITER5) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@491594e[collection1] main
   [junit4]   2> 530536 INFO  (WRITER5) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 530536 INFO  (searcherExecutor-2040-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@491594e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1m(5.4.0):c9/3:delGen=1)
 Uninverting(_1n(5.4.0):c1) Uninverting(_1o(5.4.0):c1) 
Uninverting(_1p(5.4.0):c1)))}
   [junit4]   2> 530537 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 
(135)]} 0 72
   [junit4]   2> 530537 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null params={} {commit=} 0 73
   [junit4]   2> 530538 INFO  (WRITER6) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@2b7a1bdc[collection1] realtime
   [junit4]   2> 530538 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:14 (-136)} 0 65
   [junit4]   2> 530539 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[10 
(137)]} 0 65
   [junit4]   2> 530540 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(138)]} 0 62
   [junit4]   2> 530540 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(139)]} 0 62
   [junit4]   2> 530545 INFO  (WRITER0) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@2d96fd6c[collection1] realtime
   [junit4]   2> 530545 INFO  (WRITER0) [    ] 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:5 (-140)} 0 67
   [junit4]   2> 530546 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[8 
(141)]} 0 14
   [junit4]   2> 530546 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 
65536 version buckets from index
   [junit4]   2> 530548 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.u.VersionInfo Found MAX value 133 from Terms for _version_ 
in index
   [junit4]   2> 530550 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.u.UpdateLog Took 4 ms to seed version buckets with highest 
version 141
   [junit4]   2> 530550 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.u.DefaultSolrCoreState Creating new IndexWriter...
   [junit4]   2> 530550 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.u.DefaultSolrCoreState Waiting until IndexWriter is 
unused... core=collection1
   [junit4]   2> 530550 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.u.DefaultSolrCoreState Closing old IndexWriter... 
core=collection1
   [junit4]   2> 530553 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3eb11dcd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e1b9de2),segFN=segments_8,generation=8}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3eb11dcd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e1b9de2),segFN=segments_9,generation=9}
   [junit4]   2> 530553 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 9
   [junit4]   2> 530554 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=23, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7355819685894223]
   [junit4]   2> 530556 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3eb11dcd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e1b9de2),segFN=segments_9,generation=9}
   [junit4]   2> 530556 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 9
   [junit4]   2> 530557 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 530560 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@226779ab[collection1] 
main
   [junit4]   2> 530560 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[43D7C7E8846AE831]) [    
x:collection1] o.a.s.c.CoreContainer replacing core: collection1
   [junit4]   2> 530561 INFO  
(searcherExecutor-2045-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@226779ab[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1m(5.4.0):c9/3:delGen=2)
 Uninverting(_1n(5.4.0):c1) Uninverting(_1r(5.4.0):c1) 
Uninverting(_1s(5.4.0):c1)))}
   [junit4]   2> 530565 INFO  (WRITER6) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@417a8945[collection1] realtime
   [junit4]   2> 530565 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[8 
(142)]} 0 28
   [junit4]   2> 530567 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[13 
(144)]} 0 27
   [junit4]   2> 530567 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(145)]} 0 27
   [junit4]   2> 530573 INFO  (WRITER7) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@71ca2db3[collection1] realtime
   [junit4]   2> 530573 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-146&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:8 (-146)} 0 33
   [junit4]   2> 530573 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-143&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:2 (-143)} 0 34
   [junit4]   2> 530574 INFO  (WRITER0) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 530575 INFO  (WRITER0) [    ] o.a.s.c.SolrCore 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3eb11dcd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e1b9de2),segFN=segments_9,generation=9}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3eb11dcd 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e1b9de2),segFN=segments_a,generation=10}
   [junit4]   2> 530575 INFO  (WRITER0) [    ] o.a.s.c.SolrCore newest commit 
generation = 10
   [junit4]   2> 530575 INFO  (WRITER0) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@2cafa4cc[collection1] main
   [junit4]   2> 530576 INFO  (WRITER0) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 530576 INFO  (searcherExecutor-2040-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2cafa4cc[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1m(5.4.0):c9/5:delGen=3)
 Uninverting(_1n(5.4.0):c1) Uninverting(_1r(5.4.0):c1) 
Uninverting(_1u(5.4.0):c1) Uninverting(_1v(5.4.0):c1)))}
   [junit4]   2> 530577 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null params={} {commit=} 0 31
   [junit4]   2> 530577 INFO  (WRITER0) [    ] o.a.s.c.SolrCore [collection1]  
CLOSING SolrCore org.apache.solr.core.SolrCore@74b2a94f
   [junit4]   2> 530580 INFO  (WRITER1) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@10b8958e[collection1] realtime
   [junit4]   2> 530580 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:9 (-148)} 0 33
   [junit4]   2> 530581 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[13 
(150)]} 0 13
   [junit4]   2> 530581 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[8 
(152)]} 0 7
   [junit4]   2> 530582 INFO  (WRITER0) [    ] o.a.s.u.UpdateHandler closing 
DirectUpdateHandler2{commits=8,autocommits=0,soft 
autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=1,adds=1,deletesById=0,deletesByQuery=1,errors=1,cumulative_adds=88,cumulative_deletesById=0,cumulative_deletesByQuery=54,cumulative_errors=1,transaction_logs_total_size=2582,transaction_logs_total_number=5}
   [junit4]   2> 530582 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(147)]} 0 40
   [junit4]   2> 530583 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 
(154)]} 0 2
   [junit4]   2> 530583 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 
(153)]} 0 9
   [junit4]   2> 530583 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[12 
(149)]} 0 16
   [junit4]   2> 530583 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[3 
(151)]} 0 15
   [junit4]   2> 530582 INFO  (WRITER0) [    ] o.a.s.c.SolrCore [collection1] 
Closing main searcher on request.
   [junit4]   2> 530593 INFO  (WRITER4) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@12bcb8df[collection1] realtime
   [junit4]   2> 530593 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-155&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:13 (-155)} 0 11
   [junit4]   2> 530595 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] web

[...truncated too long message...]

ient.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1085)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799)
   [junit4]    >        at 
org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
   [junit4]    >        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:894)Throwable
 #4: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an 
uncaught exception in thread: Thread[id=8284, name=collection0, state=RUNNABLE, 
group=TGRP-CollectionsAPIDistributedZkTest]
   [junit4]    > Caused by: 
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error 
from server at http://127.0.0.1:37696/m: Could not find collection : 
awholynewstresscollection_collection0_1
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([43D7C7E8846AE831]:0)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1085)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799)
   [junit4]    >        at 
org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
   [junit4]    >        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:894)Throwable
 #5: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an 
uncaught exception in thread: Thread[id=8289, name=collection5, state=RUNNABLE, 
group=TGRP-CollectionsAPIDistributedZkTest]
   [junit4]    > Caused by: 
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error 
from server at http://127.0.0.1:37696/m: Could not find collection : 
awholynewstresscollection_collection5_1
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([43D7C7E8846AE831]:0)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1085)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799)
   [junit4]    >        at 
org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
   [junit4]    >        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:894)Throwable
 #6: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an 
uncaught exception in thread: Thread[id=8288, name=collection4, state=RUNNABLE, 
group=TGRP-CollectionsAPIDistributedZkTest]
   [junit4]    > Caused by: 
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error 
from server at http://127.0.0.1:37696/m: Could not find collection : 
awholynewstresscollection_collection4_1
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([43D7C7E8846AE831]:0)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1085)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799)
   [junit4]    >        at 
org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
   [junit4]    >        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:894)
   [junit4]   2> 1541559 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[43D7C7E8846AE831]-worker) 
[n:127.0.0.1:37696_m c:addReplicaColl s:shard2 r:core_node3 
x:addReplicaColl_shard2_replica1] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: test params are: 
codec=DummyCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=DUMMY,
 chunkSize=2, maxDocsPerChunk=106, blockSize=3), 
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=DUMMY, 
chunkSize=2, blockSize=3)), sim=DefaultSimilarity, locale=el_GR, 
timezone=Antarctica/Casey
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 
1.7.0_72 (64-bit)/cpus=4,threads=1,free=156328080,total=506462208
   [junit4]   2> NOTE: All tests run in this JVM: [TestPivotHelperCode, 
WordBreakSolrSpellCheckerTest, FacetPivotSmallTest, 
PKIAuthenticationIntegrationTest, TestSearchPerf, 
TestCopyFieldCollectionResource, StatelessScriptUpdateProcessorFactoryTest, 
TestExpandComponent, CloudExitableDirectoryReaderTest, JsonLoaderTest, 
AtomicUpdatesTest, ChaosMonkeySafeLeaderTest, TestFastWriter, 
TestCoreContainer, TestManagedResource, LukeRequestHandlerTest, CopyFieldTest, 
ConvertedLegacyTest, DistributedMLTComponentTest, TestConfigOverlay, 
UpdateParamsTest, TestMissingGroups, TestRTimerTree, TestNoOpRegenerator, 
HLLUtilTest, SSLMigrationTest, TestConfigReload, ShardRoutingTest, 
TestCoreDiscovery, AssignTest, TestMaxScoreQueryParser, 
BigEndianAscendingWordDeserializerTest, DistributedTermsComponentTest, 
SuggesterTSTTest, ZkSolrClientTest, TestPKIAuthenticationPlugin, 
BasicAuthIntegrationTest, RuleEngineTest, SliceStateTest, 
TestAddFieldRealTimeGet, DistributedFacetPivotSmallTest, 
TestImplicitCoreProperties, TestSolrCoreProperties, TestReplicaProperties, 
SpellPossibilityIteratorTest, AlternateDirectoryTest, SystemInfoHandlerTest, 
TestAuthenticationFramework, TestLMDirichletSimilarityFactory, TestJsonFacets, 
ZkCLITest, DistanceUnitsTest, DistributedFacetPivotWhiteBoxTest, 
DirectSolrConnectionTest, TestFuzzyAnalyzedSuggestions, 
TestManagedSynonymFilterFactory, TestSolrDeletionPolicy2, 
TestManagedStopFilterFactory, TestFiltering, ResourceLoaderTest, 
TestJsonRequest, TestReloadDeadlock, TestStressVersions, ZkStateReaderTest, 
TestCrossCoreJoin, OpenCloseCoreStressTest, TestSolrConfigHandler, 
TestDistributedGrouping, RequiredFieldsTest, ClusterStateUpdateTest, 
TestManagedResourceStorage, TestCustomSort, SaslZkACLProviderTest, 
OutOfBoxZkACLAndCredentialsProvidersTest, DocValuesMultiTest, 
IndexSchemaRuntimeFieldTest, TestMacros, CollectionsAPIDistributedZkTest]
   [junit4] Completed [221/533] on J1 in 297.01s, 1 test, 1 error <<< FAILURES!

[...truncated 996 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:733: 
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:670: 
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:59: 
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build.xml:230:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/common-build.xml:524:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/lucene/common-build.xml:1449:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/lucene/common-build.xml:1003:
 There were test failures: 533 suites, 2110 tests, 2 errors, 26 ignored (14 
assumptions)

Total time: 174 minutes 20 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Sending artifact delta relative to Lucene-Solr-NightlyTests-5.x #911
Archived 4 artifacts
Archive block size is 32768
Received 0 blocks and 1197246708 bytes
Compression is 0.0%
Took 3 min 18 sec
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure


---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to