lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-NightlyTests-5.3 - Build # 13 - Still Failing
Date Fri, 15 Jan 2016 03:09:32 GMT
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.3/13/

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

Error Message:
Error from server at http://127.0.0.1:49638: 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:49638: 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:234)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226)
	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.makeRequest(CollectionsAPIDistributedZkTest.java:301)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:418)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:168)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:963)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
	at java.lang.Thread.run(Thread.java:745)


FAILED:  org.apache.solr.handler.TestReplicationHandler.doTestStressReplication

Error Message:
[index.20160115040133335, replication.properties, index.properties, index.20160115040140809] expected:<1> but was:<2>

Stack Trace:
java.lang.AssertionError: [index.20160115040133335, replication.properties, index.properties, index.20160115040140809] expected:<1> but was:<2>
	at __randomizedtesting.SeedInfo.seed([2D238DE4032FCB38:F6888D220607A28B]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.failNotEquals(Assert.java:647)
	at org.junit.Assert.assertEquals(Assert.java:128)
	at org.junit.Assert.assertEquals(Assert.java:472)
	at org.apache.solr.handler.TestReplicationHandler.checkForSingleIndex(TestReplicationHandler.java:818)
	at org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:785)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
	at java.lang.Thread.run(Thread.java:745)


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

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

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=76535, name=collection3, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest]
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:59401: Could not find collection : awholynewstresscollection_collection3_0
	at __randomizedtesting.SeedInfo.seed([2D238DE4032FCB38]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1098)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:869)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:805)
	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:894)




Build Log:
[...truncated 11066 lines...]
   [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/init-core-data-001
   [junit4]   2> 1060342 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[2D238DE4032FCB38]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 1060342 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[2D238DE4032FCB38]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 1060349 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1060349 INFO  (Thread-3839) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1060350 INFO  (Thread-3839) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1060450 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.ZkTestServer start zk server on port:49278
   [junit4]   2> 1060450 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1060450 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1060453 INFO  (zkCallback-848-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@bc6f13 name:ZooKeeperConnection Watcher:127.0.0.1:49278 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1060454 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1060454 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1060454 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 1060457 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1060461 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1060464 INFO  (zkCallback-849-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1bbab384 name:ZooKeeperConnection Watcher:127.0.0.1:49278/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1060464 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1060464 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1060465 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 1060467 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 1060468 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 1060470 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 1060471 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 1060472 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 1060477 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
   [junit4]   2> 1060477 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 1060481 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1060481 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1060483 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
   [junit4]   2> 1060483 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 1060484 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
   [junit4]   2> 1060485 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 1060486 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
   [junit4]   2> 1060486 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 1060490 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
   [junit4]   2> 1060490 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 1060494 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 1060494 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 1060499 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1060499 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1060501 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
   [junit4]   2> 1060502 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 1060503 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
   [junit4]   2> 1060503 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 1060613 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/control-001/cores/collection1
   [junit4]   2> 1060615 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.e.j.s.Server jetty-9.2.11.v20150529
   [junit4]   2> 1060617 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1197fe08{/,null,AVAILABLE}
   [junit4]   2> 1060621 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.e.j.s.ServerConnector Started ServerConnector@11a6c83e{HTTP/1.1}{127.0.0.1:57099}
   [junit4]   2> 1060621 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.e.j.s.Server Started @1064441ms
   [junit4]   2> 1060621 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/control-001/cores, hostPort=57099, solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/tempDir-001/control/data}
   [junit4]   2> 1060622 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4f6de641
   [junit4]   2> 1060622 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/control-001/'
   [junit4]   2> 1060648 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1060649 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1060651 INFO  (zkCallback-850-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5f7b17dd name:ZooKeeperConnection Watcher:127.0.0.1:49278/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1060651 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1060652 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1060653 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 1060653 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/control-001/solr.xml
   [junit4]   2> 1060669 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/control-001/cores
   [junit4]   2> 1060670 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.CoreContainer New CoreContainer 1209720047
   [junit4]   2> 1060670 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/control-001/]
   [junit4]   2> 1060670 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/control-001/lib
   [junit4]   2> 1060670 WARN  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/control-001/lib).
   [junit4]   2> 1060680 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] 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> 1060681 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1060682 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1060682 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1060683 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49278/solr
   [junit4]   2> 1060683 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 1060683 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1060683 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1060685 INFO  (zkCallback-852-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@27fe8cd8 name:ZooKeeperConnection Watcher:127.0.0.1:49278 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1060686 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1060686 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1060688 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1060689 INFO  (zkCallback-853-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5db71691 name:ZooKeeperConnection Watcher:127.0.0.1:49278/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1060690 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1060691 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 1060693 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
   [junit4]   2> 1060696 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
   [junit4]   2> 1060700 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
   [junit4]   2> 1060703 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
   [junit4]   2> 1060705 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 1060707 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 1060709 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 1060710 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 1060712 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57099_
   [junit4]   2> 1060714 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:57099_
   [junit4]   2> 1060716 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 1060718 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
   [junit4]   2> 1060720 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1060721 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95212126147510276-127.0.0.1:57099_-n_0000000000
   [junit4]   2> 1060721 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:57099_
   [junit4]   2> 1060722 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 1060723 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.c.Overseer Overseer (id=95212126147510276-127.0.0.1:57099_-n_0000000000) starting
   [junit4]   2> 1060726 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 1060735 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 1060735 INFO  (OverseerCollectionProcessor-95212126147510276-127.0.0.1:57099_-n_0000000000) [n:127.0.0.1:57099_    ] o.a.s.c.OverseerCollectionProcessor Process current queue of collection creations
   [junit4]   2> 1060735 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1060736 INFO  (OverseerStateUpdate-95212126147510276-127.0.0.1:57099_-n_0000000000) [n:127.0.0.1:57099_    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 1060752 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 1060752 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1060753 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/control-001/cores
   [junit4]   2> 1060754 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.c.SolrCore Created CoreDescriptor: {configSetProperties=configsetprops.json, loadOnStartup=true, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/control-001/cores/collection1/, config=solrconfig.xml, coreNodeName=, transient=false, schema=schema.xml, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/control-001/cores/collection1, dataDir=data/, collection=control_collection, shard=, name=collection1}
   [junit4]   2> 1060754 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/control-001/cores/collection1/
   [junit4]   2> 1060755 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 1060755 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 1060755 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 1060755 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1
   [junit4]   2> 1060756 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:57099_    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 1060756 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection   x:collection1] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 1060756 INFO  (zkCallback-853-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 1060757 INFO  (OverseerStateUpdate-95212126147510276-127.0.0.1:57099_-n_0000000000) [n:127.0.0.1:57099_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "roles":null,
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "node_name":"127.0.0.1:57099_",
   [junit4]   2>   "base_url":"http://127.0.0.1:57099"} current state version: 0
   [junit4]   2> 1060758 INFO  (OverseerStateUpdate-95212126147510276-127.0.0.1:57099_-n_0000000000) [n:127.0.0.1:57099_    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "roles":null,
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "node_name":"127.0.0.1:57099_",
   [junit4]   2>   "base_url":"http://127.0.0.1:57099"}
   [junit4]   2> 1060758 INFO  (OverseerStateUpdate-95212126147510276-127.0.0.1:57099_-n_0000000000) [n:127.0.0.1:57099_    ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
   [junit4]   2> 1060758 INFO  (OverseerStateUpdate-95212126147510276-127.0.0.1:57099_-n_0000000000) [n:127.0.0.1:57099_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 1060759 INFO  (zkCallback-853-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_    ] 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> 1060761 INFO  (zkCallback-853-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 1
   [junit4]   2> 1061756 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection   x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 1061757 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection   x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection
   [junit4]   2> 1061757 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection   x:collection1] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 1061757 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection
   [junit4]   2> 1061759 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 1061759 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/control-001/cores/collection1/'
   [junit4]   2> 1061776 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection   x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 
   [junit4]   2> 1061784 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection   x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1061798 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.2
   [junit4]   2> 1061826 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection   x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1061827 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection   x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml
   [junit4]   2> 1061834 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1062075 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1062082 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection   x:collection1] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1062084 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection   x:collection1] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1062097 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1062101 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1062104 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1062105 WARN  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1062106 WARN  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1062106 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1062110 WARN  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1062110 WARN  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1062112 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection   x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1
   [junit4]   2> 1062112 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
   [junit4]   2> 1062112 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1062113 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/control-001/cores/collection1/], dataDir=[null]
   [junit4]   2> 1062113 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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@4634b96
   [junit4]   2> 1062113 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/control-001/cores/collection1/data
   [junit4]   2> 1062113 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/control-001/cores/collection1/data/index/
   [junit4]   2> 1062114 WARN  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 1062114 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/control-001/cores/collection1/data/index
   [junit4]   2> 1062114 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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=24, maxMergeAtOnceExplicit=38, maxMergedSegmentMB=26.45703125, floorSegmentMB=1.1279296875, forceMergeDeletesPctAllowed=13.981939693936257, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5278915465591818
   [junit4]   2> 1062115 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 	commit{dir=RawDirectoryWrapper(RAMDirectory@37ea4dab lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7791323a),segFN=segments_1,generation=1}
   [junit4]   2> 1062115 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 1062115 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:57099_ 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-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/control-001/cores/collection1/data/
   [junit4]   2> 1062116 WARN  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
   [junit4]   2> 1062120 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 1062120 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 1062120 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1062121 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1062121 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1062121 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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> 1062121 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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> 1062121 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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> 1062122 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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> 1062124 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1062126 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1062127 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1062128 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1062132 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/logging,/schema,/get,/admin/system,/admin/segments,/admin/mbeans,/replication,/config,/update/json,/update/json/docs,/admin/plugins,/update/csv,/admin/file,/admin/ping,/admin/properties,standard,/admin/threads,/admin/luke,/update
   [junit4]   2> 1062134 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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> 1062134 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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> 1062134 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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> 1062135 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 1062135 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 1062136 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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=25, maxMergeAtOnceExplicit=23, maxMergedSegmentMB=43.4951171875, floorSegmentMB=1.0810546875, forceMergeDeletesPctAllowed=20.52228972464659, segmentsPerTier=8.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1062136 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 	commit{dir=RawDirectoryWrapper(RAMDirectory@37ea4dab lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7791323a),segFN=segments_1,generation=1}
   [junit4]   2> 1062136 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 1062137 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@199e0bed[collection1] main
   [junit4]   2> 1062137 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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> 1062138 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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> 1062138 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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> 1062138 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 1062139 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 1062139 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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> 1062139 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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> 1062139 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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> 1062140 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1062140 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1062140 INFO  (searcherExecutor-2775-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@199e0bed[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1062141 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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> 1062141 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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> 1062141 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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> 1062141 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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 1523394020231872512
   [junit4]   2> 1062144 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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 1523394020231872512
   [junit4]   2> 1062144 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 1062145 INFO  (coreLoadExecutor-2774-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 1062146 INFO  (coreZkRegister-2768-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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:57099 collection:control_collection shard:shard1
   [junit4]   2> 1062147 INFO  (coreZkRegister-2768-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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> 1062151 INFO  (coreZkRegister-2768-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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/95212126147510276-core_node1-n_0000000000
   [junit4]   2> 1062152 INFO  (coreZkRegister-2768-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1
   [junit4]   2> 1062154 INFO  (zkCallback-853-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 1062154 INFO  (coreZkRegister-2768-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1062154 INFO  (coreZkRegister-2768-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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> 1062154 INFO  (coreZkRegister-2768-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:57099/collection1/
   [junit4]   2> 1062154 INFO  (coreZkRegister-2768-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 1062165 INFO  (coreZkRegister-2768-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:57099/collection1/ has no replicas
   [junit4]   2> 1062166 INFO  (coreZkRegister-2768-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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:57099/collection1/ shard1
   [junit4]   2> 1062166 INFO  (coreZkRegister-2768-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 1062167 INFO  (OverseerStateUpdate-95212126147510276-127.0.0.1:57099_-n_0000000000) [n:127.0.0.1:57099_    ] 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> 1062171 INFO  (zkCallback-853-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 1062172 INFO  (OverseerStateUpdate-95212126147510276-127.0.0.1:57099_-n_0000000000) [n:127.0.0.1:57099_    ] 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:57099",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 1
   [junit4]   2> 1062258 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1062258 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1062260 INFO  (zkCallback-855-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2adf7cec name:ZooKeeperConnection Watcher:127.0.0.1:49278/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1062260 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1062261 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1062261 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1062265 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 1062265 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
   [junit4]   2> 1062265 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1062266 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1062267 INFO  (zkCallback-856-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4b841149 name:ZooKeeperConnection Watcher:127.0.0.1:49278/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1062267 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1062268 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1062269 INFO  (zkCallback-853-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 1062271 INFO  (OverseerStateUpdate-95212126147510276-127.0.0.1:57099_-n_0000000000) [n:127.0.0.1:57099_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"create",
   [junit4]   2>   "name":"collection1",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "stateFormat":"2"} current state version: 1
   [junit4]   2> 1062271 INFO  (OverseerStateUpdate-95212126147510276-127.0.0.1:57099_-n_0000000000) [n:127.0.0.1:57099_    ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1
   [junit4]   2> 1062272 INFO  (zkCallback-853-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_    ] 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> 1062273 INFO  (zkCallback-855-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> 1062275 INFO  (zkCallback-853-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 2
   [junit4]   2> 1062279 INFO  (zkCallback-855-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 2
   [junit4]   2> 1062322 INFO  (coreZkRegister-2768-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:57099/collection1/ and leader is http://127.0.0.1:57099/collection1/
   [junit4]   2> 1062322 INFO  (coreZkRegister-2768-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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:57099
   [junit4]   2> 1062322 INFO  (coreZkRegister-2768-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1062323 INFO  (coreZkRegister-2768-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:57099_) [n:127.0.0.1:57099_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active
   [junit4]   2> 1062323 INFO  (coreZkRegister-2768-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:57099_) [n:127.0.0.1:57099_ 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> 1062325 INFO  (zkCallback-853-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 1062326 INFO  (OverseerStateUpdate-95212126147510276-127.0.0.1:57099_-n_0000000000) [n:127.0.0.1:57099_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "roles":null,
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "node_name":"127.0.0.1:57099_",
   [junit4]   2>   "base_url":"http://127.0.0.1:57099"} current state version: 2
   [junit4]   2> 1062326 INFO  (OverseerStateUpdate-95212126147510276-127.0.0.1:57099_-n_0000000000) [n:127.0.0.1:57099_    ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
   [junit4]   2>   "roles":null,
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "node_name":"127.0.0.1:57099_",
   [junit4]   2>   "base_url":"http://127.0.0.1:57099"}
   [junit4]   2> 1062327 INFO  (OverseerStateUpdate-95212126147510276-127.0.0.1:57099_-n_0000000000) [n:127.0.0.1:57099_    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json
   [junit4]   2> 1062328 INFO  (zkCallback-855-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> 1062328 INFO  (zkCallback-853-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_    ] 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> 1062330 INFO  (zkCallback-855-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3
   [junit4]   2> 1062331 INFO  (zkCallback-853-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3
   [junit4]   2> 1062359 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/shard-1-001/cores/collection1
   [junit4]   2> 1062360 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/shard-1-001
   [junit4]   2> 1062361 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.e.j.s.Server jetty-9.2.11.v20150529
   [junit4]   2> 1062363 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3a51e5d5{/,null,AVAILABLE}
   [junit4]   2> 1062363 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.e.j.s.ServerConnector Started ServerConnector@6ce57806{HTTP/1.1}{127.0.0.1:49638}
   [junit4]   2> 1062364 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.e.j.s.Server Started @1066184ms
   [junit4]   2> 1062364 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/shard-1-001/cores, hostPort=49638, solrconfig=solrconfig.xml, solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/tempDir-001/jetty1, hostContext=/}
   [junit4]   2> 1062364 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4f6de641
   [junit4]   2> 1062364 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/shard-1-001/'
   [junit4]   2> 1062383 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1062385 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1062385 INFO  (zkCallback-857-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2ee0626a name:ZooKeeperConnection Watcher:127.0.0.1:49278/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1062385 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1062386 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1062387 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 1062387 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/shard-1-001/solr.xml
   [junit4]   2> 1062402 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/shard-1-001/cores
   [junit4]   2> 1062402 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.CoreContainer New CoreContainer 1061336936
   [junit4]   2> 1062402 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/shard-1-001/]
   [junit4]   2> 1062402 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/shard-1-001/lib
   [junit4]   2> 1062402 WARN  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/shard-1-001/lib).
   [junit4]   2> 1062410 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] 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> 1062412 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1062412 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1062412 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1062412 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49278/solr
   [junit4]   2> 1062412 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 1062413 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1062414 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1062416 INFO  (zkCallback-859-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2cac8de8 name:ZooKeeperConnection Watcher:127.0.0.1:49278 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1062417 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1062417 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1062419 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:49638_    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1062421 INFO  (zkCallback-860-thread-1-processing-n:127.0.0.1:49638_) [n:127.0.0.1:49638_    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@d262195 name:ZooKeeperConnection Watcher:127.0.0.1:49278/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1062421 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:49638_    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1062426 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:49638_    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1062434 INFO  (zkCallback-855-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> 1062434 INFO  (zkCallback-853-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_    ] 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> 1062434 INFO  (zkCallback-860-thread-1-processing-n:127.0.0.1:49638_) [n:127.0.0.1:49638_    ] 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> 1062437 INFO  (zkCallback-855-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4
   [junit4]   2> 1062437 INFO  (zkCallback-853-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4
   [junit4]   2> 1062438 INFO  (zkCallback-860-thread-1-processing-n:127.0.0.1:49638_) [n:127.0.0.1:49638_    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4
   [junit4]   2> 1063432 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:49638_    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49638_
   [junit4]   2> 1063433 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:49638_    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:49638_
   [junit4]   2> 1063436 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:49638_    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1063436 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:49638_    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95212126147510281-127.0.0.1:49638_-n_0000000001
   [junit4]   2> 1063438 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:49638_    ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95212126147510276-127.0.0.1:57099_-n_0000000000 to know if I could be the leader
   [junit4]   2> 1063455 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:49638_    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 1063455 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:49638_    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1063456 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:49638_    ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/shard-1-001/cores
   [junit4]   2> 1063457 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:49638_    ] o.a.s.c.SolrCore Created CoreDescriptor: {coreNodeName=, loadOnStartup=true, shard=, name=collection1, config=solrconfig.xml, configSetProperties=configsetprops.json, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/shard-1-001/cores/collection1/, transient=false, collection=collection1, dataDir=data/, schema=schema.xml, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/shard-1-001/cores/collection1}
   [junit4]   2> 1063457 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:49638_    ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D238DE4032FCB38-001/shard-1-001/cores/collection1/
   [junit4]   2> 1063457 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:49638_    ] o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 1063459 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:49638_    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1
   [junit4]   2> 1063459 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[2D238DE4032FCB38]) [n:127.0.0.1:49638_    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 1063460 INFO  (coreLoadExecutor-2785-thread-1-processing-n:127.0.0.1:49638_) [n:127.0.0.1:49638_ c:collection1   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 1063460 INFO  (coreLoadExecutor-2785-thread-1-processing-n:127.0.0.1:49638_) [n:127.0.0.1:49638_ c:collection1   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 1063461 INFO  (zkCallback-853-thread-1-processing-n:127.0.0.1:57099_) [n:127.0.0.1:57099_    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 1063462 INFO  (coreLoadExecutor-2785-thread-1-processing-n:127.0.0.1:49638_) [n:127.0.0.1:49638_ c:collection1   x:collection1] o.a.s.c.ZkController Registering watch for external collection collection1
   [junit4]   2> 1063462 INFO  (coreLoadExecutor-2785-thread-1-processing-n:127.0.0.1:49638_) [n:127.0.0.1:49638_ c:collection1   x:collection1] o.a.s.c.c.ZkStateReader addZkWatch collection1
   [junit4]   2> 1063462 INFO  (OverseerStateUpdate-95212126147510276-127.0.0.1:57099_-n_0000000000) [n:127.0.0.1:570

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

e(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> 6564462 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[2D238DE4032FCB38]-worker) [n:127.0.0.1:46937_ c:addReplicaColl s:shard2 r:core_node6 x:addReplicaColl_shard2_replica3] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> Jan 15, 2016 3:07:33 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 2 leaked thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene53): {id=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), other_tl1=FSTOrd50, text=PostingsFormat(name=LuceneVarGapFixedInterval), _version_=FSTOrd50, intDefault=FSTOrd50, multiDefault=PostingsFormat(name=Direct), timestamp=PostingsFormat(name=Direct), range_facet_l_dv=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), range_facet_i_dv=PostingsFormat(name=Direct), a_t=PostingsFormat(name=Direct), a_i1=PostingsFormat(name=Direct), range_facet_l=PostingsFormat(name=Direct)}, docValues:{timestamp=DocValuesFormat(name=Memory), range_facet_l_dv=DocValuesFormat(name=Lucene50), range_facet_i_dv=DocValuesFormat(name=Memory)}, sim=DefaultSimilarity, locale=ar_DZ, timezone=Asia/Makassar
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=4,threads=2,free=107417440,total=534249472
   [junit4]   2> NOTE: All tests run in this JVM: [SuggesterFSTTest, TestCodecSupport, TestConfigSets, TestQuerySenderListener, TestHighlightDedupGrouping, TestIntervalFaceting, TestJmxIntegration, TestOverriddenPrefixQueryForCustomFieldType, TestCollectionAPI, SaslZkACLProviderTest, HdfsNNFailoverTest, TestInitParams, TestCloudSchemaless, AsyncCallRequestStatusResponseTest, HighlighterTest, TestBulkSchemaConcurrent, SmileWriterTest, TestSolr4Spatial, TestConfigOverlay, UniqFieldsUpdateProcessorFactoryTest, BJQParserTest, BigEndianAscendingWordSerializerTest, TestSimpleQParserPlugin, UpdateRequestProcessorFactoryTest, TestSolrIndexConfig, TestReloadAndDeleteDocs, MigrateRouteKeyTest, TestIndexingPerformance, TestStressUserVersions, DefaultValueUpdateProcessorTest, TestManagedSchemaFieldTypeResource, DistributedSuggestComponentTest, TestRuleBasedAuthorizationPlugin, DistanceUnitsTest, HdfsWriteToMultipleCollectionsTest, HdfsThreadLeakTest, SimplePostToolTest, TestBinaryField, LeaderElectionIntegrationTest, TestWordDelimiterFilterFactory, TestFieldSortValues, TermVectorComponentTest, TestConfig, DistributedFacetPivotLongTailTest, TestDynamicLoading, StandardRequestHandlerTest, TestLazyCores, TestMergePolicyConfig, TestDistribIDF, IndexSchemaTest, TestDFRSimilarityFactory, SuggestComponentTest, TermsComponentTest, OverriddenZkACLAndCredentialsProvidersTest, TestRandomDVFaceting, ActionThrottleTest, TestHashQParserPlugin, RequestHandlersTest, TimeZoneUtilsTest, TestSolrDynamicMBean, TestInfoStreamLogging, TestRandomFaceting, CurrencyFieldXmlFileTest, BasicZkTest, FastVectorHighlighterTest, TestDynamicFieldResource, TestPostingsSolrHighlighter, FullHLLTest, TestFunctionQuery, TestFieldTypeResource, CursorMarkTest, PeerSyncTest, TestFaceting, TestReplicationHandlerBackup, TestRandomRequestDistribution, SolrCoreTest, TestRandomMergePolicy, AliasIntegrationTest, TestReversedWildcardFilterFactory, CustomCollectionTest, TestDistributedSearch, SecurityConfHandlerTest, MultiTermTest, QueryElevationComponentTest, ShardSplitTest, TestNRTOpen, HighlighterConfigTest, LeaderElectionTest, ShardRoutingCustomTest, AutoCommitTest, TestRecovery, DistributedSpellCheckComponentTest, TermVectorComponentDistributedTest, ZkControllerTest, TestRealTimeGet, TestJoin, TestReload, HardAutoCommitTest, DistributedTermsComponentTest, TestRangeQuery, SimpleFacetsTest, SolrCmdDistributorTest, BadIndexSchemaTest, ConvertedLegacyTest, TestFiltering, BasicFunctionalityTest, DirectUpdateHandlerTest, TestBadConfig, DistributedQueryElevationComponentTest, SolrIndexSplitterTest, TestCoreDiscovery, SignatureUpdateProcessorFactoryTest, TestExtendedDismaxParser, SpellCheckCollatorTest, CoreAdminHandlerTest, SolrRequestParserTest, TestStressLucene, TestTrie, SpatialFilterTest, SuggesterWFSTTest, TestCSVLoader, NoCacheHeaderTest, WordBreakSolrSpellCheckerTest, SchemaVersionSpecificBehaviorTest, SolrCoreCheckLockOnStartupTest, TestPseudoReturnFields, TestUpdate, TestAtomicUpdateErrorCases, QueryEqualityTest, DirectUpdateHandlerOptimizeTest, StatelessScriptUpdateProcessorFactoryTest, SortByFunctionTest, TestRemoteStreaming, DistanceFunctionTest, TestSolrDeletionPolicy1, DebugComponentTest, IndexBasedSpellCheckerTest, TestSurroundQueryParser, DisMaxRequestHandlerTest, PrimitiveFieldTypeTest, TestOmitPositions, FileBasedSpellCheckerTest, DocumentBuilderTest, RequiredFieldsTest, TestArbitraryIndexDir, RegexBoostProcessorTest, SolrPluginUtilsTest, ReturnFieldsTest, TestCSVResponseWriter, MBeansHandlerTest, TestAnalyzedSuggestions, TestPartialUpdateDeduplication, PingRequestHandlerTest, CSVRequestHandlerTest, TestComponentsName, SearchHandlerTest, TestLFUCache, SOLR749Test, AlternateDirectoryTest, ResponseLogComponentTest, SolrIndexConfigTest, TestStressRecovery, SampleTest, TestDocSet, TestSearchPerf, NumericFieldsTest, MinimalSchemaTest, TestElisionMultitermQuery, OutputWriterTest, TestFuzzyAnalyzedSuggestions, ExternalFileFieldSortTest, DirectSolrConnectionTest, TestPhraseSuggestions, SynonymTokenizerTest, TestSweetSpotSimilarityFactory, TestPerFieldSimilarity, TestLMDirichletSimilarityFactory, TestIBSimilarityFactory, TestFastWriter, TestJmxMonitoredMap, ResourceLoaderTest, TestFastOutputStream, ScriptEngineTest, OpenExchangeRatesOrgProviderTest, URLClassifyProcessorTest, TestFastLRUCache, DateMathParserTest, ChaosMonkeyNothingIsSafeTest, DateFieldTest, ClusterStateTest, TestLRUCache, TestDocumentBuilder, ZkNodePropsTest, SystemInfoHandlerTest, UUIDFieldTest, FileUtilsTest, CircularListTest, DistributedMLTComponentTest, TestRTGBase, CursorPagingTest, SolrTestCaseJ4Test, TestCursorMarkWithoutUniqueKey, TestJettySolrRunner, AssignTest, AsyncMigrateRouteKeyTest, CleanupOldIndexTest, ConnectionManagerTest, DeleteInactiveReplicaTest, DistribJoinFromCollectionTest, DistributedQueueTest, DistributedVersionInfoTest, LeaderInitiatedRecoveryOnCommitTest, OverseerStatusTest, RecoveryAfterSoftCommitTest, SolrCloudExampleTest, TestDownShardTolerantSearch, TestMiniSolrCloudClusterBase, TestReplicaProperties, TestRequestStatusCollectionAPI, TestSolrCloudWithKerberosAlt, HdfsBasicDistributedZk2Test, HdfsChaosMonkeySafeLeaderTest, HdfsCollectionsAPIDistributedZkTest]
   [junit4] Completed [533/533] on J0 in 3177.95s, 1 test, 1 error <<< FAILURES!

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

Total time: 233 minutes 34 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 - Any
Sending email for trigger: Failure - Any



Mime
View raw message