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

4 tests failed.
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([547BEA79E2A6115B]: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:235)
        at sun.reflect.GeneratedMethodAccessor42.invoke(Unknown Source)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:483)
        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)


REGRESSION:  org.apache.solr.cloud.hdfs.HdfsChaosMonkeySafeLeaderTest.test

Error Message:
shard1 is not consistent.  Got 168 from 
http://127.0.0.1:51292/collection1lastClient and got 150 from 
http://127.0.0.1:47576/collection1

Stack Trace:
java.lang.AssertionError: shard1 is not consistent.  Got 168 from 
http://127.0.0.1:51292/collection1lastClient and got 150 from 
http://127.0.0.1:47576/collection1
        at 
__randomizedtesting.SeedInfo.seed([547BEA79E2A6115B:DC2FD5A34C5A7CA3]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at 
org.apache.solr.cloud.AbstractFullDistribZkTestBase.checkShardConsistency(AbstractFullDistribZkTestBase.java:1242)
        at 
org.apache.solr.cloud.AbstractFullDistribZkTestBase.checkShardConsistency(AbstractFullDistribZkTestBase.java:1221)
        at 
org.apache.solr.cloud.ChaosMonkeySafeLeaderTest.test(ChaosMonkeySafeLeaderTest.java:165)
        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:483)
        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:960)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935)
        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.CollectionsAPIDistributedZkTest.test

Error Message:
Error from server at http://127.0.0.1:39630: Error CREATEing SolrCore 
'halfcollection_shard1_replica1': Unable to create core 
[halfcollection_shard1_replica1] Caused by: Could not get shard id for core: 
halfcollection_shard1_replica1

Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error 
from server at http://127.0.0.1:39630: Error CREATEing SolrCore 
'halfcollection_shard1_replica1': Unable to create core 
[halfcollection_shard1_replica1] Caused by: Could not get shard id for core: 
halfcollection_shard1_replica1
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227)
        at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.makeRequest(CollectionsAPIDistributedZkTest.java:309)
        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:437)
        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:176)
        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:483)
        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:960)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935)
        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=5546, name=collection2, 
state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=5546, name=collection2, state=RUNNABLE, 
group=TGRP-HdfsCollectionsAPIDistributedZkTest]
Caused by: 
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error 
from server at http://127.0.0.1:41432/tf/h: Could not find collection : 
awholynewstresscollection_collection2_0
        at __randomizedtesting.SeedInfo.seed([547BEA79E2A6115B]:0)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227)
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376)
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799)
        at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:902)




Build Log:
[...truncated 10610 lines...]
   [junit4] Suite: org.apache.solr.cloud.HttpPartitionTest
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/init-core-data-001
   [junit4]   2> 2055073 INFO  
(SUITE-HttpPartitionTest-seed#[547BEA79E2A6115B]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 2055080 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2055080 INFO  (Thread-5334) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2055080 INFO  (Thread-5334) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 2055180 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.ZkTestServer start zk server on port:36393
   [junit4]   2> 2055180 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2055186 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2055195 INFO  (zkCallback-1806-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@36b9343d 
name:ZooKeeperConnection Watcher:127.0.0.1:36393 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2055195 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2055196 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2055196 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 2055204 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2055209 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2055209 INFO  (zkCallback-1807-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@3c11ead6 
name:ZooKeeperConnection Watcher:127.0.0.1:36393/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2055210 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2055210 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2055210 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 2055216 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 2055218 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 2055222 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 2055229 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
 to /configs/conf1/solrconfig.xml
   [junit4]   2> 2055229 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 2055233 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml
 to /configs/conf1/schema.xml
   [junit4]   2> 2055234 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 2055237 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
 to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 2055237 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.SolrZkClient makePath: 
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 2055241 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
 to /configs/conf1/stopwords.txt
   [junit4]   2> 2055242 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 2055247 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt
 to /configs/conf1/protwords.txt
   [junit4]   2> 2055247 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 2055250 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml
 to /configs/conf1/currency.xml
   [junit4]   2> 2055250 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 2055251 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
 to /configs/conf1/enumsConfig.xml
   [junit4]   2> 2055252 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 2055253 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
 to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 2055253 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 2055254 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
 to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 2055254 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 2055256 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
 to /configs/conf1/old_synonyms.txt
   [junit4]   2> 2055256 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 2055257 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
 to /configs/conf1/synonyms.txt
   [junit4]   2> 2055257 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 2055809 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/control-001/cores/collection1
   [junit4]   2> 2055813 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] o.e.j.s.Server 
jetty-9.2.11.v20150529
   [junit4]   2> 2055820 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@ba6e5e7{/,null,AVAILABLE}
   [junit4]   2> 2055821 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@313eaa6{HTTP/1.1}{127.0.0.1:53699}
   [junit4]   2> 2055821 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] o.e.j.s.Server 
Started @2058941ms
   [junit4]   2> 2055821 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/tempDir-001/control/data,
 hostContext=/, hostPort=52375, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/control-001/cores}
   [junit4]   2> 2055822 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@c387f44
   [junit4]   2> 2055822 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/control-001/'
   [junit4]   2> 2055872 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/control-001/solr.xml
   [junit4]   2> 2055878 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.CoresLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/control-001/cores
   [junit4]   2> 2055878 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 790998541
   [junit4]   2> 2055878 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/control-001/]
   [junit4]   2> 2055878 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.CoreContainer loading shared library: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/control-001/lib
   [junit4]   2> 2055879 WARN  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
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/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/control-001/lib).
   [junit4]   2> 2055890 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme 
: ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 
10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 
5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
   [junit4]   2> 2055892 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2055893 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] o.a.s.l.LogWatcher 
SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2055894 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] o.a.s.l.LogWatcher 
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2055897 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36393/solr
   [junit4]   2> 2055897 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 2055897 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2055898 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2055911 INFO  (zkCallback-1809-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@d2eac0b name:ZooKeeperConnection 
Watcher:127.0.0.1:36393 got event WatchedEvent state:SyncConnected type:None 
path:null path:null type:None
   [junit4]   2> 2055911 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2055912 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2055913 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from 
client sessionid 0x14dfc4ca8530002, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 2055914 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:52375_    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2055915 INFO  
(zkCallback-1810-thread-1-processing-n:127.0.0.1:52375_) [n:127.0.0.1:52375_    
] o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@3b4b133b 
name:ZooKeeperConnection Watcher:127.0.0.1:36393/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2055915 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:52375_    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2055917 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:52375_    ] 
o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 2055919 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:52375_    ] 
o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
   [junit4]   2> 2055923 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:52375_    ] 
o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
   [junit4]   2> 2055928 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:52375_    ] 
o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
   [junit4]   2> 2055931 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:52375_    ] 
o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
   [junit4]   2> 2055933 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:52375_    ] 
o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 2055940 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:52375_    ] 
o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 2055943 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:52375_    ] 
o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 2055944 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:52375_    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:52375_
   [junit4]   2> 2055945 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:52375_    ] 
o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:52375_
   [junit4]   2> 2055951 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:52375_    ] 
o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 2055954 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:52375_    ] 
o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
   [junit4]   2> 2055955 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:52375_    ] 
o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2055956 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:52375_    ] 
o.a.s.c.LeaderElector Joined leadership election with path: 
/overseer_elect/election/94008573416374275-127.0.0.1:52375_-n_0000000000
   [junit4]   2> 2055957 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:52375_    ] 
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:52375_
   [junit4]   2> 2055957 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:52375_    ] 
o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 2055960 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:52375_    ] 
o.a.s.c.Overseer Overseer (id=94008573416374275-127.0.0.1:52375_-n_0000000000) 
starting
   [junit4]   2> 2055966 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:52375_    ] 
o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 2055978 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:52375_    ] 
o.a.s.c.OverseerAutoReplicaFailoverThread Starting 
OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 
autoReplicaFailoverWaitAfterExpiration=30000 
autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 2055979 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:52375_    ] 
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 2055979 INFO  
(OverseerCollectionProcessor-94008573416374275-127.0.0.1:52375_-n_0000000000) 
[n:127.0.0.1:52375_    ] o.a.s.c.OverseerCollectionProcessor Process current 
queue of collection creations
   [junit4]   2> 2055980 INFO  
(OverseerStateUpdate-94008573416374275-127.0.0.1:52375_-n_0000000000) 
[n:127.0.0.1:52375_    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 2055990 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:52375_    ] 
o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 2055991 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:52375_    ] 
o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 2055991 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:52375_    ] 
o.a.s.c.CoresLocator Looking for core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/control-001/cores
   [junit4]   2> 2055992 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:52375_    ] 
o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, 
config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, 
instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/control-001/cores/collection1,
 collection=control_collection, 
absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/control-001/cores/collection1/,
 coreNodeName=, dataDir=data/, shard=}
   [junit4]   2> 2055992 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:52375_    ] 
o.a.s.c.CoresLocator Found core collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/control-001/cores/collection1/
   [junit4]   2> 2055992 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:52375_    ] 
o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 2055995 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection   x:collection1] o.a.s.c.ZkController 
publishing state=down
   [junit4]   2> 2055996 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection   x:collection1] o.a.s.c.ZkController 
numShards not found on descriptor - reading it from system property
   [junit4]   2> 2055996 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:52375_    ] 
o.a.s.s.SolrDispatchFilter 
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1
   [junit4]   2> 2055996 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:52375_    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 2055996 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection   x:collection1] o.a.s.c.ZkController 
look for our core node name
   [junit4]   2> 2055999 INFO  
(OverseerStateUpdate-94008573416374275-127.0.0.1:52375_-n_0000000000) 
[n:127.0.0.1:52375_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message 
= {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:52375";,
   [junit4]   2>   "node_name":"127.0.0.1:52375_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"} current state version: 0
   [junit4]   2> 2056000 INFO  
(OverseerStateUpdate-94008573416374275-127.0.0.1:52375_-n_0000000000) 
[n:127.0.0.1:52375_    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 
message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:52375";,
   [junit4]   2>   "node_name":"127.0.0.1:52375_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 2056001 INFO  
(OverseerStateUpdate-94008573416374275-127.0.0.1:52375_-n_0000000000) 
[n:127.0.0.1:52375_    ] o.a.s.c.o.ClusterStateMutator building a new cName: 
control_collection
   [junit4]   2> 2056001 INFO  
(OverseerStateUpdate-94008573416374275-127.0.0.1:52375_-n_0000000000) 
[n:127.0.0.1:52375_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard 
shard=shard1
   [junit4]   2> 2056005 INFO  
(zkCallback-1810-thread-1-processing-n:127.0.0.1:52375_) [n:127.0.0.1:52375_    
] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred 
- updating... (live nodes size: 1)
   [junit4]   2> 2056011 INFO  
(zkCallback-1810-thread-1-processing-n:127.0.0.1:52375_) [n:127.0.0.1:52375_    
] o.a.s.c.c.ZkStateReader Updated cluster state version to 1
   [junit4]   2> 2056015 INFO  
(zkCallback-1810-thread-1-processing-n:127.0.0.1:52375_) [n:127.0.0.1:52375_    
] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue 
state SyncConnected
   [junit4]   2> 2056996 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection   x:collection1] o.a.s.c.ZkController 
waiting to find shard id in clusterstate for collection1
   [junit4]   2> 2056997 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection   x:collection1] o.a.s.c.ZkController 
Check for collection zkNode:control_collection
   [junit4]   2> 2056997 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection   x:collection1] o.a.s.c.ZkController 
Collection zkNode exists
   [junit4]   2> 2056997 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection   x:collection1] 
o.a.s.c.c.ZkStateReader Load collection config 
from:/collections/control_collection
   [junit4]   2> 2056998 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection   x:collection1] 
o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 
specified config exists in ZooKeeper
   [junit4]   2> 2056999 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection   x:collection1] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/control-001/cores/collection1/'
   [junit4]   2> 2057022 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection   x:collection1] o.a.s.c.Config loaded 
config solrconfig.xml with version 0 
   [junit4]   2> 2057046 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection   x:collection1] o.a.s.c.SolrConfig 
current version of requestparams : -1
   [junit4]   2> 2057049 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection   x:collection1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 2057059 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection   x:collection1] o.a.s.c.Config Loaded 
SolrConfig: solrconfig.xml
   [junit4]   2> 2057060 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection   x:collection1] o.a.s.s.IndexSchema 
Reading Solr Schema from /configs/conf1/schema.xml
   [junit4]   2> 2057069 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection   x:collection1] o.a.s.s.IndexSchema 
[collection1] Schema name=test
   [junit4]   2> 2057188 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with 
rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 2057195 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection   x:collection1] o.a.s.s.IndexSchema 
default search field in schema is text
   [junit4]   2> 2057196 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection   x:collection1] o.a.s.s.IndexSchema 
unique key field: id
   [junit4]   2> 2057210 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection   x:collection1] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 2057212 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection   x:collection1] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 2057216 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 2057217 WARN  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 2057218 WARN  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 2057219 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 2057220 WARN  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 2057221 WARN  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 2057221 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection   x:collection1] o.a.s.c.CoreContainer 
Creating SolrCore 'collection1' using configuration from collection 
control_collection
   [junit4]   2> 2057221 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 2057221 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/control-001/cores/collection1/],
 dataDir=[null]
   [junit4]   2> 2057221 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX 
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3e9e4449
   [junit4]   2> 2057222 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/control-001/cores/collection1/data
   [junit4]   2> 2057222 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore New index directory detected: old=null 
new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/control-001/cores/collection1/data/index/
   [junit4]   2> 2057222 WARN  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore [collection1] Solr index directory 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/control-001/cores/collection1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 2057223 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/control-001/cores/collection1/data/index
   [junit4]   2> 2057223 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=714039037, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 2057223 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@56242e20 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e161143),segFN=segments_1,generation=1}
   [junit4]   2> 2057224 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 2057225 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:52375_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore 
Looking for old index directories to cleanup for core collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/control-001/cores/collection1/data/
   [junit4]   2> 2057225 WARN  
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:52375_ 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.DirectoryFactory 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/control-001/cores/collection1/data/
 does not point to a valid data directory; skipping clean-up of old index 
directories.
   [junit4]   2> 2057230 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"nodistrib"
   [junit4]   2> 2057231 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"dedupe"
   [junit4]   2> 2057231 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 2057232 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"stored_sig"
   [junit4]   2> 2057232 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 2057232 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"distrib-dup-test-chain-explicit"
   [junit4]   2> 2057232 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 2057233 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 2057233 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating 
implicit default
   [junit4]   2> 2057237 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2057238 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2057239 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2057241 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2057246 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 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> 2057246 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 2057247 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2057247 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2057249 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 2057249 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 2057249 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=7, maxMergeAtOnceExplicit=9, maxMergedSegmentMB=27.9072265625, 
floorSegmentMB=2.193359375, forceMergeDeletesPctAllowed=1.6415820687032423, 
segmentsPerTier=36.0, maxCFSSegmentSizeMB=2.1064453125, 
noCFSRatio=0.6217567923197758
   [junit4]   2> 2057250 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@56242e20 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e161143),segFN=segments_1,generation=1}
   [junit4]   2> 2057250 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 2057250 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.s.SolrIndexSearcher Opening Searcher@36169850[collection1] main
   [junit4]   2> 2057250 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.c.ZkStateReader Load collection config 
from:/collections/control_collection
   [junit4]   2> 2057252 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 
specified config exists in ZooKeeper
   [junit4]   2> 2057252 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the 
RestManager with znodeBase: /configs/conf1
   [junit4]   2> 2057252 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 2057253 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 2057253 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 2057253 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.r.ManagedResourceStorage No data found for znode 
/configs/conf1/_rest_managed.json
   [junit4]   2> 2057253 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 2057253 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 2057254 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2057254 INFO  
(searcherExecutor-3434-thread-1-processing-n:127.0.0.1:52375_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52375_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore 
[collection1] Registered new searcher Searcher@36169850[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2057254 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
   [junit4]   2> 2057254 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version 
buckets from index
   [junit4]   2> 2057254 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket 
highest value from index
   [junit4]   2> 2057255 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1504137176933203968
   [junit4]   2> 2057257 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.UpdateLog Took 3 ms to seed version buckets with highest version 
1504137176933203968
   [junit4]   2> 2057257 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 2057258 INFO  
(coreLoadExecutor-3433-thread-1-processing-n:127.0.0.1:52375_) 
[n:127.0.0.1:52375_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 2057259 INFO  
(coreZkRegister-3427-thread-1-processing-n:127.0.0.1:52375_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52375_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController 
Register replica - core:collection1 address:http://127.0.0.1:52375 
collection:control_collection shard:shard1
   [junit4]   2> 2057260 INFO  
(coreZkRegister-3427-thread-1-processing-n:127.0.0.1:52375_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52375_ 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.c.SolrZkClient makePath: 
/collections/control_collection/leader_elect/shard1/election
   [junit4]   2> 2057267 INFO  
(coreZkRegister-3427-thread-1-processing-n:127.0.0.1:52375_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52375_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.LeaderElector 
Joined leadership election with path: 
/collections/control_collection/leader_elect/shard1/election/94008573416374275-core_node1-n_0000000000
   [junit4]   2> 2057270 INFO  
(coreZkRegister-3427-thread-1-processing-n:127.0.0.1:52375_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52375_ 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1
   [junit4]   2> 2057272 INFO  
(zkCallback-1810-thread-1-processing-n:127.0.0.1:52375_) [n:127.0.0.1:52375_    
] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue 
state SyncConnected
   [junit4]   2> 2057272 INFO  
(coreZkRegister-3427-thread-1-processing-n:127.0.0.1:52375_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52375_ 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2057273 INFO  
(OverseerStateUpdate-94008573416374275-127.0.0.1:52375_-n_0000000000) 
[n:127.0.0.1:52375_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message 
= {
   [junit4]   2>   "operation":"leader",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection"} current state version: 1
   [junit4]   2> 2057273 INFO  
(coreZkRegister-3427-thread-1-processing-n:127.0.0.1:52375_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52375_ 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2057273 INFO  
(coreZkRegister-3427-thread-1-processing-n:127.0.0.1:52375_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52375_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy 
Sync replicas to http://127.0.0.1:52375/collection1/
   [junit4]   2> 2057273 INFO  
(coreZkRegister-3427-thread-1-processing-n:127.0.0.1:52375_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52375_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy 
Sync Success - now sync replicas to me
   [junit4]   2> 2057273 INFO  
(coreZkRegister-3427-thread-1-processing-n:127.0.0.1:52375_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52375_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy 
http://127.0.0.1:52375/collection1/ has no replicas
   [junit4]   2> 2057273 INFO  
(coreZkRegister-3427-thread-1-processing-n:127.0.0.1:52375_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52375_ 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:52375/collection1/ shard1
   [junit4]   2> 2057273 INFO  
(coreZkRegister-3427-thread-1-processing-n:127.0.0.1:52375_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52375_ 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 2057281 INFO  
(zkCallback-1810-thread-1-processing-n:127.0.0.1:52375_) [n:127.0.0.1:52375_    
] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue 
state SyncConnected
   [junit4]   2> 2057282 INFO  
(OverseerStateUpdate-94008573416374275-127.0.0.1:52375_-n_0000000000) 
[n:127.0.0.1:52375_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message 
= {
   [junit4]   2>   "operation":"leader",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "base_url":"http://127.0.0.1:52375";,
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 1
   [junit4]   2> 2057385 INFO  
(zkCallback-1810-thread-1-processing-n:127.0.0.1:52375_) [n:127.0.0.1:52375_    
] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred 
- updating... (live nodes size: 1)
   [junit4]   2> 2057387 INFO  
(zkCallback-1810-thread-1-processing-n:127.0.0.1:52375_) [n:127.0.0.1:52375_    
] o.a.s.c.c.ZkStateReader Updated cluster state version to 2
   [junit4]   2> 2057434 INFO  
(coreZkRegister-3427-thread-1-processing-n:127.0.0.1:52375_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52375_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController 
We are http://127.0.0.1:52375/collection1/ and leader is 
http://127.0.0.1:52375/collection1/
   [junit4]   2> 2057434 INFO  
(coreZkRegister-3427-thread-1-processing-n:127.0.0.1:52375_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52375_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController 
No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:52375
   [junit4]   2> 2057434 INFO  
(coreZkRegister-3427-thread-1-processing-n:127.0.0.1:52375_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52375_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController 
I am the leader, no recovery necessary
   [junit4]   2> 2057434 INFO  
(coreZkRegister-3427-thread-1-processing-n:127.0.0.1:52375_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52375_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController 
publishing state=active
   [junit4]   2> 2057435 INFO  
(coreZkRegister-3427-thread-1-processing-n:127.0.0.1:52375_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52375_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController 
numShards not found on descriptor - reading it from system property
   [junit4]   2> 2057436 INFO  
(zkCallback-1810-thread-1-processing-n:127.0.0.1:52375_) [n:127.0.0.1:52375_    
] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue 
state SyncConnected
   [junit4]   2> 2057437 INFO  
(OverseerStateUpdate-94008573416374275-127.0.0.1:52375_-n_0000000000) 
[n:127.0.0.1:52375_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message 
= {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:52375";,
   [junit4]   2>   "node_name":"127.0.0.1:52375_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"} current state version: 2
   [junit4]   2> 2057438 INFO  
(OverseerStateUpdate-94008573416374275-127.0.0.1:52375_-n_0000000000) 
[n:127.0.0.1:52375_    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 
message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:52375";,
   [junit4]   2>   "node_name":"127.0.0.1:52375_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 2057502 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2057506 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2057507 INFO  (zkCallback-1812-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@5e31aa25 
name:ZooKeeperConnection Watcher:127.0.0.1:36393/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2057507 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2057507 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2057508 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 2057514 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection 
loss:false
   [junit4]   2> 2057541 INFO  (zkCallback-1812-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred 
- updating... (live nodes size: 1)
   [junit4]   2> 2057543 INFO  
(zkCallback-1810-thread-1-processing-n:127.0.0.1:52375_) [n:127.0.0.1:52375_    
] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred 
- updating... (live nodes size: 1)
   [junit4]   2> 2057543 INFO  (zkCallback-1812-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated cluster state version to 3
   [junit4]   2> 2057545 INFO  
(zkCallback-1810-thread-1-processing-n:127.0.0.1:52375_) [n:127.0.0.1:52375_    
] o.a.s.c.c.ZkStateReader Updated cluster state version to 3
   [junit4]   2> 2058031 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/shard-1-001/cores/collection1
   [junit4]   2> 2058032 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/shard-1-001
   [junit4]   2> 2058033 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] o.e.j.s.Server 
jetty-9.2.11.v20150529
   [junit4]   2> 2058040 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@5d8a0af4{/,null,AVAILABLE}
   [junit4]   2> 2058040 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@69d8feb2{HTTP/1.1}{127.0.0.1:45010}
   [junit4]   2> 2058040 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] o.e.j.s.Server 
Started @2061160ms
   [junit4]   2> 2058040 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/tempDir-001/jetty1,
 solrconfig=solrconfig.xml, hostContext=/, hostPort=54994, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/shard-1-001/cores}
   [junit4]   2> 2058041 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@c387f44
   [junit4]   2> 2058041 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/shard-1-001/'
   [junit4]   2> 2058063 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/shard-1-001/solr.xml
   [junit4]   2> 2058068 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.CoresLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/shard-1-001/cores
   [junit4]   2> 2058068 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 926539785
   [junit4]   2> 2058069 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/shard-1-001/]
   [junit4]   2> 2058069 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.CoreContainer loading shared library: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/shard-1-001/lib
   [junit4]   2> 2058069 WARN  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
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/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/shard-1-001/lib).
   [junit4]   2> 2058095 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme 
: ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 
10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 
5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
   [junit4]   2> 2058096 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2058097 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] o.a.s.l.LogWatcher 
SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2058097 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] o.a.s.l.LogWatcher 
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2058097 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36393/solr
   [junit4]   2> 2058097 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 2058097 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2058098 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2058100 INFO  (zkCallback-1814-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@4751fc09 
name:ZooKeeperConnection Watcher:127.0.0.1:36393 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2058101 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2058101 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2058104 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:54994_    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2058105 INFO  
(zkCallback-1815-thread-1-processing-n:127.0.0.1:54994_) [n:127.0.0.1:54994_    
] o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@78777c91 
name:ZooKeeperConnection Watcher:127.0.0.1:36393/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2058105 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:54994_    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2058114 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:54994_    ] 
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 2059119 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:54994_    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:54994_
   [junit4]   2> 2059119 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:54994_    ] 
o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:54994_
   [junit4]   2> 2059126 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:54994_    ] 
o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2059126 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:54994_    ] 
o.a.s.c.LeaderElector Joined leadership election with path: 
/overseer_elect/election/94008573416374278-127.0.0.1:54994_-n_0000000001
   [junit4]   2> 2059128 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:54994_    ] 
o.a.s.c.LeaderElector Watching path 
/overseer_elect/election/94008573416374275-127.0.0.1:52375_-n_0000000000 to 
know if I could be the leader
   [junit4]   2> 2059128 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:54994_    ] 
o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 2059128 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:54994_    ] 
o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 2059129 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:54994_    ] 
o.a.s.c.CoresLocator Looking for core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/shard-1-001/cores
   [junit4]   2> 2059130 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:54994_    ] 
o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, 
config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, 
instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/shard-1-001/cores/collection1,
 collection=collection1, 
absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/shard-1-001/cores/collection1/,
 coreNodeName=, dataDir=data/, shard=}
   [junit4]   2> 2059130 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:54994_    ] 
o.a.s.c.CoresLocator Found core collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/shard-1-001/cores/collection1/
   [junit4]   2> 2059130 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:54994_    ] 
o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 2059132 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:54994_    ] 
o.a.s.s.SolrDispatchFilter 
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1
   [junit4]   2> 2059132 INFO  
(TEST-HttpPartitionTest.test-seed#[547BEA79E2A6115B]) [n:127.0.0.1:54994_    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 2059134 INFO  
(coreLoadExecutor-3444-thread-1-processing-n:127.0.0.1:54994_) 
[n:127.0.0.1:54994_ c:collection1   x:collection1] o.a.s.c.ZkController 
publishing state=down
   [junit4]   2> 2059134 INFO  
(coreLoadExecutor-3444-thread-1-processing-n:127.0.0.1:54994_) 
[n:127.0.0.1:54994_ c:collection1   x:collection1] o.a.s.c.ZkController 
numShards not found on descriptor - reading it from system property
   [junit4]   2> 2059135 INFO  
(coreLoadExecutor-3444-thread-1-processing-n:127.0.0.1:54994_) 
[n:127.0.0.1:54994_ c:collection1   x:collection1] o.a.s.c.ZkController look 
for our core node name
   [junit4]   2> 2059136 INFO  
(zkCallback-1810-thread-1-processing-n:127.0.0.1:52375_) [n:127.0.0.1:52375_    
] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue 
state SyncConnected
   [junit4]   2> 2059137 INFO  
(OverseerStateUpdate-94008573416374275-127.0.0.1:52375_-n_0000000000) 
[n:127.0.0.1:52375_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message 
= {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:54994";,
   [junit4]   2>   "node_name":"127.0.0.1:54994_",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "operation":"state"} current state version: 3
   [junit4]   2> 2059139 INFO  
(OverseerStateUpdate-94008573416374275-127.0.0.1:52375_-n_0000000000) 
[n:127.0.0.1:52375_    ] o.a.s.c.o.ReplicaMutator Update state numShards=2 
message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:54994";,
   [junit4]   2>   "node_name":"127.0.0.1:54994_",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 2059139 INFO  
(OverseerStateUpdate-94008573416374275-127.0.0.1:52375_-n_0000000000) 
[n:127.0.0.1:52375_    ] o.a.s.c.o.ClusterStateMutator building a new cName: 
collection1
   [junit4]   2> 2059139 INFO  
(OverseerStateUpdate-94008573416374275-127.0.0.1:52375_-n_0000000000) 
[n:127.0.0.1:52375_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard 
shard=shard2
   [junit4]   2> 2059140 INFO  
(zkCallback-1810-thread-1-processing-n:127.0.0.1:52375_) [n:127.0.0.1:52375_    
] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred 
- updating... (live nodes size: 2)
   [junit4]   2> 2059140 INFO  (zkCallback-1812-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred 
- updating... (live nodes size: 2)
   [junit4]   2> 2059140 INFO  
(zkCallback-1815-thread-1-processing-n:127.0.0.1:54994_) [n:127.0.0.1:54994_    
] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred 
- updating... (live nodes size: 2)
   [junit4]   2> 2059141 INFO  (zkCallback-1812-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated cluster state version to 4
   [junit4]   2> 2059142 INFO  
(zkCallback-1815-thread-1-processing-n:127.0.0.1:54994_) [n:127.0.0.1:54994_    
] o.a.s.c.c.ZkStateReader Updated cluster state version to 4
   [junit4]   2> 2059143 INFO  
(zkCallback-1810-thread-1-processing-n:127.0.0.1:52375_) [n:127.0.0.1:52375_    
] o.a.s.c.c.ZkStateReader Updated cluster state version to 4
   [junit4]   2> 2060135 INFO  
(coreLoadExecutor-3444-thread-1-processing-n:127.0.0.1:54994_) 
[n:127.0.0.1:54994_ c:collection1   x:collection1] o.a.s.c.ZkController waiting 
to find shard id in clusterstate for collection1
   [junit4]   2> 2060136 INFO  
(coreLoadExecutor-3444-thread-1-processing-n:127.0.0.1:54994_) 
[n:127.0.0.1:54994_ c:collection1   x:collection1] o.a.s.c.ZkController Check 
for collection zkNode:collection1
   [junit4]   2> 2060137 INFO  
(coreLoadExecutor-3444-thread-1-processing-n:127.0.0.1:54994_) 
[n:127.0.0.1:54994_ c:collection1   x:collection1] o.a.s.c.ZkController 
Collection zkNode exists
   [junit4]   2> 2060137 INFO  
(coreLoadExecutor-3444-thread-1-processing-n:127.0.0.1:54994_) 
[n:127.0.0.1:54994_ c:collection1   x:collection1] o.a.s.c.c.ZkStateReader Load 
collection config from:/collections/collection1
   [junit4]   2> 2060137 INFO  
(coreLoadExecutor-3444-thread-1-processing-n:127.0.0.1:54994_) 
[n:127.0.0.1:54994_ c:collection1   x:collection1] o.a.s.c.c.ZkStateReader 
path=/collections/collection1 configName=conf1 specified config exists in 
ZooKeeper
   [junit4]   2> 2060138 INFO  
(coreLoadExecutor-3444-thread-1-processing-n:127.0.0.1:54994_) 
[n:127.0.0.1:54994_ c:collection1   x:collection1] o.a.s.c.SolrResourceLoader 
new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_547BEA79E2A6115B-001/shard-1-001/cores/collection1/'
   [junit4]   2> 2060163 INFO  
(coreLoadExecutor-3444-thread-1-processing-n:127.0.0.1:54994_) 
[n:127.0.0.1:54994_ c:collection1   x:collection1] o.a.s.c.Config loaded config 
solrconfig.xml with version 0 
   [junit4]   2> 2060170 INFO  
(coreLoadExecutor-3444-thread-1-processing-n:127.0.0.1:54994_) 
[n:127.0.0.1:54994_ c:collection1   x:collection1] o.a.s.c.SolrConfig current 
version of requestparams : -1
   [junit4]   2> 2060174 INFO  
(coreLoadExecutor-3444-thread-1-processing-n:127.0.0.1:54994_) 
[n:127.0.0.1:54994_ c:collection1   x:collection1] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.0.0
   [junit4]   2> 2060186 INFO  
(coreLoadExecutor-3444-thread-1-processing-n:127.0.0.1:54994_) 
[n:127.0.0.1:54994_ c:collection1   x:collection1] o.a.s.c.Config Loaded 
SolrConfig: solrconfig.xml
   [junit4]   2> 2060187 INFO  
(coreLoadExecutor-3444-thread-1-processing-n:127.0.0.1:54994_) 
[n:127.0.0.1:54994_ c:collection1   x:collection1] o.a.s.s.IndexSchema Reading 
Solr Schema from /configs/conf1/schema.xml
   [junit4]   2> 2060193 INFO  
(coreLoadExecutor-3444-thread-1-processing-n:127.0.0.1:54994_) 
[n:127.0.0.1:54994_ c:collection1   x:collection1] o.a.s.s.IndexSchema 
[collection1] Schema name=test
   [junit4]   2> 2060321 INFO  
(coreLoadExecutor-3444-thread-1-processing-n:127.0.0.1:54994_) 
[n:127.0.0.1:54994_ c:collection1   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with 
rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 2060330 INFO  
(coreLoadExecutor-3444-thread-1-processing-n:127.0.0.1:54994_) 
[n:127.0.0.1:54994_ c:collection1   x:collection1] o.a.s.s.IndexSchema default 
search field in schema is text
   [junit4]   2> 2060332 INFO  
(coreLoadExecutor-3444-thread-1-processing-n:127.0.0.1:54994_) 
[n:127.0.0.1:54994_ c:collection1   x:collection1] o.a.s.s.IndexSchema unique 
key field: id
   [junit4]   2> 2060343 INFO  
(coreLoadExecutor-3444-thread-1-processing-n:127.0.0.1:54994_) 
[n:127.0.0.1:54994_ c:collection1   x:collection1] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 2060346 INFO  
(coreLoadExecutor-3444-thread-1-processing-n:127.0.0.1:54994_) 
[n:127.0.0.1:54994_ c:collection1   x:collection1] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 2060350 INFO  
(coreLoadExecutor-3444-thread-1-processing-n:127.0.0.1:54994_) 
[n:127.0.0.1:54994_ c:collection1   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 2060351 WARN  
(coreLoadExecutor-3444-thread-1-processing-n:127.0.0.1:54994_) 
[n:127.0.0.1:54994_ c:collection1   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 2060352 WARN  
(coreLoadExecutor-3444-thread-1-processing-n:127.0.0.1:54994_) 
[n:127.0.0.1:54994_ c:collection1   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 2060352 INFO  
(coreLoadExecutor-3444-thread-1-processing-n:127.0.0.1:54994_) 
[n:127.0.0.1:54994_ c:collection1   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [juni

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

le.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> 4006585 INFO  
(SUITE-HdfsChaosMonkeySafeLeaderTest-seed#[547BEA79E2A6115B]-worker) 
[n:127.0.0.1:48730_ c:collection1 s:shard1 r:core_node5 x:collection1] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsChaosMonkeySafeLeaderTest_547BEA79E2A6115B-001
   [junit4]   2> Jun 16, 2015 8:49:03 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 3 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=CheapBastard, 
sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=sq, 
timezone=Pacific/Wallis
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 
1.8.0_25 (64-bit)/cpus=4,threads=3,free=255404328,total=516947968
   [junit4]   2> NOTE: All tests run in this JVM: [TestHighlightDedupGrouping, 
TestDynamicFieldResource, TestSolrConfigHandler, TestRemoteStreaming, 
TestStandardQParsers, CSVRequestHandlerTest, 
TestDynamicFieldCollectionResource, TestBadConfig, TestIBSimilarityFactory, 
DeleteInactiveReplicaTest, LeaderElectionIntegrationTest, ClusterStateTest, 
RequestHandlersTest, HighlighterTest, TestDistributedMissingSort, 
TestWordDelimiterFilterFactory, TestDocBasedVersionConstraints, 
MergeStrategyTest, TestRestoreCore, HdfsDirectoryFactoryTest, 
SolrRequestParserTest, RemoteQueryErrorTest, SolrTestCaseJ4Test, 
TestRandomFaceting, TestRestManager, TestAddFieldRealTimeGet, TestCodecSupport, 
RulesTest, TestFieldResource, TestMacros, TestBinaryField, 
DistributedFacetPivotWhiteBoxTest, SpatialRPTFieldTypeTest, 
TestManagedSynonymFilterFactory, StandardRequestHandlerTest, 
BaseCdcrDistributedZkTest, TestHighFrequencyDictionaryFactory, 
TestSolr4Spatial, SegmentsInfoRequestHandlerTest, TestJmxIntegration, 
SolrIndexConfigTest, TestCloudManagedSchema, TestRealTimeGet, 
TestCloudInspectUtil, SyncSliceTest, SchemaVersionSpecificBehaviorTest, 
PingRequestHandlerTest, TestUpdate, TestFreeTextSuggestions, 
TermsComponentTest, BasicZkTest, TestInfoStreamLogging, TestCSVLoader, 
ExternalFileFieldSortTest, TestTrackingShardHandlerFactory, 
DistributedFacetPivotLongTailTest, TestBlendedInfixSuggestions, 
TestReRankQParserPlugin, TestCollapseQParserPlugin, 
ChaosMonkeyNothingIsSafeTest, HdfsRecoverLeaseTest, AliasIntegrationTest, 
SimpleCollectionCreateDeleteTest, LeaderFailoverAfterPartitionTest, 
SolrCoreTest, TestIndexingPerformance, ParsingFieldUpdateProcessorsTest, 
SignatureUpdateProcessorFactoryTest, HighlighterConfigTest, TimeZoneUtilsTest, 
MBeansHandlerTest, DateFieldTest, MultiTermTest, UUIDFieldTest, 
DistributedIntervalFacetingTest, ResponseHeaderTest, CdcrUpdateLogTest, 
DeleteShardTest, ExitableDirectoryReaderTest, TestRangeQuery, 
DistributedSpellCheckComponentTest, SuggesterWFSTTest, BasicDistributedZkTest, 
OverseerTest, FullSolrCloudDistribCmdsTest, TestReplicationHandler, 
LeaderElectionTest, TestZkChroot, TestRandomDVFaceting, ShardRoutingCustomTest, 
TestDistributedSearch, AutoCommitTest, TestDistributedGrouping, TestFaceting, 
TestHashPartitioner, ZkControllerTest, TestJoin, TestReload, 
TestStressVersions, HardAutoCommitTest, DistributedTermsComponentTest, 
SimpleFacetsTest, SpellCheckComponentTest, TestGroupingSearch, 
SolrCmdDistributorTest, QueryElevationComponentTest, PeerSyncTest, 
TestFunctionQuery, TestLazyCores, OverseerCollectionProcessorTest, 
SoftAutoCommitTest, ShowFileRequestHandlerTest, CurrencyFieldXmlFileTest, 
SolrIndexSplitterTest, SimplePostToolTest, AnalysisAfterCoreReloadTest, 
TestExtendedDismaxParser, SpellCheckCollatorTest, SuggesterFSTTest, 
CoreAdminHandlerTest, TestFoldingMultitermQuery, SuggesterTSTTest, 
SuggesterTest, TestStressLucene, SpatialFilterTest, NoCacheHeaderTest, 
TestPseudoReturnFields, FieldMutatingUpdateProcessorTest, 
TestAtomicUpdateErrorCases, QueryEqualityTest, DefaultValueUpdateProcessorTest, 
StatelessScriptUpdateProcessorFactoryTest, SortByFunctionTest, 
DocValuesMultiTest, TestSolrDeletionPolicy1, SolrInfoMBeanTest, 
DebugComponentTest, IndexBasedSpellCheckerTest, TestQueryUtils, 
TestReversedWildcardFilterFactory, TestQueryTypes, PrimitiveFieldTypeTest, 
TestOmitPositions, FileBasedSpellCheckerTest, XmlUpdateRequestHandlerTest, 
DocumentBuilderTest, TestValueSourceCache, TermVectorComponentTest, 
FieldAnalysisRequestHandlerTest, TestArbitraryIndexDir, 
FastVectorHighlighterTest, IndexSchemaTest, TestCollationField, 
UpdateRequestProcessorFactoryTest, QueryParsingTest, TestAnalyzedSuggestions, 
UniqFieldsUpdateProcessorFactoryTest, JsonLoaderTest, TestComponentsName, 
SearchHandlerTest, TestQuerySenderListener, TestSolrIndexConfig, CopyFieldTest, 
TestStressRecovery, TestMergePolicyConfig, SampleTest, TestDocSet, 
MinimalSchemaTest, TestElisionMultitermQuery, TestConfig, 
TestFuzzyAnalyzedSuggestions, TestSolrCoreProperties, 
TestPostingsSolrHighlighter, DirectSolrConnectionTest, 
NotRequiredUniqueKeyTest, TestPhraseSuggestions, SpellPossibilityIteratorTest, 
TestCharFilters, TestXIncludeConfig, EchoParamsTest, 
TestSweetSpotSimilarityFactory, TestDFRSimilarityFactory, 
TestPerFieldSimilarity, TestLMDirichletSimilarityFactory, 
TestLMJelinekMercerSimilarityFactory, TestDefaultSimilarityFactory, 
TestFastWriter, TestJmxMonitoredMap, ResourceLoaderTest, TestFastOutputStream, 
OpenExchangeRatesOrgProviderTest, URLClassifyProcessorTest, PluginInfoTest, 
DateMathParserTest, PreAnalyzedFieldTest, SpellingQueryConverterTest, 
TestUtils, TestDocumentBuilder, ZkNodePropsTest, SliceStateTest, 
SystemInfoHandlerTest, FileUtilsTest, CircularListTest, CursorPagingTest, 
TestSimpleTrackingShardHandler, TestEmbeddedSolrServerConstructors, 
TestJettySolrRunner, AssignTest, CloudExitableDirectoryReaderTest, 
CollectionStateFormat2Test, ConnectionManagerTest, CustomCollectionTest, 
DeleteReplicaTest, DistribCursorPagingTest, 
DistribDocExpirationUpdateProcessorTest, DistribJoinFromCollectionTest, 
DistributedVersionInfoTest, LeaderInitiatedRecoveryOnCommitTest, 
MultiThreadedOCPTest, RecoveryAfterSoftCommitTest, 
SharedFSAutoReplicaFailoverUtilsTest, SolrCloudExampleTest, SolrXmlInZkTest, 
TestCloudPivotFacet, TestCollectionAPI, TestDistribDocBasedVersion, 
TestDownShardTolerantSearch, TestMiniSolrCloudCluster, 
HdfsChaosMonkeySafeLeaderTest]
   [junit4] Completed [418/511] on J0 in 141.42s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 309 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:533:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:474:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:61:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/extra-targets.xml:39:
 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:513:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1426:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:981:
 There were test failures: 511 suites, 2010 tests, 1 suite-level error, 2 
errors, 1 failure, 20 ignored (10 assumptions)

Total time: 228 minutes 59 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