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-6.x - Build # 64 - Failure
Date Mon, 16 May 2016 16:44:42 GMT
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.x/64/

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

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

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=123339, name=collection2, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:57635/au_: collection already exists: awholynewstresscollection_collection2_6
	at __randomizedtesting.SeedInfo.seed([D8A7E56D4F2BE5E3]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:590)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:259)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:404)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:357)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1228)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:998)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:934)
	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1595)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1616)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:987)


FAILED:  org.apache.solr.cloud.TestCryptoKeys.test

Error Message:
Could not find collection:.system

Stack Trace:
java.lang.AssertionError: Could not find collection:.system
	at __randomizedtesting.SeedInfo.seed([D8A7E56D4F2BE5E3:50F3DAB7E1D7881B]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.assertTrue(Assert.java:43)
	at org.junit.Assert.assertNotNull(Assert.java:526)
	at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:154)
	at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:139)
	at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:134)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:852)
	at org.apache.solr.cloud.TestCryptoKeys.test(TestCryptoKeys.java:118)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
	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:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	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:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at java.lang.Thread.run(Thread.java:745)


FAILED:  org.apache.solr.handler.TestReqParamsAPI.test

Error Message:
Could not get expected value  'P val' for path 'response/params/y/p' full output: {   "responseHeader":{     "status":0,     "QTime":0},   "response":{     "znodeVersion":2,     "params":{       "x":{         "a":"A val",         "b":"B val",         "":{"v":0}},       "y":{         "c":"CY val modified",         "b":"BY val",         "i":20,         "d":[           "val 1",           "val 2"],         "e":"EY val",         "":{"v":1}}}}},  from server:  https://127.0.0.1:57565/collection1

Stack Trace:
java.lang.AssertionError: Could not get expected value  'P val' for path 'response/params/y/p' full output: {
  "responseHeader":{
    "status":0,
    "QTime":0},
  "response":{
    "znodeVersion":2,
    "params":{
      "x":{
        "a":"A val",
        "b":"B val",
        "":{"v":0}},
      "y":{
        "c":"CY val modified",
        "b":"BY val",
        "i":20,
        "d":[
          "val 1",
          "val 2"],
        "e":"EY val",
        "":{"v":1}}}}},  from server:  https://127.0.0.1:57565/collection1
	at __randomizedtesting.SeedInfo.seed([D8A7E56D4F2BE5E3:50F3DAB7E1D7881B]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.assertTrue(Assert.java:43)
	at org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:457)
	at org.apache.solr.handler.TestReqParamsAPI.testReqParams(TestReqParamsAPI.java:216)
	at org.apache.solr.handler.TestReqParamsAPI.test(TestReqParamsAPI.java:62)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
	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:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	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:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 12024 lines...]
   [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/init-core-data-001
   [junit4]   2> 2710995 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[D8A7E56D4F2BE5E3]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true)
   [junit4]   2> 2710997 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[D8A7E56D4F2BE5E3]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /au_/
   [junit4]   2> 2710999 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2711003 INFO  (Thread-26322) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2711003 INFO  (Thread-26322) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 2711103 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.ZkTestServer start zk server on port:32852
   [junit4]   2> 2711103 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2711103 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2711105 INFO  (zkCallback-26938-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4de8ec82 name:ZooKeeperConnection Watcher:127.0.0.1:32852 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2711105 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2711105 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2711105 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 2711107 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2711108 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2711109 INFO  (zkCallback-26939-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7d4c3428 name:ZooKeeperConnection Watcher:127.0.0.1:32852/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2711109 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2711109 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2711109 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 2711111 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 2711111 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 2711112 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 2711113 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 2711113 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 2711115 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
   [junit4]   2> 2711115 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 2711117 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 2711117 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 2711118 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
   [junit4]   2> 2711118 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 2711119 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
   [junit4]   2> 2711119 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 2711120 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
   [junit4]   2> 2711120 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 2711122 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
   [junit4]   2> 2711122 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 2711123 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 2711123 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 2711124 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 2711124 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 2711125 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
   [junit4]   2> 2711125 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 2711128 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
   [junit4]   2> 2711128 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 2711129 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2711130 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2711131 INFO  (zkCallback-26940-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@22ce9a6 name:ZooKeeperConnection Watcher:127.0.0.1:32852 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2711131 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2711131 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2711131 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 2711132 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2711133 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2711133 INFO  (zkCallback-26941-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1ea986cb name:ZooKeeperConnection Watcher:127.0.0.1:32852/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2711133 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2711133 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2711134 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml
   [junit4]   2> 2711134 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml
   [junit4]   2> 2711136 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml
   [junit4]   2> 2711136 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml
   [junit4]   2> 2711137 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml
   [junit4]   2> 2711137 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml
   [junit4]   2> 2711138 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 2711139 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 2711140 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt
   [junit4]   2> 2711140 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt
   [junit4]   2> 2711141 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt
   [junit4]   2> 2711142 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt
   [junit4]   2> 2711143 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml
   [junit4]   2> 2711143 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml
   [junit4]   2> 2711144 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json
   [junit4]   2> 2711144 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json
   [junit4]   2> 2711145 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 2711145 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 2711148 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt
   [junit4]   2> 2711148 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt
   [junit4]   2> 2711149 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt
   [junit4]   2> 2711149 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt
   [junit4]   2> 2711151 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml
   [junit4]   2> 2711151 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml
   [junit4]   2> 2711216 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/control-001/cores/collection1
   [junit4]   2> 2711217 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 2711218 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@21a4ec27{/au_,null,AVAILABLE}
   [junit4]   2> 2711220 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.e.j.s.ServerConnector Started ServerConnector@19342426{HTTP/1.1,[http/1.1]}{127.0.0.1:49222}
   [junit4]   2> 2711220 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.e.j.s.Server Started @2713221ms
   [junit4]   2> 2711220 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/tempDir-001/control/data, hostContext=/au_, hostPort=49222, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/control-001/cores}
   [junit4]   2> 2711221 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
   [junit4]   2> 2711221 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/control-001'
   [junit4]   2> 2711221 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2711221 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 2711221 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2711221 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2711222 INFO  (zkCallback-26942-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4e1c0ac0 name:ZooKeeperConnection Watcher:127.0.0.1:32852/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2711222 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2711222 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2711223 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 2711223 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/control-001/solr.xml
   [junit4]   2> 2711226 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/control-001/cores
   [junit4]   2> 2711226 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.CoreContainer New CoreContainer 1629441528
   [junit4]   2> 2711226 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/control-001]
   [junit4]   2> 2711227 WARN  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/control-001/lib
   [junit4]   2> 2711227 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] 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> 2711228 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2711228 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2711228 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2711228 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:32852/solr
   [junit4]   2> 2711228 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 2711228 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2711228 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2711229 INFO  (zkCallback-26945-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6a9666ea name:ZooKeeperConnection Watcher:127.0.0.1:32852 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2711229 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2711229 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2711230 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$50/1947330880@94a6b9a
   [junit4]   2> 2711230 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2711231 INFO  (zkCallback-26946-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@62ff9e21 name:ZooKeeperConnection Watcher:127.0.0.1:32852/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2711231 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2711232 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 2711233 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
   [junit4]   2> 2711235 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
   [junit4]   2> 2711236 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
   [junit4]   2> 2711237 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
   [junit4]   2> 2711238 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 2711239 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 2711239 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 2711240 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 2711241 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 2711241 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 2711241 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 2711243 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 2711244 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
   [junit4]   2> 2711244 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2711244 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95906349097680902-127.0.0.1:49222_au_-n_0000000000
   [junit4]   2> 2711245 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:49222_au_
   [junit4]   2> 2711245 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 2711245 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.Overseer Overseer (id=95906349097680902-127.0.0.1:49222_au_-n_0000000000) starting
   [junit4]   2> 2711246 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 2711250 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 2711250 INFO  (OverseerCollectionConfigSetProcessor-95906349097680902-127.0.0.1:49222_au_-n_0000000000) [n:127.0.0.1:49222_au_    ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
   [junit4]   2> 2711250 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49222_au_
   [junit4]   2> 2711250 INFO  (OverseerStateUpdate-95906349097680902-127.0.0.1:49222_au_-n_0000000000) [n:127.0.0.1:49222_au_    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 2711252 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:49222_au_
   [junit4]   2> 2711253 INFO  (zkCallback-26946-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_    ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
   [junit4]   2> 2711256 INFO  (OverseerStateUpdate-95906349097680902-127.0.0.1:49222_au_-n_0000000000) [n:127.0.0.1:49222_au_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2711273 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 2711273 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 2711273 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/control-001/cores
   [junit4]   2> 2711274 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=control_collection, coreNodeName=, dataDir=data/, shard=}
   [junit4]   2> 2711274 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/control-001/cores/collection1
   [junit4]   2> 2711274 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 2711275 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 2711275 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1
   [junit4]   2> 2711275 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 2711275 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [n:127.0.0.1:49222_au_    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 2711276 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection   x:collection1] o.a.s.c.ZkController Collection control_collection not visible yet, but flagging it so a watch is registered when it becomes visible
   [junit4]   2> 2711276 INFO  (OverseerStateUpdate-95906349097680902-127.0.0.1:49222_au_-n_0000000000) [n:127.0.0.1:49222_au_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:49222/au_",
   [junit4]   2>   "node_name":"127.0.0.1:49222_au_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"} current state version: 0
   [junit4]   2> 2711276 INFO  (OverseerStateUpdate-95906349097680902-127.0.0.1:49222_au_-n_0000000000) [n:127.0.0.1:49222_au_    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:49222/au_",
   [junit4]   2>   "node_name":"127.0.0.1:49222_au_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 2711276 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader Deleting data for [control_collection]
   [junit4]   2> 2711277 INFO  (OverseerStateUpdate-95906349097680902-127.0.0.1:49222_au_-n_0000000000) [n:127.0.0.1:49222_au_    ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
   [junit4]   2> 2711277 INFO  (OverseerStateUpdate-95906349097680902-127.0.0.1:49222_au_-n_0000000000) [n:127.0.0.1:49222_au_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 2711277 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection   x:collection1] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 2711278 INFO  (zkCallback-26946-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_    ] 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> 2712277 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection   x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 2712277 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection   x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection
   [junit4]   2> 2712278 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection   x:collection1] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 2712278 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection]
   [junit4]   2> 2712278 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper
   [junit4]   2> 2712278 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/control-001/cores/collection1'
   [junit4]   2> 2712278 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2712279 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 2712280 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection   x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 
   [junit4]   2> 2712284 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection   x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 2712287 WARN  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection   x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 2712288 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.1.0
   [junit4]   2> 2712307 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection   x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2712314 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2712380 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 2712384 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection   x:collection1] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 2712385 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection   x:collection1] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 2712397 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 2712399 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 2712401 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 2712401 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 2712402 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ 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-6.x/solr/build/solr-core/test/J1
   [junit4]   2> 2712402 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
   [junit4]   2> 2712403 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 2712403 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ 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-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/control-001/cores/collection1/data/]
   [junit4]   2> 2712403 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ 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@7776115f
   [junit4]   2> 2712403 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ 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-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/control-001/cores/collection1/data
   [junit4]   2> 2712404 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ 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-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/control-001/cores/collection1/data/index/
   [junit4]   2> 2712404 WARN  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ 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-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 2712404 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ 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-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/control-001/cores/collection1/data/index
   [junit4]   2> 2712404 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ 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=13, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=45.3388671875, floorSegmentMB=1.2060546875, forceMergeDeletesPctAllowed=17.85711476498687, segmentsPerTier=11.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 2712405 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6c7dfcdf lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6f4eaec2),segFN=segments_1,generation=1}
   [junit4]   2> 2712405 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 2712405 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:49222_au_ 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-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/control-001/cores/collection1/data/
   [junit4]   2> 2712405 WARN  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
   [junit4]   2> 2712407 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 2712408 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 2712408 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 2712408 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 2712408 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 2712408 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ 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> 2712408 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ 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> 2712408 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ 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> 2712408 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ 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> 2712408 WARN  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
   [junit4]   2> 2712410 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2712411 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2712411 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2712412 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2712415 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/dump,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file
   [junit4]   2> 2712415 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ 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> 2712416 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ 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> 2712416 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ 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> 2712417 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2712417 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2712417 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@14da4b2c
   [junit4]   2> 2712417 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6c7dfcdf lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6f4eaec2),segFN=segments_1,generation=1}
   [junit4]   2> 2712417 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 2712417 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1bdb0a8c[collection1] main]
   [junit4]   2> 2712417 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ 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> 2712418 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ 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> 2712418 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ 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> 2712418 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 2712418 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 2712418 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ 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> 2712419 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ 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> 2712419 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ 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> 2712419 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 2712419 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2712419 INFO  (searcherExecutor-9128-thread-1-processing-n:127.0.0.1:49222_au_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1bdb0a8c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2712420 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ 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> 2712420 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ 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> 2712420 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ 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> 2712420 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ 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 1534501587047677952
   [junit4]   2> 2712421 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1534501587047677952
   [junit4]   2> 2712421 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 2712422 INFO  (coreLoadExecutor-9127-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 2712422 INFO  (coreZkRegister-9121-thread-1-processing-n:127.0.0.1:49222_au_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49222_au_ 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:49222/au_ collection:control_collection shard:shard1
   [junit4]   2> 2712423 INFO  (coreZkRegister-9121-thread-1-processing-n:127.0.0.1:49222_au_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49222_au_ 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> 2712425 INFO  (coreZkRegister-9121-thread-1-processing-n:127.0.0.1:49222_au_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49222_au_ 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/95906349097680902-core_node1-n_0000000000
   [junit4]   2> 2712426 INFO  (coreZkRegister-9121-thread-1-processing-n:127.0.0.1:49222_au_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard1 and weAreReplacement=false and leaderVoteWait=180000
   [junit4]   2> 2712431 INFO  (coreZkRegister-9121-thread-1-processing-n:127.0.0.1:49222_au_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2712431 INFO  (coreZkRegister-9121-thread-1-processing-n:127.0.0.1:49222_au_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49222_au_ 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> 2712431 INFO  (coreZkRegister-9121-thread-1-processing-n:127.0.0.1:49222_au_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:49222/au_/collection1/
   [junit4]   2> 2712431 INFO  (coreZkRegister-9121-thread-1-processing-n:127.0.0.1:49222_au_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 2712431 INFO  (coreZkRegister-9121-thread-1-processing-n:127.0.0.1:49222_au_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:49222/au_/collection1/ has no replicas
   [junit4]   2> 2712431 INFO  (OverseerStateUpdate-95906349097680902-127.0.0.1:49222_au_-n_0000000000) [n:127.0.0.1:49222_au_    ] 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> 2712432 INFO  (coreZkRegister-9121-thread-1-processing-n:127.0.0.1:49222_au_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 2712433 INFO  (coreZkRegister-9121-thread-1-processing-n:127.0.0.1:49222_au_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/95906349097680902-core_node1-n_0000000000
   [junit4]   2> 2712435 INFO  (coreZkRegister-9121-thread-1-processing-n:127.0.0.1:49222_au_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49222_au_ 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:49222/au_/collection1/ shard1
   [junit4]   2> 2712435 INFO  (OverseerStateUpdate-95906349097680902-127.0.0.1:49222_au_-n_0000000000) [n:127.0.0.1:49222_au_    ] 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:49222/au_",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 1
   [junit4]   2> 2712537 INFO  (zkCallback-26946-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_    ] 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> 2712586 INFO  (coreZkRegister-9121-thread-1-processing-n:127.0.0.1:49222_au_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:49222/au_/collection1/ and leader is http://127.0.0.1:49222/au_/collection1/
   [junit4]   2> 2712586 INFO  (coreZkRegister-9121-thread-1-processing-n:127.0.0.1:49222_au_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49222_au_ 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:49222/au_
   [junit4]   2> 2712586 INFO  (coreZkRegister-9121-thread-1-processing-n:127.0.0.1:49222_au_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2712586 INFO  (coreZkRegister-9121-thread-1-processing-n:127.0.0.1:49222_au_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49222_au_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active
   [junit4]   2> 2712586 INFO  (coreZkRegister-9121-thread-1-processing-n:127.0.0.1:49222_au_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49222_au_ 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> 2712587 INFO  (OverseerStateUpdate-95906349097680902-127.0.0.1:49222_au_-n_0000000000) [n:127.0.0.1:49222_au_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:49222/au_",
   [junit4]   2>   "node_name":"127.0.0.1:49222_au_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"} current state version: 2
   [junit4]   2> 2712587 INFO  (OverseerStateUpdate-95906349097680902-127.0.0.1:49222_au_-n_0000000000) [n:127.0.0.1:49222_au_    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:49222/au_",
   [junit4]   2>   "node_name":"127.0.0.1:49222_au_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 2712689 INFO  (zkCallback-26946-thread-1-processing-n:127.0.0.1:49222_au_) [n:127.0.0.1:49222_au_    ] 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> 2712776 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.s.i.CloudSolrClient Final constructed zkHost string: 127.0.0.1:32852/solr
   [junit4]   2> 2712776 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2712777 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2712778 INFO  (zkCallback-26950-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1422fb05 name:ZooKeeperConnection Watcher:127.0.0.1:32852/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2712778 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2712779 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2712779 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 2712779 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 2712780 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2712782 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 2712870 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/shard-1-001/cores/collection1
   [junit4]   2> 2712871 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/shard-1-001
   [junit4]   2> 2712871 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 2712872 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@312c24cf{/au_,null,AVAILABLE}
   [junit4]   2> 2712873 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.e.j.s.ServerConnector Started ServerConnector@2fc92397{HTTP/1.1,[http/1.1]}{127.0.0.1:57635}
   [junit4]   2> 2712873 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.e.j.s.Server Started @2714873ms
   [junit4]   2> 2712873 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/au_, hostPort=57635, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/shard-1-001/cores}
   [junit4]   2> 2712873 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
   [junit4]   2> 2712873 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/shard-1-001'
   [junit4]   2> 2712873 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2712873 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 2712873 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2712874 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2712881 INFO  (zkCallback-26951-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@d3f6283 name:ZooKeeperConnection Watcher:127.0.0.1:32852/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2712881 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2712881 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2712882 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 2712882 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/shard-1-001/solr.xml
   [junit4]   2> 2712887 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/shard-1-001/cores
   [junit4]   2> 2712887 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.CoreContainer New CoreContainer 1653852203
   [junit4]   2> 2712887 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/shard-1-001]
   [junit4]   2> 2712888 WARN  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D8A7E56D4F2BE5E3-001/shard-1-001/lib
   [junit4]   2> 2712888 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] 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> 2712889 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2712889 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2712889 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2712889 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:32

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

.java:266)
   [junit4]   2> 	at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 4211439 WARN  (zkCallback-4290-thread-3-processing-n:127.0.0.1:52131_) [n:127.0.0.1:52131_    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /clusterstate.json]
   [junit4]   2> 4211439 WARN  (zkCallback-4290-thread-1-processing-n:127.0.0.1:52131_) [n:127.0.0.1:52131_    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 4211440 INFO  (TEST-TestReqParamsAPI.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.e.j.s.ServerConnector Stopped ServerConnector@678d7d94{SSL,[ssl, http/1.1]}{127.0.0.1:0}
   [junit4]   2> 4211440 INFO  (TEST-TestReqParamsAPI.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@11b96a75{/,null,UNAVAILABLE}
   [junit4]   2> 4211441 INFO  (TEST-TestReqParamsAPI.test-seed#[D8A7E56D4F2BE5E3]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:50357 50357
   [junit4]   2> 4211764 INFO  (Thread-194191) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:50357 50357
   [junit4]   2> 4211771 WARN  (Thread-194191) [    ] o.a.s.c.ZkTestServer Watch limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2> 	35	/solr/configs/conf1
   [junit4]   2> 	6	/solr/aliases.json
   [junit4]   2> 	5	/solr/security.json
   [junit4]   2> 	4	/solr/collections/collection1/state.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2> 	6	/solr/clusterstate.json
   [junit4]   2> 	6	/solr/clusterprops.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2> 	6	/solr/live_nodes
   [junit4]   2> 	6	/solr/collections
   [junit4]   2> 	3	/solr/overseer/queue
   [junit4]   2> 	3	/solr/overseer/collection-queue-work
   [junit4]   2> 	3	/solr/overseer/queue-work
   [junit4]   2> 
   [junit4]   2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory.
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestReqParamsAPI -Dtests.method=test -Dtests.seed=D8A7E56D4F2BE5E3 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt -Dtests.locale=sr -Dtests.timezone=Asia/Kashgar -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] FAILURE 28.0s J2 | TestReqParamsAPI.test <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Could not get expected value  'P val' for path 'response/params/y/p' full output: {
   [junit4]    >   "responseHeader":{
   [junit4]    >     "status":0,
   [junit4]    >     "QTime":0},
   [junit4]    >   "response":{
   [junit4]    >     "znodeVersion":2,
   [junit4]    >     "params":{
   [junit4]    >       "x":{
   [junit4]    >         "a":"A val",
   [junit4]    >         "b":"B val",
   [junit4]    >         "":{"v":0}},
   [junit4]    >       "y":{
   [junit4]    >         "c":"CY val modified",
   [junit4]    >         "b":"BY val",
   [junit4]    >         "i":20,
   [junit4]    >         "d":[
   [junit4]    >           "val 1",
   [junit4]    >           "val 2"],
   [junit4]    >         "e":"EY val",
   [junit4]    >         "":{"v":1}}}}},  from server:  https://127.0.0.1:57565/collection1
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([D8A7E56D4F2BE5E3:50F3DAB7E1D7881B]:0)
   [junit4]    > 	at org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:457)
   [junit4]    > 	at org.apache.solr.handler.TestReqParamsAPI.testReqParams(TestReqParamsAPI.java:216)
   [junit4]    > 	at org.apache.solr.handler.TestReqParamsAPI.test(TestReqParamsAPI.java:62)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 4211774 INFO  (SUITE-TestReqParamsAPI-seed#[D8A7E56D4F2BE5E3]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_D8A7E56D4F2BE5E3-001
   [junit4]   2> May 16, 2016 4:30:51 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene60): {}, docValues:{}, maxPointsInLeafNode=1257, maxMBSortInHeap=7.306057920472538, sim=ClassicSimilarity, locale=sr, timezone=Asia/Kashgar
   [junit4]   2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_74 (64-bit)/cpus=4,threads=1,free=100098832,total=532152320
   [junit4]   2> NOTE: All tests run in this JVM: [TestSweetSpotSimilarityFactory, TestBlobHandler, SolrTestCaseJ4Test, DebugComponentTest, BinaryUpdateRequestHandlerTest, CleanupOldIndexTest, TestRecoveryHdfs, TestGraphMLResponseWriter, HdfsCollectionsAPIDistributedZkTest, TestAuthenticationFramework, DirectSolrConnectionTest, UnloadDistributedZkTest, TestPHPSerializedResponseWriter, MigrateRouteKeyTest, RequestHandlersTest, CircularListTest, QueryParsingTest, TestSimpleQParserPlugin, BasicAuthIntegrationTest, TestHighFrequencyDictionaryFactory, TestMiniSolrCloudCluster, TestMacros, ShardSplitTest, TestNonDefinedSimilarityFactory, DeleteInactiveReplicaTest, TestFieldCollectionResource, DeleteShardTest, TestPostingsSolrHighlighter, TestCloudManagedSchema, TestSizeLimitedDistributedMap, TestExclusionRuleCollectionAccess, TestXIncludeConfig, TestExtendedDismaxParser, SolrCoreCheckLockOnStartupTest, TestUtils, RollingRestartTest, DirectUpdateHandlerOptimizeTest, TestInitQParser, RemoteQueryErrorTest, TestGroupingSearch, TestCollationFieldDocValues, TestRTimerTree, TermVectorComponentDistributedTest, TestConfigSetsAPI, TestCSVResponseWriter, ConnectionManagerTest, TestMiniSolrCloudClusterSSL, CoreAdminRequestStatusTest, TestPerFieldSimilarity, DistributedFacetPivotSmallAdvancedTest, CollectionsAPISolrJTest, TestRequestStatusCollectionAPI, TestTolerantUpdateProcessorCloud, RegexBoostProcessorTest, DistributedQueryComponentCustomSortTest, BufferStoreTest, TestDefaultSearchFieldResource, TestStressReorder, BasicDistributedZk2Test, TestManagedResource, LukeRequestHandlerTest, TestJettySolrRunner, OverseerTest, TestMinMaxOnMultiValuedField, PreAnalyzedUpdateProcessorTest, BigEndianAscendingWordDeserializerTest, PluginInfoTest, MergeStrategyTest, DistributedExpandComponentTest, CoreAdminHandlerTest, TestCoreDiscovery, CdcrRequestHandlerTest, TestConfigSets, BlockJoinFacetSimpleTest, SuggestComponentTest, DirectUpdateHandlerTest, HLLUtilTest, BadComponentTest, TestSolrConfigHandlerCloud, SearchHandlerTest, TestSolrQueryParser, TestAddFieldRealTimeGet, TestSolrQueryResponse, AssignTest, TestJmxMonitoredMap, RuleEngineTest, TestFilteredDocIdSet, SliceStateTest, TestManagedStopFilterFactory, DataDrivenBlockJoinTest, TestDistributedMissingSort, ClusterStateTest, ScriptEngineTest, TestInfoStreamLogging, HdfsBasicDistributedZkTest, TestWriterPerf, LeaderElectionIntegrationTest, RecoveryZkTest, TestReplicationHandler, CollectionsAPIAsyncDistributedZkTest, DeleteReplicaTest, HttpPartitionTest, TestRebalanceLeaders, TestTolerantUpdateProcessorRandomCloud, VMParamsZkACLAndCredentialsProvidersTest, HdfsChaosMonkeyNothingIsSafeTest, HdfsRecoveryZkTest, StressHdfsTest, ExitableDirectoryReaderTest, HdfsDirectoryFactoryTest, QueryResultKeyTest, TestConfigOverlay, TestConfigSetImmutable, TestConfigSetProperties, TestImplicitCoreProperties, TestInitParams, TestNRTOpen, TestReloadAndDeleteDocs, TestSolrConfigHandler, TestSolrDynamicMBean, TestSolrXml, RequestLoggingTest, TestConfigReload, TestReqParamsAPI]
   [junit4] Completed [495/598 (3!)] on J2 in 28.57s, 1 test, 1 failure <<< FAILURES!

[...truncated 332 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:747: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:684: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:59: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build.xml:233: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/common-build.xml:529: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1457: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1014: There were test failures: 598 suites (10 ignored), 2420 tests, 1 error, 2 failures, 32 ignored (17 assumptions) [seed: D8A7E56D4F2BE5E3]

Total time: 231 minutes 17 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