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

3 tests failed.
REGRESSION:  
org.apache.solr.handler.TestReplicationHandler.doTestStressReplication

Error Message:
timed out waiting for collection1 startAt time to exceed: Tue Aug 18 18:09:01 
MEST 2015

Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to 
exceed: Tue Aug 18 18:09:01 MEST 2015
        at 
__randomizedtesting.SeedInfo.seed([E5F76943B73D63E3:3E5C6985B2150A50]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at 
org.apache.solr.handler.TestReplicationHandler.watchCoreStartAt(TestReplicationHandler.java:1417)
        at 
org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:769)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:606)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
        at java.lang.Thread.run(Thread.java:745)


FAILED:  org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test

Error Message:
Captured an uncaught exception in thread: Thread[id=18660, name=collection5, 
state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=18660, name=collection5, state=RUNNABLE, 
group=TGRP-HdfsCollectionsAPIDistributedZkTest]
Caused by: 
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error 
from server at http://127.0.0.1:40450: collection already exists: 
awholynewstresscollection_collection5_0
        at __randomizedtesting.SeedInfo.seed([E5F76943B73D63E3]:0)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226)
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372)
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:857)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:800)
        at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
        at 
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1574)
        at 
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1595)
        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:888)


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

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

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=707, name=WRITER6, state=RUNNABLE, 
group=TGRP-TestReloadDeadlock]
Caused by: java.lang.RuntimeException: org.apache.solr.common.SolrException: 
Error opening new searcher
        at __randomizedtesting.SeedInfo.seed([E5F76943B73D63E3]:0)
        at 
org.apache.solr.search.TestReloadDeadlock$1.run(TestReloadDeadlock.java:166)
Caused by: org.apache.solr.common.SolrException: Error opening new searcher
        at org.apache.solr.core.SolrCore.openNewSearcher(SolrCore.java:1673)
        at org.apache.solr.core.SolrCore.getRealtimeSearcher(SolrCore.java:1530)
        at 
org.apache.solr.update.VersionInfo.getVersionFromIndex(VersionInfo.java:201)
        at org.apache.solr.update.UpdateLog.lookupVersion(UpdateLog.java:779)
        at 
org.apache.solr.update.VersionInfo.lookupVersion(VersionInfo.java:194)
        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:1088)
        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:705)
        at 
org.apache.solr.update.processor.LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:104)
        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.handleAdds(JsonLoader.java:470)
        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.processUpdate(JsonLoader.java:134)
        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.load(JsonLoader.java:113)
        at org.apache.solr.handler.loader.JsonLoader.load(JsonLoader.java:76)
        at 
org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:98)
        at 
org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:74)
        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:151)
        at org.apache.solr.core.SolrCore.execute(SolrCore.java:2079)
        at 
org.apache.solr.servlet.DirectSolrConnection.request(DirectSolrConnection.java:131)
        at org.apache.solr.SolrTestCaseJ4.updateJ(SolrTestCaseJ4.java:1104)
        at 
org.apache.solr.SolrTestCaseJ4.addAndGetVersion(SolrTestCaseJ4.java:1250)
        at 
org.apache.solr.search.TestReloadDeadlock.addDoc(TestReloadDeadlock.java:200)
        at 
org.apache.solr.search.TestReloadDeadlock.access$100(TestReloadDeadlock.java:46)
        at 
org.apache.solr.search.TestReloadDeadlock$1.run(TestReloadDeadlock.java:156)
Caused by: java.lang.NullPointerException
        at org.apache.solr.core.SolrCore.openNewSearcher(SolrCore.java:1642)
        ... 21 more




Build Log:
[...truncated 10088 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_E5F76943B73D63E3-001/init-core-data-001
   [junit4]   2> 109784 INFO  
(SUITE-TestReloadDeadlock-seed#[E5F76943B73D63E3]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 109785 INFO  
(SUITE-TestReloadDeadlock-seed#[E5F76943B73D63E3]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 109785 INFO  
(SUITE-TestReloadDeadlock-seed#[E5F76943B73D63E3]-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> 109785 INFO  
(SUITE-TestReloadDeadlock-seed#[E5F76943B73D63E3]-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> 109786 INFO  
(SUITE-TestReloadDeadlock-seed#[E5F76943B73D63E3]-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> 109786 INFO  
(SUITE-TestReloadDeadlock-seed#[E5F76943B73D63E3]-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> 109824 INFO  
(SUITE-TestReloadDeadlock-seed#[E5F76943B73D63E3]-worker) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 109842 INFO  
(SUITE-TestReloadDeadlock-seed#[E5F76943B73D63E3]-worker) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.4.0
   [junit4]   2> 109880 INFO  
(SUITE-TestReloadDeadlock-seed#[E5F76943B73D63E3]-worker) [    ] o.a.s.c.Config 
Loaded SolrConfig: solrconfig-tlog.xml
   [junit4]   2> 109880 INFO  
(SUITE-TestReloadDeadlock-seed#[E5F76943B73D63E3]-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> 109900 INFO  
(SUITE-TestReloadDeadlock-seed#[E5F76943B73D63E3]-worker) [    ] 
o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 110175 INFO  
(SUITE-TestReloadDeadlock-seed#[E5F76943B73D63E3]-worker) [    ] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 110177 INFO  
(SUITE-TestReloadDeadlock-seed#[E5F76943B73D63E3]-worker) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 110179 INFO  
(SUITE-TestReloadDeadlock-seed#[E5F76943B73D63E3]-worker) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 110182 INFO  
(SUITE-TestReloadDeadlock-seed#[E5F76943B73D63E3]-worker) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 110206 INFO  
(SUITE-TestReloadDeadlock-seed#[E5F76943B73D63E3]-worker) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 110206 INFO  
(SUITE-TestReloadDeadlock-seed#[E5F76943B73D63E3]-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> 110207 INFO  
(SUITE-TestReloadDeadlock-seed#[E5F76943B73D63E3]-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> 110220 INFO  
(SUITE-TestReloadDeadlock-seed#[E5F76943B73D63E3]-worker) [    ] 
o.a.s.c.CoreContainer New CoreContainer 416217510
   [junit4]   2> 110220 INFO  
(SUITE-TestReloadDeadlock-seed#[E5F76943B73D63E3]-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> 110220 INFO  
(SUITE-TestReloadDeadlock-seed#[E5F76943B73D63E3]-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> 110221 WARN  
(SUITE-TestReloadDeadlock-seed#[E5F76943B73D63E3]-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> 110240 INFO  
(SUITE-TestReloadDeadlock-seed#[E5F76943B73D63E3]-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> 110242 INFO  
(SUITE-TestReloadDeadlock-seed#[E5F76943B73D63E3]-worker) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 110243 INFO  
(SUITE-TestReloadDeadlock-seed#[E5F76943B73D63E3]-worker) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 110243 INFO  
(SUITE-TestReloadDeadlock-seed#[E5F76943B73D63E3]-worker) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 110243 INFO  
(SUITE-TestReloadDeadlock-seed#[E5F76943B73D63E3]-worker) [    ] 
o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 110243 INFO  
(SUITE-TestReloadDeadlock-seed#[E5F76943B73D63E3]-worker) [    ] 
o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 110244 INFO  
(SUITE-TestReloadDeadlock-seed#[E5F76943B73D63E3]-worker) [    ] 
o.a.s.c.SolrCore Created CoreDescriptor: 
{dataDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J1/temp/solr.search.TestReloadDeadlock_E5F76943B73D63E3-001/init-core-data-001,
 shard=shard1, instanceDir=collection1, transient=false, schema=schema15.xml, 
loadOnStartup=true, configSetProperties=configsetprops.json, 
absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/,
 collection=collection1, name=collection1, config=solrconfig-tlog.xml}
   [junit4]   2> 110252 INFO  (coreLoadExecutor-197-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> 110253 INFO  (coreLoadExecutor-197-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> 110253 INFO  (coreLoadExecutor-197-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> 110254 INFO  (coreLoadExecutor-197-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> 110281 INFO  (coreLoadExecutor-197-thread-1) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 110295 INFO  (coreLoadExecutor-197-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.4.0
   [junit4]   2> 110342 INFO  (coreLoadExecutor-197-thread-1) [    ] 
o.a.s.c.Config Loaded SolrConfig: solrconfig-tlog.xml
   [junit4]   2> 110343 INFO  (coreLoadExecutor-197-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> 110365 INFO  (coreLoadExecutor-197-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 110649 INFO  (coreLoadExecutor-197-thread-1) [    ] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 110651 INFO  (coreLoadExecutor-197-thread-1) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 110653 INFO  (coreLoadExecutor-197-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 110661 INFO  (coreLoadExecutor-197-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 110706 INFO  (coreLoadExecutor-197-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> 110707 INFO  (coreLoadExecutor-197-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> 110707 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 110707 INFO  (coreLoadExecutor-197-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> 110708 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@e3553f9
   [junit4]   2> 110709 INFO  (coreLoadExecutor-197-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_E5F76943B73D63E3-001/init-core-data-001
   [junit4]   2> 110709 INFO  (coreLoadExecutor-197-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_E5F76943B73D63E3-001/init-core-data-001/index/
   [junit4]   2> 110709 WARN  (coreLoadExecutor-197-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_E5F76943B73D63E3-001/init-core-data-001/index'
 doesn't exist. Creating new index...
   [junit4]   2> 110709 INFO  (coreLoadExecutor-197-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_E5F76943B73D63E3-001/init-core-data-001/index
   [junit4]   2> 110710 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=45, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=40.779296875, 
floorSegmentMB=0.470703125, forceMergeDeletesPctAllowed=7.536259304488297, 
segmentsPerTier=43.0, maxCFSSegmentSizeMB=1.44140625, noCFSRatio=0.0
   [junit4]   2> 110712 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5f5c9a08 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3884b421),segFN=segments_1,generation=1}
   [junit4]   2> 110713 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 110715 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_E5F76943B73D63E3-001/init-core-data-001/
   [junit4]   2> 110721 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "nodistrib"
   [junit4]   2> 110722 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 110722 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 110722 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 110723 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 110723 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 110723 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 110723 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 110724 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 110727 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 110728 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 110730 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 110731 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 110737 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers Registered paths: 
/update,/admin/threads,/schema,/get,/admin/system,/admin/luke,/admin/file,/update/json/docs,/update/csv,/admin/properties,/admin/mbeans,standard,/admin/logging,/replication,/update/json,/admin/segments,/admin/plugins,/admin/ping,/config
   [junit4]   2> 110739 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 110740 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 110740 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 110741 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 110741 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 110741 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.MockRandomMergePolicy: 
org.apache.lucene.index.MockRandomMergePolicy@4377b92e
   [junit4]   2> 110742 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5f5c9a08 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3884b421),segFN=segments_1,generation=1}
   [junit4]   2> 110743 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 110744 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@26ca372a[collection1] 
main
   [junit4]   2> 110744 WARN  (coreLoadExecutor-197-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> 110744 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 110744 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
InMemoryStorage
   [junit4]   2> 110745 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 110745 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 110746 INFO  
(searcherExecutor-198-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@26ca372a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 110746 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed 
version buckets
   [junit4]   2> 110746 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 
65536 version buckets from index
   [junit4]   2> 110746 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed 
version bucket highest value from index
   [junit4]   2> 110747 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1509856283694465024
   [junit4]   2> 110749 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with 
highest version 1509856283694465024
   [junit4]   2> 110749 INFO  (coreLoadExecutor-197-thread-1) [    
x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 110751 INFO  
(SUITE-TestReloadDeadlock-seed#[E5F76943B73D63E3]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 110757 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testReloadDeadlock
   [junit4]   2> 110764 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    ] 
o.a.s.c.SolrCore [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 110772 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@26079d81[collection1] realtime
   [junit4]   2> 110773 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    ] 
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 14
   [junit4]   2> 110774 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    ] 
o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 110774 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    ] 
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5f5c9a08 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3884b421),segFN=segments_1,generation=1}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5f5c9a08 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3884b421),segFN=segments_2,generation=2}
   [junit4]   2> 110775 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    ] 
o.a.s.c.SolrCore newest commit generation = 2
   [junit4]   2> 110775 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@6b1974f6[collection1] main
   [junit4]   2> 110776 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    ] 
o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 110776 INFO  (searcherExecutor-198-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6b1974f6[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 110777 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{commit=} 0 3
   [junit4]   2> 110787 INFO  (WRITER1) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 110802 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[12 
(1)]} 0 17
   [junit4]   2> 110804 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[10 
(3)]} 0 16
   [junit4]   2> 110804 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[19 
(2)]} 0 17
   [junit4]   2> 110820 INFO  (WRITER1) [    ] o.a.s.c.SolrCore 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5f5c9a08 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3884b421),segFN=segments_2,generation=2}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5f5c9a08 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3884b421),segFN=segments_3,generation=3}
   [junit4]   2> 110820 INFO  (WRITER1) [    ] o.a.s.c.SolrCore newest commit 
generation = 3
   [junit4]   2> 110826 INFO  (WRITER1) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@44b22f93[collection1] main
   [junit4]   2> 110828 INFO  (searcherExecutor-198-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@44b22f93[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.4.0):C1)
 Uninverting(_1(5.4.0):C1) Uninverting(_2(5.4.0):C1)))}
   [junit4]   2> 110830 INFO  (WRITER1) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 110833 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null params={} {commit=} 0 43
   [junit4]   2> 110878 INFO  (WRITER4) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@4946346[collection1] realtime
   [junit4]   2> 110879 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:10 (-4)} 0 90
   [junit4]   2> 110880 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[10 
(6)]} 0 77
   [junit4]   2> 110880 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 
(7)]} 0 77
   [junit4]   2> 110881 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[1 
(5)]} 0 93
   [junit4]   2> 110896 INFO  (WRITER3) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@416d55c[collection1] realtime
   [junit4]   2> 110896 INFO  (WRITER1) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 110898 INFO  (WRITER2) [    ] o.a.s.u.UpdateHandler Reordered 
DBQs detected.  Update=add{_version_=8,id=10} DBQs=[DBQ{version=9,q=id:20}]
   [junit4]   2> 110909 INFO  (WRITER1) [    ] o.a.s.c.SolrCore 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5f5c9a08 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3884b421),segFN=segments_3,generation=3}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5f5c9a08 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3884b421),segFN=segments_4,generation=4}
   [junit4]   2> 110910 INFO  (WRITER1) [    ] o.a.s.c.SolrCore newest commit 
generation = 4
   [junit4]   2> 110955 INFO  (WRITER2) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@176f61bb[collection1] realtime
   [junit4]   2> 110958 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-9&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:20 (-9)} 0 152
   [junit4]   2> 110958 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[18 
(13)]} 0 76
   [junit4]   2> 110960 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[10 
(8)]} 0 154
   [junit4]   2> 110961 INFO  (WRITER1) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@32aa204d[collection1] main
   [junit4]   2> 110961 INFO  (WRITER1) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 110962 INFO  (searcherExecutor-198-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@32aa204d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.4.0):C1)
 Uninverting(_1(5.4.0):C1) Uninverting(_4(5.4.0):C1) Uninverting(_7(5.4.0):C1) 
Uninverting(_6(5.4.0):C1) Uninverting(_5(5.4.0):C1)))}
   [junit4]   2> 110964 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[18 
(15)]} 0 0
   [junit4]   2> 110964 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null params={} {commit=} 0 121
   [junit4]   2> 110967 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 
(12)]} 0 86
   [junit4]   2> 110967 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[15 
(14)]} 0 8
   [junit4]   2> 110967 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 
(10)]} 0 87
   [junit4]   2> 110968 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[21 
(19)]} 0 0
   [junit4]   2> 110968 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[10 
(11)]} 0 87
   [junit4]   2> 110968 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 
(18)]} 0 1
   [junit4]   2> 110969 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 
(21)]} 0 0
   [junit4]   2> 110969 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[21 
(22)]} 0 0
   [junit4]   2> 110968 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[15 
(20)]} 0 0
   [junit4]   2> 110969 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[10 
(17)]} 0 4
   [junit4]   2> 110974 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[10 
(16)]} 0 13
   [junit4]   2> 110982 INFO  (WRITER1) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@50527344[collection1] realtime
   [junit4]   2> 110982 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-24&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:5 (-24)} 0 13
   [junit4]   2> 110983 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[20 
(25)]} 0 14
   [junit4]   2> 110984 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[20 
(26)]} 0 14
   [junit4]   2> 110999 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    ] 
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> 111000 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    ] 
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> 111000 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    ] 
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> 111001 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    ] 
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> 111050 INFO  (WRITER6) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@166f4e85[collection1] realtime
   [junit4]   2> 111051 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-23&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:21 (-23)} 0 81
   [junit4]   2> 111052 INFO  (WRITER5) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 111055 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 
(28)]} 0 78
   [junit4]   2> 111057 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 
(27)]} 0 87
   [junit4]   2> 111057 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 
(29)]} 0 74
   [junit4]   2> 111059 INFO  (WRITER5) [    ] o.a.s.c.SolrCore 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5f5c9a08 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3884b421),segFN=segments_4,generation=4}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5f5c9a08 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3884b421),segFN=segments_5,generation=5}
   [junit4]   2> 111060 INFO  (WRITER5) [    ] o.a.s.c.SolrCore newest commit 
generation = 5
   [junit4]   2> 111063 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 111086 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.4.0
   [junit4]   2> 111124 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    ] 
o.a.s.c.Config Loaded SolrConfig: solrconfig-tlog.xml
   [junit4]   2> 111125 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    ] 
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> 111132 INFO  (WRITER5) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@4eaf12e2[collection1] main
   [junit4]   2> 111135 INFO  (searcherExecutor-198-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4eaf12e2[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_8(5.4.0):c6/2:delGen=1)
 Uninverting(_c(5.4.0):C4/1) Uninverting(_e(5.4.0):C1) 
Uninverting(_f(5.4.0):C1) Uninverting(_d(5.4.0):C1)))}
   [junit4]   2> 111139 INFO  (WRITER5) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 111145 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null params={} {commit=} 0 175
   [junit4]   2> 111166 INFO  (WRITER4) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@1644beec[collection1] realtime
   [junit4]   2> 111167 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-30&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:15 (-30)} 0 182
   [junit4]   2> 111168 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 
(31)]} 0 182
   [junit4]   2> 111170 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 111177 INFO  (WRITER6) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@130ff04b[collection1] realtime
   [junit4]   2> 111178 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-32&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:21 (-32)} 0 126
   [junit4]   2> 111186 INFO  (WRITER3) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@7c8ce38d[collection1] realtime
   [junit4]   2> 111189 INFO  (WRITER1) [    ] 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:6 (-35)} 0 130
   [junit4]   2> 111189 INFO  (WRITER2) [    ] o.a.s.u.UpdateHandler Reordered 
DBQs detected.  Update=add{_version_=33,id=4} DBQs=[DBQ{version=35,q=id:6}, 
DBQ{version=34,q=id:11}]
   [junit4]   2> 111190 INFO  (WRITER3) [    ] 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 133
   [junit4]   2> 111208 INFO  (WRITER2) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@47fb8997[collection1] realtime
   [junit4]   2> 111209 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 
(36)]} 0 46
   [junit4]   2> 111217 INFO  (WRITER4) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@67b12136[collection1] realtime
   [junit4]   2> 111219 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 
(33)]} 0 161
   [junit4]   2> 111222 INFO  (WRITER0) [    ] 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:4 (-38)} 0 52
   [junit4]   2> 111224 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-39&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:4 (-39)} 0 40
   [junit4]   2> 111226 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-40&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:6 (-40)} 0 35
   [junit4]   2> 111226 INFO  (WRITER3) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 111235 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[6 
(43)]} 0 2
   [junit4]   2> 111234 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 
(42)]} 0 14
   [junit4]   2> 111234 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 
(45)]} 0 7
   [junit4]   2> 111233 INFO  (WRITER4) [    ] 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:4 (-37)} 0 64
   [junit4]   2> 111232 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 
(41)]} 0 22
   [junit4]   2> 111236 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[4 
(44)]} 0 3
   [junit4]   2> 111249 INFO  (WRITER3) [    ] o.a.s.c.SolrCore 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5f5c9a08 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3884b421),segFN=segments_5,generation=5}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5f5c9a08 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3884b421),segFN=segments_6,generation=6}
   [junit4]   2> 111249 INFO  (WRITER3) [    ] o.a.s.c.SolrCore newest commit 
generation = 6
   [junit4]   2> 111264 INFO  (WRITER3) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@cab39ef[collection1] main
   [junit4]   2> 111265 INFO  (WRITER3) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 111271 INFO  (searcherExecutor-198-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@cab39ef[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(5.4.0):c4/2:delGen=1)
 Uninverting(_c(5.4.0):C4/2:delGen=1) Uninverting(_d(5.4.0):C1) 
Uninverting(_g(5.4.0):C1) Uninverting(_k(5.4.0):C3/1:delGen=1) 
Uninverting(_j(5.4.0):C2)))}
   [junit4]   2> 111272 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null params={} {commit=} 0 81
   [junit4]   2> 111279 INFO  (WRITER0) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@3f9362f0[collection1] realtime
   [junit4]   2> 111281 INFO  (WRITER2) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@35335489[collection1] realtime
   [junit4]   2> 111282 INFO  (WRITER2) [    ] 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 45
   [junit4]   2> 111284 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-48&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:4 (-48)} 0 45
   [junit4]   2> 111285 INFO  (WRITER5) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@724bb435[collection1] realtime
   [junit4]   2> 111286 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-46&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:6 (-46)} 0 48
   [junit4]   2> 111286 INFO  (WRITER5) [    ] 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:3 (-50)} 0 47
   [junit4]   2> 111286 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-49&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:4 (-49)} 0 47
   [junit4]   2> 111287 INFO  (WRITER3) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 111289 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 
(51)]} 0 49
   [junit4]   2> 111290 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[6 
(54)]} 0 4
   [junit4]   2> 111291 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[4 
(53)]} 0 6
   [junit4]   2> 111291 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[13 
(52)]} 0 5
   [junit4]   2> 111299 INFO  (WRITER3) [    ] o.a.s.c.SolrCore 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5f5c9a08 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3884b421),segFN=segments_6,generation=6}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5f5c9a08 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3884b421),segFN=segments_7,generation=7}
   [junit4]   2> 111300 INFO  (WRITER3) [    ] o.a.s.c.SolrCore newest commit 
generation = 7
   [junit4]   2> 111314 INFO  (WRITER3) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@59ada21c[collection1] main
   [junit4]   2> 111316 INFO  (searcherExecutor-198-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@59ada21c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_l(5.4.0):c6)
 Uninverting(_j(5.4.0):C2/1:delGen=1) Uninverting(_m(5.4.0):C1) 
Uninverting(_p(5.4.0):C1) Uninverting(_n(5.4.0):C1) Uninverting(_o(5.4.0):C1)))}
   [junit4]   2> 111318 INFO  (WRITER3) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 111318 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null params={} {commit=} 0 41
   [junit4]   2> 111320 INFO  (WRITER5) [    ] 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:3 (-55)} 0 33
   [junit4]   2> 111322 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 
(56)]} 0 34
   [junit4]   2> 111322 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[4 
(57)]} 0 33
   [junit4]   2> 111329 INFO  (WRITER0) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@5293c8a6[collection1] realtime
   [junit4]   2> 111332 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 
(59)]} 0 40
   [junit4]   2> 111332 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[13 
(60)]} 0 40
   [junit4]   2> 111336 INFO  (WRITER0) [    ] 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:4 (-58)} 0 45
   [junit4]   2> 111344 INFO  (WRITER3) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@79ef3e2b[collection1] realtime
   [junit4]   2> 111346 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 24
   [junit4]   2> 111346 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 
(63)]} 0 24
   [junit4]   2> 111347 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[4 
(64)]} 0 24
   [junit4]   2> 111348 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 
(66)]} 0 15
   [junit4]   2> 111348 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[4 
(65)]} 0 14
   [junit4]   2> 111349 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[2 
(67)]} 0 9
   [junit4]   2> 111359 INFO  (WRITER6) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@6f4809f6[collection1] realtime
   [junit4]   2> 111360 INFO  (WRITER6) [    ] 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:3 (-69)} 0 12
   [junit4]   2> 111363 INFO  (WRITER2) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@7a6b136f[collection1] realtime
   [junit4]   2> 111365 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 
(72)]} 0 16
   [junit4]   2> 111366 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[2 
(73)]} 0 16
   [junit4]   2> 111382 INFO  (WRITER4) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@4b9681f7[collection1] realtime
   [junit4]   2> 111383 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-70&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:4 (-70)} 0 34
   [junit4]   2> 111387 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-61&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:13 (-61)} 0 67
   [junit4]   2> 111387 INFO  (WRITER5) [    ] o.a.s.u.UpdateHandler Reordered 
DBQs detected.  Update=add{_version_=68,id=21} DBQs=[DBQ{version=71,q=id:4}, 
DBQ{version=70,q=id:4}, DBQ{version=69,q=id:3}]
   [junit4]   2> 111398 INFO  (WRITER5) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@15c5816b[collection1] realtime
   [junit4]   2> 111400 INFO  (WRITER4) [    ] 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:4 (-71)} 0 49
   [junit4]   2> 111401 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[21 
(68)]} 0 48
   [junit4]   2> 111404 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-74&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:3 (-74)} 0 43
   [junit4]   2> 111405 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[18 
(75)]} 0 39
   [junit4]   2> 111405 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[18 
(76)]} 0 38
   [junit4]   2> 111406 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[16 
(77)]} 0 23
   [junit4]   2> 111408 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 
(79)]} 0 6
   [junit4]   2> 111409 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[18 
(78)]} 0 17
   [junit4]   2> 111410 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 
(82)]} 0 3
   [junit4]   2> 111411 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[3 
(81)]} 0 3
   [junit4]   2> 111411 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[12 
(83)]} 0 5
   [junit4]   2> 111411 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 
(84)]} 0 3
   [junit4]   2> 111412 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[21 
(80)]} 0 10
   [junit4]   2> 111412 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[17 
(85)]} 0 2
   [junit4]   2> 111413 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[18 
(86)]} 0 3
   [junit4]   2> 111413 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 
(87)]} 0 2
   [junit4]   2> 111414 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 
(89)]} 0 2
   [junit4]   2> 111430 INFO  (WRITER2) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@64c78d51[collection1] realtime
   [junit4]   2> 111432 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[9 
(88)]} 0 19
   [junit4]   2> 111433 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[20 
(92)]} 0 20
   [junit4]   2> 111434 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[18 
(93)]} 0 20
   [junit4]   2> 111448 INFO  (WRITER5) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@4154ca4[collection1] realtime
   [junit4]   2> 111449 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-91&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:20 (-91)} 0 35
   [junit4]   2> 111450 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-90&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:12 (-90)} 0 37
   [junit4]   2> 111451 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[14 
(94)]} 0 37
   [junit4]   2> 111452 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[3 
(95)]} 0 37
   [junit4]   2> 111479 INFO  (WRITER6) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@2c5737e0[collection1] realtime
   [junit4]   2> 111480 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-96&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:11 (-96)} 0 47
   [junit4]   2> 111481 INFO  (WRITER2) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 111482 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 
(97)]} 0 48
   [junit4]   2> 111485 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[11 
(98)]} 0 50
   [junit4]   2> 111508 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[11 
(99)]} 0 58
   [junit4]   2> 111508 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[11 
(100)]} 0 56
   [junit4]   2> 111515 INFO  (WRITER2) [    ] o.a.s.c.SolrCore 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5f5c9a08 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3884b421),segFN=segments_7,generation=7}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5f5c9a08 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3884b421),segFN=segments_8,generation=8}
   [junit4]   2> 111515 INFO  (WRITER2) [    ] o.a.s.c.SolrCore newest commit 
generation = 8
   [junit4]   2> 111523 INFO  (WRITER2) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@1aabdbb3[collection1] main
   [junit4]   2> 111528 INFO  (searcherExecutor-198-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1aabdbb3[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_10(5.4.0):c15/3)
 Uninverting(_v(5.4.0):C1) Uninverting(_z(5.4.0):C2) Uninverting(_11(5.4.0):C1) 
Uninverting(_12(5.4.0):C1) Uninverting(_13(5.4.0):C1) 
Uninverting(_15(5.4.0):C1)))}
   [junit4]   2> 111535 INFO  (WRITER2) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 111535 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null params={} {commit=} 0 85
   [junit4]   2> 111539 INFO  (WRITER0) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@6acec49d[collection1] realtime
   [junit4]   2> 111539 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-101&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:3 (-101)} 0 87
   [junit4]   2> 111541 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 
(102)]} 0 58
   [junit4]   2> 111550 INFO  (WRITER4) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@18d4b89e[collection1] realtime
   [junit4]   2> 111553 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-104&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:16 (-104)} 0 67
   [junit4]   2> 111554 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-103&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:16 (-103)} 0 71
   [junit4]   2> 111554 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[16 
(105)]} 0 45
   [junit4]   2> 111554 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[16 
(106)]} 0 45
   [junit4]   2> 111566 INFO  (WRITER2) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@18e16126[collection1] realtime
   [junit4]   2> 111568 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 
(109)]} 0 27
   [junit4]   2> 111569 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 
(110)]} 0 15
   [junit4]   2> 111570 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[13 
(108)]} 0 29
   [junit4]   2> 111575 INFO  (WRITER2) [    ] 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:16 (-107)} 0 36
   [junit4]   2> 111581 INFO  (WRITER4) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@727dc077[collection1] realtime
   [junit4]   2> 111584 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-113&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:8 (-113)} 0 27
   [junit4]   2> 111586 INFO  (WRITER5) [    ] 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:16 (-112)} 0 30
   [junit4]   2> 111588 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[19 
(114)]} 0 19
   [junit4]   2> 111613 INFO  (WRITER3) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@1b2256ec[collection1] realtime
   [junit4]   2> 111613 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-115&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:9 (-115)} 0 43
   [junit4]   2> 111616 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-116&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:9 (-116)} 0 44
   [junit4]   2> 111619 INFO  (WRITER2) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@38a1c177[collection1] realtime
   [junit4]   2> 111622 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:8 (-118)} 0 37
   [junit4]   2> 111622 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-111&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:8 (-111)} 0 68
   [junit4]   2> 111624 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 
(120)]} 0 35
   [junit4]   2> 111624 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 
(119)]} 0 35
   [junit4]   2> 111625 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[16 
(122)]} 0 9
   [junit4]   2> 111625 INFO  (WRITER2) [    ] 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:17 (-117)} 0 50
   [junit4]   2> 111644 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    ] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 111646 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 111653 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 111657 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 111664 INFO  (WRITER4) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@2f07f387[collection1] realtime
   [junit4]   2> 111665 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-124&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:6 (-124)} 0 42
   [junit4]   2> 111674 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[21 
(125)]} 0 50
   [junit4]   2> 111675 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[21 
(126)]} 0 50
   [junit4]   2> 111676 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 
(127)]} 0 50
   [junit4]   2> 111685 INFO  (WRITER1) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@442fec83[collection1] realtime
   [junit4]   2> 111701 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 
(128)]} 0 74
   [junit4]   2> 111708 INFO  (WRITER3) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@3f8c1384[collection1] realtime
   [junit4]   2> 111709 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-123&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:6 (-123)} 0 82
   [junit4]   2> 111710 INFO  (WRITER3) [    ] 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:13 (-121)} 0 82
   [junit4]   2> 111711 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[6 
(129)]} 0 45
   [junit4]   2> 111719 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    ] 
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> 111719 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    ] 
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> 111720 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
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_E5F76943B73D63E3-001/init-core-data-001/]
   [junit4]   2> 111720 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@e3553f9
   [junit4]   2> 111732 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
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_E5F76943B73D63E3-001/init-core-data-001/index/
   [junit4]   2> 111739 INFO  (WRITER6) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@7aabd543[collection1] realtime
   [junit4]   2> 111740 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-130&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:6 (-130)} 0 65
   [junit4]   2> 111742 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[18 
(131)]} 0 64
   [junit4]   2> 111752 INFO  (WRITER0) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@7b87d6c5[collection1] realtime
   [junit4]   2> 111760 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 
(133)]} 0 58
   [junit4]   2> 111764 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "nodistrib"
   [junit4]   2> 111767 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 111767 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 111767 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 111768 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 111768 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 111768 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 111769 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 111769 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 111769 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_E5F76943B73D63E3-001/init-core-data-001/
   [junit4]   2> 111775 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 111788 INFO  (WRITER1) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@182a483f[collection1] realtime
   [junit4]   2> 111789 INFO  (WRITER0) [    ] 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:18 (-132)} 0 111
   [junit4]   2> 111789 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:7 (-134)} 0 79
   [junit4]   2> 111791 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 111793 INFO  (WRITER4) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@4f85ac01[collection1] realtime
   [junit4]   2> 111793 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 111795 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 111803 INFO  (WRITER4) [    ] 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:8 (-136)} 0 92
   [junit4]   2> 111803 INFO  (WRITER3) [    ] o.a.s.u.UpdateHandler Reordered 
DBQs detected.  Update=add{_version_=135,id=8} DBQs=[DBQ{version=136,q=id:8}]
   [junit4]   2> 111824 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
x:collection1] o.a.s.c.RequestHandlers Registered paths: 
/update,/admin/threads,/schema,/get,/admin/system,/admin/luke,/admin/file,/update/json/docs,/update/csv,/admin/properties,/admin/mbeans,standard,/admin/logging,/replication,/update/json,/admin/segments,/admin/plugins,/admin/ping,/config
   [junit4]   2> 111826 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 111831 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 111835 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 111936 INFO  (WRITER3) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@794dca09[collection1] realtime
   [junit4]   2> 111937 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[6 
(137)]} 0 196
   [junit4]   2> 111938 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[20 
(139)]} 0 173
   [junit4]   2> 111938 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 
(138)]} 0 195
   [junit4]   2> 111945 INFO  (WRITER0) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@2c2569a1[collection1] realtime
   [junit4]   2> 111946 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 
(135)]} 0 231
   [junit4]   2> 111946 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@fd6b1d6[collection1] 
main
   [junit4]   2> 111946 WARN  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
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> 111952 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 111952 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
InMemoryStorage
   [junit4]   2> 111953 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 111953 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 
(142)]} 0 149
   [junit4]   2> 111949 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 
(141)]} 0 159
   [junit4]   2> 111949 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[18 
(143)]} 0 10
   [junit4]   2> 111948 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[17 
(144)]} 0 9
   [junit4]   2> 111953 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 111952 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:11 (-140)} 0 163
   [junit4]   2> 111956 INFO  
(searcherExecutor-203-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@fd6b1d6[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1i(5.4.0):c11/3)
 Uninverting(_v(5.4.0):C1) Uninverting(_1a(5.4.0):C1) 
Uninverting(_1l(5.4.0):C1) Uninverting(_1m(5.4.0):C1)))}
   [junit4]   2> 111956 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed 
version buckets
   [junit4]   2> 112016 INFO  (WRITER5) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@2dec8e27[collection1] realtime
   [junit4]   2> 112018 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[18 
(146)]} 0 72
   [junit4]   2> 112019 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-145&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:17 (-145)} 0 80
   [junit4]   2> 112029 INFO  (WRITER4) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@29859ec3[collection1] realtime
   [junit4]   2> 112031 INFO  (WRITER6) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 112032 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[9 
(148)]} 0 78
   [junit4]   2> 112035 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-147&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:0 (-147)} 0 82
   [junit4]   2> 112038 INFO  (WRITER6) [    ] o.a.s.c.SolrCore 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5f5c9a08 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3884b421),segFN=segments_8,generation=8}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5f5c9a08 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3884b421),segFN=segments_9,generation=9}
   [junit4]   2> 112039 INFO  (WRITER6) [    ] o.a.s.c.SolrCore newest commit 
generation = 9
   [junit4]   2> 112040 INFO  (WRITER6) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@d9e5aba[collection1] main
   [junit4]   2> 112042 INFO  (searcherExecutor-198-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@d9e5aba[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1i(5.4.0):c11/3:delGen=1)
 Uninverting(_v(5.4.0):C1) Uninverting(_1a(5.4.0):C1) 
Uninverting(_1l(5.4.0):C1) Uninverting(_1m(5.4.0):C1) 
Uninverting(_1n(5.4.0):C2/1:delGen=1) Uninverting(_1q(5.4.0):C1) 
Uninverting(_1r(5.4.0):C1)))}
   [junit4]   2> 112044 INFO  (WRITER6) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 112047 INFO  (WRITER2) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@33bcfb09[collection1] realtime
   [junit4]   2> 112049 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[16 
(150)]} 0 93
   [junit4]   2> 112049 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-149&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:18 (-149)} 0 94
   [junit4]   2> 112050 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null params={} {commit=} 0 95
   [junit4]   2> 112058 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 
65536 version buckets from index
   [junit4]   2> 112071 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
x:collection1] o.a.s.u.VersionInfo Found MAX value 139 from Terms for _version_ 
in index
   [junit4]   2> 112074 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
x:collection1] o.a.s.u.UpdateLog Took 25.0ms to seed version buckets with 
highest version 150
   [junit4]   2> 112079 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[18 
(151)]} 0 60
   [junit4]   2> 112080 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[3 
(153)]} 0 47
   [junit4]   2> 112081 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[18 
(152)]} 0 61
   [junit4]   2> 112082 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63E3]) [    
x:collection1] o.a.s.u.DefaultSolrCoreState Creating new IndexWriter...
   [junit4]   2> 112082 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[E5F76943B73D63

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

ter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
   [junit4]   2>        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> Caused by: java.lang.NullPointerException
   [junit4]   2>        at 
org.apache.hadoop.hdfs.server.blockmanagement.BlocksMap.size(BlocksMap.java:198)
   [junit4]   2>        at 
org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.getTotalBlocks(BlockManager.java:3291)
   [junit4]   2>        at 
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:6223)
   [junit4]   2>        ... 53 more
   [junit4]   2> 5263822 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E5F76943B73D63E3]-worker) 
[n:127.0.0.1:40450_ c:addReplicaColl s:shard1 r:core_node2 
x:addReplicaColl_shard1_replica1] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> Aug 18, 2015 12:45:13 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 2 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene53): 
{range_facet_l_dv=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 
id=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 timestamp=PostingsFormat(name=LuceneVarGapFixedInterval), 
_version_=PostingsFormat(name=LuceneVarGapFixedInterval), 
other_tl1=PostingsFormat(name=LuceneVarGapFixedInterval), 
range_facet_i_dv=PostingsFormat(name=LuceneVarGapFixedInterval), 
multiDefault=PostingsFormat(name=Direct), 
text=PostingsFormat(name=LuceneFixedGap), 
range_facet_l=PostingsFormat(name=LuceneVarGapFixedInterval), 
a_i1=PostingsFormat(name=LuceneVarGapFixedInterval), 
a_t=PostingsFormat(name=LuceneVarGapFixedInterval), 
intDefault=PostingsFormat(name=LuceneVarGapFixedInterval)}, 
docValues:{range_facet_l_dv=DocValuesFormat(name=Memory), 
timestamp=DocValuesFormat(name=Lucene50), 
range_facet_i_dv=DocValuesFormat(name=Lucene50)}, sim=DefaultSimilarity, 
locale=de_AT, timezone=Africa/Dakar
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 
1.7.0_72 (64-bit)/cpus=4,threads=2,free=116604016,total=534773760
   [junit4]   2> NOTE: All tests run in this JVM: [TestDistributedMissingSort, 
ConnectionManagerTest, TestAuthorizationFramework, SaslZkACLProviderTest, 
TestMaxScoreQueryParser, TestSearchPerf, DirectSolrConnectionTest, 
TestReloadDeadlock, TestNoOpRegenerator, SpellCheckComponentTest, 
DisMaxRequestHandlerTest, HdfsThreadLeakTest, TestElisionMultitermQuery, 
TestHashPartitioner, BasicAuthIntegrationTest, TestManagedSynonymFilterFactory, 
AlternateDirectoryTest, TestImplicitCoreProperties, CursorPagingTest, 
TestClassNameShortening, TestSuggestSpellingConverter, SolrCmdDistributorTest, 
NoCacheHeaderTest, AssignTest, TestSearcherReuse, 
TestCursorMarkWithoutUniqueKey, TestFreeTextSuggestions, JsonLoaderTest, 
TestFieldCollectionResource, TestHighFrequencyDictionaryFactory, 
TestLMDirichletSimilarityFactory, DistanceFunctionTest, 
TestManagedSchemaDynamicFieldResource, RAMDirectoryFactoryTest, TestCustomSort, 
PreAnalyzedFieldTest, TestManagedResource, TestFastLRUCache, ZkStateReaderTest, 
TestCollapseQParserPlugin, TestJsonRequest, TestJsonFacets, 
DistributedTermsComponentTest, TestMacros, CloudExitableDirectoryReaderTest, 
FieldAnalysisRequestHandlerTest, TestCoreDiscovery, 
ExitableDirectoryReaderTest, DistributedMLTComponentTest, OverseerRolesTest, 
TestSolrConfigHandler, AtomicUpdatesTest, SSLMigrationTest, BadComponentTest, 
TestTolerantSearch, TestDistributedGrouping, DistanceUnitsTest, 
TestDefaultStatsCache, TestWriterPerf, TestIndexSearcher, TestLRUStatsCache, 
RuleEngineTest, TestConfigSetProperties, JSONWriterTest, CopyFieldTest, 
SpatialFilterTest, FileUtilsTest, CleanupOldIndexTest, CacheHeaderTest, 
TestNonDefinedSimilarityFactory, ChaosMonkeySafeLeaderTest, 
TestManagedResourceStorage, DistributedFacetPivotSmallTest, SliceStateTest, 
TestConfigOverlay, DataDrivenBlockJoinTest, TestExpandComponent, 
OutOfBoxZkACLAndCredentialsProvidersTest, RequiredFieldsTest, ReturnFieldsTest, 
ZkSolrClientTest, TestConfigReload, TestFileDictionaryLookup, 
AnalyticsQueryTest, IndexSchemaRuntimeFieldTest, UpdateParamsTest, 
TestMissingGroups, BasicDistributedZk2Test, CollectionsAPIDistributedZkTest, 
SyncSliceTest, OpenCloseCoreStressTest, OverseerTest, ShardRoutingTest, 
ClusterStateUpdateTest, LeaderElectionTest, TestRandomDVFaceting, 
AutoCommitTest, TestRecovery, ZkControllerTest, TestRealTimeGet, TestReload, 
TestStressVersions, HardAutoCommitTest, TestCoreContainer, SimpleFacetsTest, 
TestSolr4Spatial, SolrCoreTest, StatsComponentTest, 
QueryElevationComponentTest, BadIndexSchemaTest, ConvertedLegacyTest, 
TestFiltering, TestFunctionQuery, BasicFunctionalityTest, TestLazyCores, 
TestBadConfig, SoftAutoCommitTest, ShowFileRequestHandlerTest, 
CurrencyFieldOpenExchangeTest, CurrencyFieldXmlFileTest, SimplePostToolTest, 
AnalysisAfterCoreReloadTest, TestExtendedDismaxParser, CoreAdminHandlerTest, 
SolrRequestParserTest, TestFoldingMultitermQuery, DocValuesTest, 
SuggesterTSTTest, SuggesterTest, TestStressLucene, SuggesterWFSTTest, 
TestCSVLoader, PolyFieldTest, WordBreakSolrSpellCheckerTest, 
SolrCoreCheckLockOnStartupTest, TestPseudoReturnFields, TestUpdate, 
FieldMutatingUpdateProcessorTest, TestAtomicUpdateErrorCases, 
TestWordDelimiterFilterFactory, StatelessScriptUpdateProcessorFactoryTest, 
SortByFunctionTest, DocValuesMultiTest, TestSolrDeletionPolicy1, 
SolrInfoMBeanTest, XsltUpdateRequestHandlerTest, LukeRequestHandlerTest, 
StandardRequestHandlerTest, DocumentAnalysisRequestHandlerTest, 
TestOmitPositions, DocumentBuilderTest, TestValueSourceCache, 
PathHierarchyTokenizerFactoryTest, TestIndexingPerformance, 
MoreLikeThisHandlerTest, TestArbitraryIndexDir, LoggingHandlerTest, 
RegexBoostProcessorTest, SolrPluginUtilsTest, IndexSchemaTest, 
TestJmxIntegration, TestCollationField, UpdateRequestProcessorFactoryTest, 
MBeansHandlerTest, TestAnalyzedSuggestions, 
UniqFieldsUpdateProcessorFactoryTest, BinaryUpdateRequestHandlerTest, 
TestPHPSerializedResponseWriter, PingRequestHandlerTest, TestComponentsName, 
TestLFUCache, ScriptEngineTest, URLClassifyProcessorTest, TestSystemIdResolver, 
DOMUtilTest, TestLRUCache, ZkNodePropsTest, UUIDFieldTest, SolrTestCaseJ4Test, 
TestEmbeddedSolrServerConstructors, ActionThrottleTest, 
AsyncCallRequestStatusResponseTest, CollectionReloadTest, 
DeleteLastCustomShardedReplicaTest, DistribDocExpirationUpdateProcessorTest, 
DistributedQueueTest, HttpPartitionTest, SolrXmlInZkTest, 
TestAuthenticationFramework, TestCollectionAPI, TestRandomRequestDistribution, 
TestRebalanceLeaders, TestRequestStatusCollectionAPI, 
HdfsBasicDistributedZk2Test, HdfsCollectionsAPIDistributedZkTest]
   [junit4] Completed [533/533] on J1 in 2006.57s, 1 test, 1 error <<< FAILURES!

[...truncated 13 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:792: 
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:729: 
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:233:
 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, 2114 tests, 2 errors, 1 failure, 26 
ignored (14 assumptions)

Total time: 279 minutes 31 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 1064833316 bytes
Compression is 0.0%
Took 3 min 10 sec
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure


---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to