Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/760/

5 tests failed.
REGRESSION:  org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test

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

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=13681, name=collection4, state=RUNNABLE, 
group=TGRP-CollectionsAPIDistributedZkTest]
Caused by: 
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error 
from server at http://127.0.0.1:57135: collection already exists: 
awholynewstresscollection_collection4_0
        at __randomizedtesting.SeedInfo.seed([B3F3D5A91B433718]: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:  junit.framework.TestSuite.org.apache.solr.cloud.HttpPartitionTest

Error Message:
ObjectTracker found 1 object(s) that were not released!!! [TransactionLog]

Stack Trace:
java.lang.AssertionError: ObjectTracker found 1 object(s) that were not 
released!!! [TransactionLog]
        at __randomizedtesting.SeedInfo.seed([B3F3D5A91B433718]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at org.junit.Assert.assertNull(Assert.java:551)
        at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:236)
        at sun.reflect.GeneratedMethodAccessor82.invoke(Unknown Source)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:497)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:799)
        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.search.TestReloadDeadlock.testReloadDeadlock

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

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


FAILED:  org.apache.solr.cloud.CdcrReplicationDistributedZkTest.doTests

Error Message:
There are still nodes recoverying - waited for 330 seconds

Stack Trace:
java.lang.AssertionError: There are still nodes recoverying - waited for 330 
seconds
        at 
__randomizedtesting.SeedInfo.seed([B3F3D5A91B433718:BB93A085144D1F13]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at 
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:172)
        at 
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:133)
        at 
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:128)
        at 
org.apache.solr.cloud.BaseCdcrDistributedZkTest.waitForRecoveriesToFinish(BaseCdcrDistributedZkTest.java:465)
        at 
org.apache.solr.cloud.BaseCdcrDistributedZkTest.clearTargetCollection(BaseCdcrDistributedZkTest.java:343)
        at 
org.apache.solr.cloud.CdcrReplicationDistributedZkTest.doTestTargetCollectionNotAvailable(CdcrReplicationDistributedZkTest.java:112)
        at 
org.apache.solr.cloud.CdcrReplicationDistributedZkTest.doTests(CdcrReplicationDistributedZkTest.java:48)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:497)
        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 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:963)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938)
        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=67036, name=collection5, 
state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=67036, 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:58148: Could not find collection : 
awholynewstresscollection_collection5_0
        at __randomizedtesting.SeedInfo.seed([B3F3D5A91B433718]:0)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226)
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372)
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:857)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:800)
        at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:895)




Build Log:
[...truncated 10476 lines...]
   [junit4] Suite: org.apache.solr.search.TestReloadDeadlock
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.search.TestReloadDeadlock_B3F3D5A91B433718-001/init-core-data-001
   [junit4]   2> 1123151 INFO  
(SUITE-TestReloadDeadlock-seed#[B3F3D5A91B433718]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 1123151 INFO  
(SUITE-TestReloadDeadlock-seed#[B3F3D5A91B433718]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1123151 INFO  
(SUITE-TestReloadDeadlock-seed#[B3F3D5A91B433718]-worker) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 1123152 INFO  
(SUITE-TestReloadDeadlock-seed#[B3F3D5A91B433718]-worker) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/.svn/'
 to classloader
   [junit4]   2> 1123152 INFO  
(SUITE-TestReloadDeadlock-seed#[B3F3D5A91B433718]-worker) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 1123152 INFO  
(SUITE-TestReloadDeadlock-seed#[B3F3D5A91B433718]-worker) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 1123195 INFO  
(SUITE-TestReloadDeadlock-seed#[B3F3D5A91B433718]-worker) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1123198 INFO  
(SUITE-TestReloadDeadlock-seed#[B3F3D5A91B433718]-worker) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 1123218 INFO  
(SUITE-TestReloadDeadlock-seed#[B3F3D5A91B433718]-worker) [    ] o.a.s.c.Config 
Loaded SolrConfig: solrconfig-tlog.xml
   [junit4]   2> 1123220 INFO  
(SUITE-TestReloadDeadlock-seed#[B3F3D5A91B433718]-worker) [    ] 
o.a.s.s.IndexSchema Reading Solr Schema from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema15.xml
   [junit4]   2> 1123228 INFO  
(SUITE-TestReloadDeadlock-seed#[B3F3D5A91B433718]-worker) [    ] 
o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 1123472 INFO  
(SUITE-TestReloadDeadlock-seed#[B3F3D5A91B433718]-worker) [    ] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1123474 INFO  
(SUITE-TestReloadDeadlock-seed#[B3F3D5A91B433718]-worker) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1123475 INFO  
(SUITE-TestReloadDeadlock-seed#[B3F3D5A91B433718]-worker) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1123480 INFO  
(SUITE-TestReloadDeadlock-seed#[B3F3D5A91B433718]-worker) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1123494 INFO  
(SUITE-TestReloadDeadlock-seed#[B3F3D5A91B433718]-worker) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1123494 INFO  
(SUITE-TestReloadDeadlock-seed#[B3F3D5A91B433718]-worker) [    ] 
o.a.s.c.SolrResourceLoader using system property solr.solr.home: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr
   [junit4]   2> 1123494 INFO  
(SUITE-TestReloadDeadlock-seed#[B3F3D5A91B433718]-worker) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/'
   [junit4]   2> 1123521 INFO  
(SUITE-TestReloadDeadlock-seed#[B3F3D5A91B433718]-worker) [    ] 
o.a.s.c.CoreContainer New CoreContainer 1344787227
   [junit4]   2> 1123521 INFO  
(SUITE-TestReloadDeadlock-seed#[B3F3D5A91B433718]-worker) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/]
   [junit4]   2> 1123521 INFO  
(SUITE-TestReloadDeadlock-seed#[B3F3D5A91B433718]-worker) [    ] 
o.a.s.c.CoreContainer loading shared library: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/lib
   [junit4]   2> 1123521 WARN  
(SUITE-TestReloadDeadlock-seed#[B3F3D5A91B433718]-worker) [    ] 
o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to 
classloader: lib (resolved as: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/lib).
   [junit4]   2> 1123536 INFO  
(SUITE-TestReloadDeadlock-seed#[B3F3D5A91B433718]-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> 1123539 INFO  
(SUITE-TestReloadDeadlock-seed#[B3F3D5A91B433718]-worker) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 1123540 INFO  
(SUITE-TestReloadDeadlock-seed#[B3F3D5A91B433718]-worker) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1123540 INFO  
(SUITE-TestReloadDeadlock-seed#[B3F3D5A91B433718]-worker) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1123541 INFO  
(SUITE-TestReloadDeadlock-seed#[B3F3D5A91B433718]-worker) [    ] 
o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 1123541 INFO  
(SUITE-TestReloadDeadlock-seed#[B3F3D5A91B433718]-worker) [    ] 
o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1123542 INFO  
(SUITE-TestReloadDeadlock-seed#[B3F3D5A91B433718]-worker) [    ] 
o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, 
config=solrconfig-tlog.xml, transient=false, schema=schema15.xml, 
loadOnStartup=true, configSetProperties=configsetprops.json, 
instanceDir=collection1, collection=collection1, 
absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/,
 
dataDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.search.TestReloadDeadlock_B3F3D5A91B433718-001/init-core-data-001,
 shard=shard1}
   [junit4]   2> 1123543 INFO  (coreLoadExecutor-1693-thread-1) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 1123559 INFO  (coreLoadExecutor-1693-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/.svn/'
 to classloader
   [junit4]   2> 1123560 INFO  (coreLoadExecutor-1693-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 1123560 INFO  (coreLoadExecutor-1693-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 1123621 INFO  (coreLoadExecutor-1693-thread-1) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1123629 INFO  (coreLoadExecutor-1693-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 1123644 INFO  (coreLoadExecutor-1693-thread-1) [    ] 
o.a.s.c.Config Loaded SolrConfig: solrconfig-tlog.xml
   [junit4]   2> 1123646 INFO  (coreLoadExecutor-1693-thread-1) [    ] 
o.a.s.s.IndexSchema Reading Solr Schema from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema15.xml
   [junit4]   2> 1123654 INFO  (coreLoadExecutor-1693-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1123813 INFO  (coreLoadExecutor-1693-thread-1) [    ] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1123815 INFO  (coreLoadExecutor-1693-thread-1) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1123816 INFO  (coreLoadExecutor-1693-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1123820 INFO  (coreLoadExecutor-1693-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1123831 INFO  (coreLoadExecutor-1693-thread-1) [    ] 
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default 
properties: Can't find resource 'configsetprops.json' in classpath or 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf'
   [junit4]   2> 1123831 INFO  (coreLoadExecutor-1693-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/
   [junit4]   2> 1123831 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1123832 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/],
 dataDir=[null]
   [junit4]   2> 1123832 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6f447710
   [junit4]   2> 1123833 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.search.TestReloadDeadlock_B3F3D5A91B433718-001/init-core-data-001
   [junit4]   2> 1123833 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null 
new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.search.TestReloadDeadlock_B3F3D5A91B433718-001/init-core-data-001/index/
   [junit4]   2> 1123833 WARN  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.search.TestReloadDeadlock_B3F3D5A91B433718-001/init-core-data-001/index'
 doesn't exist. Creating new index...
   [junit4]   2> 1123834 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.search.TestReloadDeadlock_B3F3D5A91B433718-001/init-core-data-001/index
   [junit4]   2> 1123835 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=4, maxMergeAtOnceExplicit=8, maxMergedSegmentMB=1.8515625, 
floorSegmentMB=1.9873046875, forceMergeDeletesPctAllowed=20.866735476425106, 
segmentsPerTier=20.0, maxCFSSegmentSizeMB=1.5703125, noCFSRatio=1.0
   [junit4]   2> 1123836 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5165f57f 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@794f046c),segFN=segments_1,generation=1}
   [junit4]   2> 1123836 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 1123853 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "nodistrib"
   [junit4]   2> 1123853 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 1123853 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1123853 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1123854 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1123854 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] 
o.a.s.c.SolrCore Looking for old index directories to cleanup for core 
collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.search.TestReloadDeadlock_B3F3D5A91B433718-001/init-core-data-001/
   [junit4]   2> 1123863 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 1123864 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1123864 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 1123864 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 1123866 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1123867 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1123868 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1123869 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1123871 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers Registered paths: 
/admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
   [junit4]   2> 1123872 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 1123882 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1123882 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1123883 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 1123883 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 1123883 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.MockRandomMergePolicy: 
org.apache.lucene.index.MockRandomMergePolicy@585d0a0
   [junit4]   2> 1123884 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5165f57f 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@794f046c),segFN=segments_1,generation=1}
   [junit4]   2> 1123884 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 1123884 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@22a75f4d[collection1] 
main
   [junit4]   2> 1123885 WARN  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 1123885 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 1123885 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
InMemoryStorage
   [junit4]   2> 1123885 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1123885 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1123885 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed 
version buckets
   [junit4]   2> 1123886 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 
65536 version buckets from index
   [junit4]   2> 1123886 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed 
version bucket highest value from index
   [junit4]   2> 1123886 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1509035231103942656
   [junit4]   2> 1123888 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with 
highest version 1509035231103942656
   [junit4]   2> 1123888 INFO  (coreLoadExecutor-1693-thread-1) [    
x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 1123889 INFO  
(searcherExecutor-1694-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@22a75f4d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1123890 INFO  
(SUITE-TestReloadDeadlock-seed#[B3F3D5A91B433718]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1123892 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testReloadDeadlock
   [junit4]   2> 1123893 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    ] 
o.a.s.c.SolrCore [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1123894 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@4efe0433[collection1] realtime
   [junit4]   2> 1123894 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=*:* (-9223372036854775807)} 0 1
   [junit4]   2> 1123894 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    ] 
o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1123895 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    ] 
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5165f57f 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@794f046c),segFN=segments_1,generation=1}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5165f57f 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@794f046c),segFN=segments_2,generation=2}
   [junit4]   2> 1123895 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    ] 
o.a.s.c.SolrCore newest commit generation = 2
   [junit4]   2> 1123896 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@496bb7e9[collection1] main
   [junit4]   2> 1123896 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    ] 
o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 1123897 INFO  (searcherExecutor-1694-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@496bb7e9[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1123897 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{commit=} 0 2
   [junit4]   2> 1123911 INFO  (WRITER3) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1123948 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 
(1)]} 0 45
   [junit4]   2> 1123949 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(2)]} 0 42
   [junit4]   2> 1123953 INFO  (WRITER3) [    ] o.a.s.c.SolrCore 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5165f57f 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@794f046c),segFN=segments_2,generation=2}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5165f57f 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@794f046c),segFN=segments_3,generation=3}
   [junit4]   2> 1123953 INFO  (WRITER3) [    ] o.a.s.c.SolrCore newest commit 
generation = 3
   [junit4]   2> 1123955 INFO  (WRITER3) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@4dd70d1f[collection1] main
   [junit4]   2> 1123956 INFO  (searcherExecutor-1694-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4dd70d1f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C1)
 Uninverting(_1(6.0.0):C1)))}
   [junit4]   2> 1123956 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 1123956 INFO  (WRITER3) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 1123956 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null params={} {commit=} 0 45
   [junit4]   2> 1123957 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/.svn/'
 to classloader
   [junit4]   2> 1123957 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 1123957 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 1123973 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-3&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:0 (-3)} 0 61
   [junit4]   2> 1123975 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-4&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:0 (-4)} 0 63
   [junit4]   2> 1123977 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(6)]} 0 64
   [junit4]   2> 1123977 INFO  (WRITER8) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(8)]} 0 46
   [junit4]   2> 1123977 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 
(5)]} 0 65
   [junit4]   2> 1123977 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(7)]} 0 59
   [junit4]   2> 1123985 INFO  (WRITER10) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@519ec412[collection1] realtime
   [junit4]   2> 1123986 INFO  (WRITER10) [    ] 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:0 (-9)} 0 38
   [junit4]   2> 1123992 INFO  (WRITER9) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@7ec709c8[collection1] realtime
   [junit4]   2> 1123992 INFO  (WRITER9) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-10&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:6 (-10)} 0 45
   [junit4]   2> 1123993 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 
(11)]} 0 44
   [junit4]   2> 1123999 INFO  (WRITER1) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@447a8164[collection1] realtime
   [junit4]   2> 1123999 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-12&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:7 (-12)} 0 48
   [junit4]   2> 1124000 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[7 
(13)]} 0 41
   [junit4]   2> 1124000 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[2 
(16)]} 0 23
   [junit4]   2> 1124001 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(15)]} 0 24
   [junit4]   2> 1124001 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(19)]} 0 21
   [junit4]   2> 1124002 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[3 
(14)]} 0 28
   [junit4]   2> 1124002 INFO  (WRITER8) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[3 
(17)]} 0 24
   [junit4]   2> 1124006 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 
(18)]} 0 27
   [junit4]   2> 1124020 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1124036 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 1124074 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    ] 
o.a.s.c.Config Loaded SolrConfig: solrconfig-tlog.xml
   [junit4]   2> 1124075 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    ] 
o.a.s.s.IndexSchema Reading Solr Schema from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema15.xml
   [junit4]   2> 1124080 INFO  (WRITER10) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@4f05f1c8[collection1] realtime
   [junit4]   2> 1124083 INFO  (WRITER10) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-20&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:4 (-20)} 0 96
   [junit4]   2> 1124085 INFO  (WRITER9) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[4 
(21)]} 0 91
   [junit4]   2> 1124085 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 
(22)]} 0 91
   [junit4]   2> 1124085 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 
(23)]} 0 85
   [junit4]   2> 1124090 INFO  (WRITER3) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@36bf6af2[collection1] realtime
   [junit4]   2> 1124090 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[7 
(25)]} 0 89
   [junit4]   2> 1124091 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[7 
(26)]} 0 89
   [junit4]   2> 1124099 INFO  (WRITER3) [    ] 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:7 (-24)} 0 99
   [junit4]   2> 1124106 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1124108 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(27)]} 0 107
   [junit4]   2> 1124116 INFO  (WRITER8) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@45913c51[collection1] realtime
   [junit4]   2> 1124117 INFO  (WRITER8) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-29&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:7 (-29)} 0 114
   [junit4]   2> 1124117 INFO  (WRITER4) [    ] o.a.s.u.UpdateHandler Reordered 
DBQs detected.  Update=add{_version_=28,id=0} DBQs=[DBQ{version=29,q=id:7}]
   [junit4]   2> 1124120 INFO  (WRITER4) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@5d40def6[collection1] realtime
   [junit4]   2> 1124156 INFO  (WRITER2) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@5aa59f66[collection1] realtime
   [junit4]   2> 1124156 INFO  (WRITER2) [    ] 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:5 (-30)} 0 149
   [junit4]   2> 1124156 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(28)]} 0 151
   [junit4]   2> 1124157 INFO  (WRITER10) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@429bc02a[collection1] realtime
   [junit4]   2> 1124158 INFO  (WRITER10) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-31&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:7 (-31)} 0 73
   [junit4]   2> 1124161 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(34)]} 0 75
   [junit4]   2> 1124162 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(33)]} 0 77
   [junit4]   2> 1124167 INFO  (WRITER9) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[4 
(32)]} 0 82
   [junit4]   2> 1124189 INFO  (WRITER5) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@1b2acfc3[collection1] realtime
   [junit4]   2> 1124194 INFO  (WRITER5) [    ] 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:0 (-35)} 0 103
   [junit4]   2> 1124195 INFO  (WRITER6) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1124196 INFO  (WRITER6) [    ] o.a.s.c.SolrCore 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5165f57f 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@794f046c),segFN=segments_3,generation=3}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5165f57f 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@794f046c),segFN=segments_4,generation=4}
   [junit4]   2> 1124196 INFO  (WRITER6) [    ] o.a.s.c.SolrCore newest commit 
generation = 4
   [junit4]   2> 1124197 INFO  (WRITER6) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@1f227c9a[collection1] main
   [junit4]   2> 1124197 INFO  (WRITER6) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 1124198 INFO  (searcherExecutor-1694-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1f227c9a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_c(6.0.0):c3/1:delGen=1)
 Uninverting(_6(6.0.0):C3/2:delGen=2) Uninverting(_d(6.0.0):C1) 
Uninverting(_e(6.0.0):C1)))}
   [junit4]   2> 1124199 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null params={} {commit=} 0 93
   [junit4]   2> 1124204 INFO  (WRITER3) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@5a658173[collection1] realtime
   [junit4]   2> 1124204 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-36&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:5 (-36)} 0 98
   [junit4]   2> 1124207 INFO  (WRITER7) [    ] 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:0 (-37)} 0 98
   [junit4]   2> 1124208 INFO  (WRITER8) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@5884007b[collection1] realtime
   [junit4]   2> 1124208 INFO  (WRITER8) [    ] 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 90
   [junit4]   2> 1124209 INFO  (WRITER2) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@5c95a45f[collection1] realtime
   [junit4]   2> 1124209 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-39&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:6 (-39)} 0 52
   [junit4]   2> 1124210 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(43)]} 0 47
   [junit4]   2> 1124211 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(40)]} 0 54
   [junit4]   2> 1124211 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[8 
(42)]} 0 50
   [junit4]   2> 1124211 INFO  (WRITER10) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[8 
(41)]} 0 49
   [junit4]   2> 1124228 INFO  (WRITER9) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@2f589361[collection1] realtime
   [junit4]   2> 1124228 INFO  (WRITER9) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-44&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:2 (-44)} 0 60
   [junit4]   2> 1124229 INFO  (WRITER5) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@309aea2[collection1] realtime
   [junit4]   2> 1124229 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-45&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:2 (-45)} 0 34
   [junit4]   2> 1124231 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(46)]} 0 31
   [junit4]   2> 1124232 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(47)]} 0 27
   [junit4]   2> 1124232 INFO  (WRITER7) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1124244 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    ] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1124245 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1124246 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1124246 INFO  (WRITER7) [    ] o.a.s.c.SolrCore 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5165f57f 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@794f046c),segFN=segments_4,generation=4}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5165f57f 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@794f046c),segFN=segments_5,generation=5}
   [junit4]   2> 1124247 INFO  (WRITER7) [    ] o.a.s.c.SolrCore newest commit 
generation = 5
   [junit4]   2> 1124247 INFO  (WRITER7) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@651d839b[collection1] main
   [junit4]   2> 1124247 INFO  (WRITER7) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 1124248 INFO  (searcherExecutor-1694-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@651d839b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_l(6.0.0):c1)
 Uninverting(_i(6.0.0):C1) Uninverting(_j(6.0.0):C1) 
Uninverting(_m(6.0.0):C2/1:delGen=1)))}
   [junit4]   2> 1124248 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null params={} {commit=} 0 41
   [junit4]   2> 1124251 INFO  (WRITER8) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@612a9810[collection1] realtime
   [junit4]   2> 1124252 INFO  (WRITER8) [    ] 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:6 (-48)} 0 43
   [junit4]   2> 1124252 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[6 
(49)]} 0 42
   [junit4]   2> 1124252 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1124259 INFO  (WRITER0) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@58ebc87d[collection1] realtime
   [junit4]   2> 1124259 INFO  (WRITER0) [    ] 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:5 (-50)} 0 48
   [junit4]   2> 1124260 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    ] 
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default 
properties: Can't find resource 'configsetprops.json' in classpath or 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf'
   [junit4]   2> 1124260 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    ] 
o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from 
instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/
   [junit4]   2> 1124261 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.search.TestReloadDeadlock_B3F3D5A91B433718-001/init-core-data-001/]
   [junit4]   2> 1124261 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6f447710
   [junit4]   2> 1124261 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null 
new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.search.TestReloadDeadlock_B3F3D5A91B433718-001/init-core-data-001/index/
   [junit4]   2> 1124262 INFO  (WRITER10) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(53)]} 0 50
   [junit4]   2> 1124262 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(52)]} 0 49
   [junit4]   2> 1124262 INFO  (WRITER9) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[1 
(54)]} 0 33
   [junit4]   2> 1124262 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[2 
(51)]} 0 50
   [junit4]   2> 1124266 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "nodistrib"
   [junit4]   2> 1124266 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 1124266 INFO  (WRITER6) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@4d0e2fed[collection1] realtime
   [junit4]   2> 1124267 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1124267 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-56&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:5 (-56)} 0 35
   [junit4]   2> 1124267 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] 
o.a.s.c.SolrCore Looking for old index directories to cleanup for core 
collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.search.TestReloadDeadlock_B3F3D5A91B433718-001/init-core-data-001/
   [junit4]   2> 1124267 INFO  (WRITER5) [    ] o.a.s.u.UpdateHandler Reordered 
DBQs detected.  Update=add{_version_=55,id=7} DBQs=[DBQ{version=56,q=id:5}]
   [junit4]   2> 1124267 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1124271 INFO  (WRITER5) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@3e0989be[collection1] realtime
   [junit4]   2> 1124271 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[7 
(55)]} 0 41
   [junit4]   2> 1124272 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-57&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:5 (-57)} 0 40
   [junit4]   2> 1124274 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[8 
(58)]} 0 21
   [junit4]   2> 1124274 INFO  (WRITER8) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(59)]} 0 22
   [junit4]   2> 1124277 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1124277 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 1124278 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1124278 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 1124279 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 1124280 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1124282 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1124283 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1124284 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1124331 INFO  (WRITER2) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@5f737666[collection1] realtime
   [junit4]   2> 1124332 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-60&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:6 (-60)} 0 80
   [junit4]   2> 1124333 INFO  (WRITER0) [    ] 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:6 (-61)} 0 73
   [junit4]   2> 1124334 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-63&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:5 (-63)} 0 71
   [junit4]   2> 1124338 INFO  (WRITER9) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@4aa639ce[collection1] realtime
   [junit4]   2> 1124338 INFO  (WRITER9) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-64&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:1 (-64)} 0 75
   [junit4]   2> 1124339 INFO  (WRITER10) [    ] o.a.s.u.UpdateHandler 
Reordered DBQs detected.  Update=add{_version_=62,id=2} 
DBQs=[DBQ{version=64,q=id:1}, DBQ{version=63,q=id:5}]
   [junit4]   2> 1124343 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.c.RequestHandlers Registered paths: 
/admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
   [junit4]   2> 1124344 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 1124345 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(66)]} 0 76
   [junit4]   2> 1124374 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 1124375 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 1124383 INFO  (WRITER10) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@186e458b[collection1] realtime
   [junit4]   2> 1124388 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@135edd50[collection1] 
main
   [junit4]   2> 1124388 WARN  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 1124389 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 1124389 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
InMemoryStorage
   [junit4]   2> 1124389 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1124389 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1124390 INFO  
(searcherExecutor-1699-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@135edd50[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_n(6.0.0):c4/3)
 Uninverting(_q(6.0.0):C1) Uninverting(_s(6.0.0):C1) Uninverting(_r(6.0.0):C1) 
Uninverting(_u(6.0.0):C1) Uninverting(_t(6.0.0):C1)))}
   [junit4]   2> 1124391 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[2 
(65)]} 0 127
   [junit4]   2> 1124394 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed 
version buckets
   [junit4]   2> 1124399 INFO  (WRITER5) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@1c26e4c5[collection1] realtime
   [junit4]   2> 1124401 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[7 
(68)]} 0 127
   [junit4]   2> 1124401 INFO  (WRITER9) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[7 
(74)]} 0 62
   [junit4]   2> 1124401 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 
(73)]} 0 66
   [junit4]   2> 1124401 INFO  (WRITER8) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(70)]} 0 127
   [junit4]   2> 1124402 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-67&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:7 (-67)} 0 130
   [junit4]   2> 1124402 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[6 
(71)]} 0 69
   [junit4]   2> 1124403 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[1 
(69)]} 0 128
   [junit4]   2> 1124410 INFO  (WRITER0) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@44512a68[collection1] realtime
   [junit4]   2> 1124411 INFO  (WRITER10) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[2 
(62)]} 0 148
   [junit4]   2> 1124412 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 
(76)]} 0 20
   [junit4]   2> 1124412 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 
(75)]} 0 67
   [junit4]   2> 1124413 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-72&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:5 (-72)} 0 72
   [junit4]   2> 1124413 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 
65536 version buckets from index
   [junit4]   2> 1124413 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.u.VersionInfo Found MAX value 66 from Terms for _version_ 
in index
   [junit4]   2> 1124416 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.u.UpdateLog Took 3.0ms to seed version buckets with 
highest version 76
   [junit4]   2> 1124416 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[7 
(77)]} 0 15
   [junit4]   2> 1124417 INFO  (WRITER9) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(78)]} 0 15
   [junit4]   2> 1124417 INFO  (WRITER8) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(80)]} 0 15
   [junit4]   2> 1124417 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(79)]} 0 15
   [junit4]   2> 1124422 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.u.DefaultSolrCoreState Creating new IndexWriter...
   [junit4]   2> 1124423 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.u.DefaultSolrCoreState Waiting until IndexWriter is 
unused... core=collection1
   [junit4]   2> 1124426 INFO  (WRITER2) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@3234bbcf[collection1] realtime
   [junit4]   2> 1124426 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.u.DefaultSolrCoreState Closing old IndexWriter... 
core=collection1
   [junit4]   2> 1124427 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-82&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:6 (-82)} 0 24
   [junit4]   2> 1124427 INFO  (WRITER5) [    ] o.a.s.u.UpdateHandler Reordered 
DBQs detected.  Update=add{_version_=81,id=6} DBQs=[DBQ{version=82,q=id:6}]
   [junit4]   2> 1124433 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5165f57f 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@794f046c),segFN=segments_5,generation=5}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5165f57f 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@794f046c),segFN=segments_6,generation=6}
   [junit4]   2> 1124433 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 6
   [junit4]   2> 1124434 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=42, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=14.546875, 
floorSegmentMB=0.3955078125, forceMergeDeletesPctAllowed=27.73947451008993, 
segmentsPerTier=13.0, maxCFSSegmentSizeMB=0.9951171875, 
noCFSRatio=0.20684173679013274
   [junit4]   2> 1124447 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5165f57f 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@794f046c),segFN=segments_6,generation=6}
   [junit4]   2> 1124448 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 6
   [junit4]   2> 1124455 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1124458 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[2 
(85)]} 0 46
   [junit4]   2> 1124460 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[8 
(83)]} 0 56
   [junit4]   2> 1124470 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@7435aa2f[collection1] 
main
   [junit4]   2> 1124471 INFO  
(searcherExecutor-1699-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7435aa2f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_12(6.0.0):c5/1)
 Uninverting(_10(6.0.0):C5/2:delGen=2) Uninverting(_13(6.0.0):C1)))}
   [junit4]   2> 1124478 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.c.CoreContainer replacing core: collection1
   [junit4]   2> 1124478 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@16c57687
   [junit4]   2> 1124534 INFO  (WRITER5) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@28210c98[collection1] realtime
   [junit4]   2> 1124535 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.u.UpdateHandler closing 
DirectUpdateHandler2{commits=4,autocommits=0,soft 
autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=23,adds=24,deletesById=0,deletesByQuery=11,errors=1,cumulative_adds=52,cumulative_deletesById=0,cumulative_deletesByQuery=29,cumulative_errors=1,transaction_logs_total_size=1222,transaction_logs_total_number=2}
   [junit4]   2> 1124535 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
   [junit4]   2> 1124536 INFO  (WRITER6) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@3d2b78f5[collection1] realtime
   [junit4]   2> 1124536 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-86&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:7 (-86)} 0 124
   [junit4]   2> 1124536 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 
(81)]} 0 132
   [junit4]   2> 1124537 INFO  (WRITER10) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-84&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:2 (-84)} 0 125
   [junit4]   2> 1124538 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 
(87)]} 0 122
   [junit4]   2> 1124541 INFO  (WRITER3) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@6e323031[collection1] realtime
   [junit4]   2> 1124541 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-88&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:0 (-88)} 0 124
   [junit4]   2> 1124542 INFO  (WRITER8) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(90)]} 0 124
   [junit4]   2> 1124542 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[6 
(92)]} 0 114
   [junit4]   2> 1124542 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(91)]} 0 124
   [junit4]   2> 1124542 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(93)]} 0 83
   [junit4]   2> 1124542 INFO  (WRITER9) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[4 
(89)]} 0 125
   [junit4]   2> 1124543 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[2 
(94)]} 0 82
   [junit4]   2> 1124602 INFO  (WRITER6) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@187b8c00[collection1] realtime
   [junit4]   2> 1124652 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-95&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:3 (-95)} 0 115
   [junit4]   2> 1124653 INFO  (WRITER5) [    ] 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:0 (-96)} 0 116
   [junit4]   2> 1124654 INFO  (WRITER10) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[2 
(97)]} 0 115
   [junit4]   2> 1124656 INFO  (WRITER0) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@2f78d16d[collection1] realtime
   [junit4]   2> 1124657 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-98&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:2 (-98)} 0 118
   [junit4]   2> 1124658 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(102)]} 0 115
   [junit4]   2> 1124658 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(103)]} 0 115
   [junit4]   2> 1124659 INFO  (WRITER8) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(100)]} 0 116
   [junit4]   2> 1124659 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(99)]} 0 117
   [junit4]   2> 1124663 INFO  (WRITER9) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@68901a72[collection1] realtime
   [junit4]   2> 1124664 INFO  (WRITER2) [    ] o.a.s.u.UpdateHandler Reordered 
DBQs detected.  Update=add{_version_=101,id=0} DBQs=[DBQ{version=104,q=id:5}]
   [junit4]   2> 1124667 INFO  (WRITER2) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@3f7e1b2f[collection1] realtime
   [junit4]   2> 1124668 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(101)]} 0 124
   [junit4]   2> 1124668 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(105)]} 0 124
   [junit4]   2> 1124669 INFO  (WRITER9) [    ] 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:5 (-104)} 0 126
   [junit4]   2> 1124671 INFO  (WRITER6) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@672803c2[collection1] realtime
   [junit4]   2> 1124672 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-106&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:5 (-106)} 0 18
   [junit4]   2> 1124672 INFO  (WRITER0) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1124673 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(107)]} 0 19
   [junit4]   2> 1124673 INFO  (WRITER10) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[8 
(108)]} 0 19
   [junit4]   2> 1124675 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(110)]} 0 16
   [junit4]   2> 1124675 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(109)]} 0 16
   [junit4]   2> 1124675 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 1124676 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/.svn/'
 to classloader
   [junit4]   2> 1124676 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 1124676 INFO  
(TEST-TestReloadDeadlock.testReloadDeadlock-seed#[B3F3D5A91B433718]) [    
x:collection1] o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 1124677 INFO  (WRITER0) [    ] o.a.s.c.SolrCore 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5165f57f 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@794f046c),segFN=segments_6,generation=6}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@5165f57f 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@794f046c),segFN=segments_7,generation=7}
   [junit4]   2> 1124677 INFO  (WRITER0) [    ] o.a.s.c.SolrCore newest commit 
generation = 7
   [junit4]   2> 1124680 INFO  (WRITER0) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@291376c5[collection1] main
   [junit4]   2> 1124681 INFO  (WRITER0) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 1124681 INFO  (searcherExecutor-1699-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@291376c5[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_12(6.0.0):c5/4:delGen=1)
 Uninverting(_18(6.0.0):C2/1:delGen=1) Uninverting(_19(6.0.0):C3/2:delGen=2) 
Uninverting(_1d(6.0.0):C1) Uninverting(_1g(6.0.0):C1) 
Uninverting(_1h(6.0.0):C1)))}
   [junit4]   2> 1124682 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null params={} {commit=} 0 24
   [junit4]   2> 1124683 INFO  (WRITER8) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@147632c4[collection1] realtime
   [junit4]   2> 1124683 INFO  (WRITER8) [    ] 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:6 (-111)} 0 24
   [junit4]   2> 1124684 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 
(112)]} 0 25
   [junit4]   2> 1124685 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(114)]} 0 15
   [junit4]   2> 1124685 INFO  (WRITER9) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[5 
(115)]} 0 15
   [junit4]   2> 1124685 INFO  (WRITER6) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[0 
(116)]} 0 13
   [junit4]   2> 1124685 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[2 
(113)]} 0 17
   [junit4]   2> 1124686 INFO  (WRITER4) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[2 
(119)]} 0 10
   [junit4]   2> 1124686 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[2 
(117)]} 0 12
   [junit4]   2> 1124686 INFO  (WRITER10) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[2 
(118)]} 0 12
   [junit4]   2> 1124696 INFO  (WRITER1) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@695224ad[collection1] realtime
   [junit4]   2> 1124697 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-120&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:2 (-120)} 0 22
   [junit4]   2> 1124697 INFO  (WRITER3) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[6 
(123)]} 0 12
   [junit4]   2> 1124698 INFO  (WRITER8) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[2 
(121)]} 0 14
   [junit4]   2> 1124698 INFO  (WRITER7) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[8 
(124)]} 0 13
   [junit4]   2> 1124698 INFO  (WRITER2) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[2 
(127)]} 0 12
   [junit4]   2> 1124698 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true} {add=[8 
(122)]} 0 14
   [junit4]   2> 1124708 INFO  (WRITER5) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@3ad5750[collection1] realtime
   [junit4]   2> 1124708 INFO  (WRITER5) [    ] o.a.s.u.p.LogUpdateProcessor 
[collection1] webapp=null path=null 
params={_version_=-129&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 {deleteByQuery=id:3 (-129)} 0 22
   [junit4]   2> 1124710 INFO  (WRITER10) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@54d05f37[collection1] realtime
   [junit4]   2> 1124710 INFO  (WRIT

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

erMBean(DefaultMBeanServerInterceptor.java:415)
   [junit4]   2>        at 
com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:546)
   [junit4]   2>        at 
org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:81)
   [junit4]   2>        at 
org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stopMBeans(MetricsSourceAdapter.java:227)
   [junit4]   2>        at 
org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stop(MetricsSourceAdapter.java:212)
   [junit4]   2>        at 
org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stopSources(MetricsSystemImpl.java:461)
   [junit4]   2>        at 
org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stop(MetricsSystemImpl.java:212)
   [junit4]   2>        at 
org.apache.hadoop.metrics2.impl.MetricsSystemImpl.shutdown(MetricsSystemImpl.java:592)
   [junit4]   2>        at 
org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdownInstance(DefaultMetricsSystem.java:72)
   [junit4]   2>        at 
org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdown(DefaultMetricsSystem.java:68)
   [junit4]   2>        at 
org.apache.hadoop.hdfs.server.namenode.metrics.NameNodeMetrics.shutdown(NameNodeMetrics.java:145)
   [junit4]   2>        at 
org.apache.hadoop.hdfs.server.namenode.NameNode.stop(NameNode.java:822)
   [junit4]   2>        at 
org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1720)
   [junit4]   2>        at 
org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1699)
   [junit4]   2>        at 
org.apache.solr.cloud.hdfs.HdfsTestUtil.teardownClass(HdfsTestUtil.java:197)
   [junit4]   2>        at 
org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.teardownClass(HdfsCollectionsAPIDistributedZkTest.java:50)
   [junit4]   2>        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native 
Method)
   [junit4]   2>        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]   2>        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]   2>        at java.lang.reflect.Method.invoke(Method.java:497)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:799)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.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> 3548616 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[B3F3D5A91B433718]-worker) 
[n:127.0.0.1:41618_ c:addReplicaColl s:shard2 r:core_node6 
x:addReplicaColl_shard2_replica3] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> Aug 09, 2015 10:29:28 AM 
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): 
{other_tl1=BlockTreeOrds(blocksize=128), 
range_facet_l_dv=PostingsFormat(name=LuceneFixedGap), 
_version_=BlockTreeOrds(blocksize=128), 
multiDefault=PostingsFormat(name=MockRandom), a_t=BlockTreeOrds(blocksize=128), 
intDefault=BlockTreeOrds(blocksize=128), a_i1=BlockTreeOrds(blocksize=128), 
id=PostingsFormat(name=LuceneFixedGap), 
range_facet_i_dv=BlockTreeOrds(blocksize=128), text=Lucene50(blocksize=128), 
range_facet_l=BlockTreeOrds(blocksize=128), 
timestamp=BlockTreeOrds(blocksize=128)}, 
docValues:{range_facet_l_dv=DocValuesFormat(name=Direct), 
range_facet_i_dv=DocValuesFormat(name=Memory), 
timestamp=DocValuesFormat(name=Memory)}, sim=DefaultSimilarity, 
locale=sr_ME_#Latn, timezone=Asia/Harbin
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 
1.8.0_45 (64-bit)/cpus=4,threads=2,free=184126832,total=517996544
   [junit4]   2> NOTE: All tests run in this JVM: [ExplicitHLLTest, 
TestFieldTypeCollectionResource, TestStressLucene, TestIBSimilarityFactory, 
TestConfigReload, DistributedSpellCheckComponentTest, TestLazyCores, 
ClusterStateTest, TestSerializedLuceneMatchVersion, 
ChaosMonkeyNothingIsSafeTest, DeleteLastCustomShardedReplicaTest, 
SolrRequestParserTest, BasicZkTest, SimplePostToolTest, TestRandomDVFaceting, 
HdfsNNFailoverTest, SolrCoreTest, TestPKIAuthenticationPlugin, 
TestExactStatsCache, DistributedQueryComponentCustomSortTest, 
TestAuthorizationFramework, TestCustomSort, EnumFieldTest, 
QueryElevationComponentTest, SchemaVersionSpecificBehaviorTest, 
TestManagedResourceStorage, TestScoreJoinQPNoScore, PrimitiveFieldTypeTest, 
MinimalSchemaTest, BlockCacheTest, CursorPagingTest, 
TestUniqueKeyFieldResource, TestCursorMarkWithoutUniqueKey, 
TestSolrDeletionPolicy1, TestDocumentBuilder, TestConfig, SparseHLLTest, 
TestScoreJoinQPScore, UUIDFieldTest, TestMacros, HighlighterTest, 
TestBulkSchemaConcurrent, MergeStrategyTest, TestCodecSupport, 
TestInfoStreamLogging, TestComplexPhraseQParserPlugin, CSVRequestHandlerTest, 
TestMiniSolrCloudCluster, SuggesterWFSTTest, TestJmxIntegration, 
TestCryptoKeys, TestSmileRequest, DistributedDebugComponentTest, 
TestQuerySenderListener, TestPhraseSuggestions, TestManagedStopFilterFactory, 
HighlighterMaxOffsetTest, SolrIndexConfigTest, DateMathParserTest, 
HighlighterConfigTest, TestSha256AuthenticationProvider, OverseerStatusTest, 
TestArbitraryIndexDir, LeaderInitiatedRecoveryOnCommitTest, 
TestReplicationHandler, PingRequestHandlerTest, TestJmxMonitoredMap, 
TestFieldResource, IgnoreCommitOptimizeUpdateProcessorFactoryTest, 
BasicDistributedZkTest, TestBadConfig, DateFieldTest, TestCloudPivotFacet, 
RequestHandlersTest, CdcrUpdateLogTest, UpdateRequestProcessorFactoryTest, 
TimeZoneUtilsTest, CloudMLTQParserTest, FastVectorHighlighterTest, 
BasicAuthIntegrationTest, CursorMarkTest, 
BigEndianAscendingWordDeserializerTest, HdfsSyncSliceTest, TestBinaryField, 
TestHdfsUpdateLog, TestSearcherReuse, SyncSliceTest, 
LeaderElectionIntegrationTest, ClusterStateUpdateTest, TestRandomFaceting, 
ZkSolrClientTest, TestDistributedSearch, CollectionReloadTest, 
ConnectionManagerTest, DeleteInactiveReplicaTest, DeleteReplicaTest, 
HttpPartitionTest, OutOfBoxZkACLAndCredentialsProvidersTest, 
OverriddenZkACLAndCredentialsProvidersTest, RecoveryAfterSoftCommitTest, 
ReplicationFactorTest, SSLMigrationTest, SharedFSAutoReplicaFailoverTest, 
TestRequestStatusCollectionAPI, HdfsCollectionsAPIDistributedZkTest]
   [junit4] Completed [462/540] on J0 in 595.65s, 1 test, 1 error <<< FAILURES!

[...truncated 258 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:723:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:660:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:59:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:230:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:514:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1429:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:984:
 There were test failures: 540 suites, 2127 tests, 1 suite-level error, 3 
errors, 1 failure, 26 ignored (14 assumptions)

Total time: 167 minutes 3 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
No prior successful build to compare, so performing full copy of artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure


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

Reply via email to