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 # 80 - Still Failing
Date Wed, 01 Jun 2016 14:07:20 GMT
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.x/80/

5 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.solr.cloud.hdfs.HdfsChaosMonkeySafeLeaderTest

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

Stack Trace:
java.lang.AssertionError: ObjectTracker found 1 object(s) that were not released!!! [HdfsTransactionLog]
	at __randomizedtesting.SeedInfo.seed([460DDE939476F360]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.assertTrue(Assert.java:43)
	at org.junit.Assert.assertNull(Assert.java:551)
	at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:256)
	at sun.reflect.GeneratedMethodAccessor103.invoke(Unknown Source)
	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$7.evaluate(RandomizedRunner.java:834)
	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.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test

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

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=56451, name=collection1, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest]
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:51849: Could not find collection : awholynewstresscollection_collection1_1
	at __randomizedtesting.SeedInfo.seed([460DDE939476F360]: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.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:994)


FAILED:  junit.framework.TestSuite.org.apache.solr.handler.TestReplicationHandler

Error Message:
ObjectTracker found 12 object(s) that were not released!!! [NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory]

Stack Trace:
java.lang.AssertionError: ObjectTracker found 12 object(s) that were not released!!! [NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory]
	at __randomizedtesting.SeedInfo.seed([460DDE939476F360]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.assertTrue(Assert.java:43)
	at org.junit.Assert.assertNull(Assert.java:551)
	at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:256)
	at sun.reflect.GeneratedMethodAccessor20.invoke(Unknown Source)
	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$7.evaluate(RandomizedRunner.java:834)
	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:  junit.framework.TestSuite.org.apache.solr.schema.TestManagedSchemaAPI

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

Stack Trace:
java.lang.AssertionError: ObjectTracker found 1 object(s) that were not released!!! [MockDirectoryWrapper]
	at __randomizedtesting.SeedInfo.seed([460DDE939476F360]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.assertTrue(Assert.java:43)
	at org.junit.Assert.assertNull(Assert.java:551)
	at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:256)
	at sun.reflect.GeneratedMethodAccessor20.invoke(Unknown Source)
	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$7.evaluate(RandomizedRunner.java:834)
	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.cloud.CollectionsAPIDistributedZkTest.test

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

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=680, name=collection5, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:60277/f_gl/nu: collection already exists: awholynewstresscollection_collection5_1
	at __randomizedtesting.SeedInfo.seed([460DDE939476F360]: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:1599)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1620)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:987)




Build Log:
[...truncated 11236 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_460DDE939476F360-001/init-core-data-001
   [junit4]   2> 32456 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[460DDE939476F360]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true)
   [junit4]   2> 32457 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[460DDE939476F360]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /f_gl/nu
   [junit4]   2> 32484 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 32529 INFO  (Thread-178) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 32531 INFO  (Thread-178) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 32721 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.ZkTestServer start zk server on port:38554
   [junit4]   2> 32737 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 33111 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 33156 INFO  (zkCallback-15-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1e8eb418 name:ZooKeeperConnection Watcher:127.0.0.1:38554 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 33157 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 33157 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 33158 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 33175 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 33181 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from client sessionid 0x1550bfa8d870000, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 33188 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 33227 INFO  (zkCallback-16-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1360f004 name:ZooKeeperConnection Watcher:127.0.0.1:38554/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 33228 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 33229 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 33230 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 33236 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 33238 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 33242 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 33244 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] 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> 33245 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 33249 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] 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> 33249 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 33251 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] 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> 33252 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 33260 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] 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> 33260 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 33262 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] 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> 33262 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 33264 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] 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> 33264 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 33268 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] 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> 33268 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 33269 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] 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> 33269 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 33271 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] 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> 33271 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 33278 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] 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> 33278 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 33279 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] 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> 33279 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 33469 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] 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_460DDE939476F360-001/control-001/cores/collection1
   [junit4]   2> 33477 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 33585 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@74aea88e{/f_gl/nu,null,AVAILABLE}
   [junit4]   2> 33586 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.e.j.s.ServerConnector Started ServerConnector@7a1c2954{HTTP/1.1,[http/1.1]}{127.0.0.1:47308}
   [junit4]   2> 33586 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.e.j.s.Server Started @37520ms
   [junit4]   2> 33586 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] 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_460DDE939476F360-001/tempDir-001/control/data, hostContext=/f_gl/nu, hostPort=47308, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/control-001/cores}
   [junit4]   2> 33588 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
   [junit4]   2> 33596 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] 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_460DDE939476F360-001/control-001'
   [junit4]   2> 33597 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 33597 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 33597 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 33615 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 33656 INFO  (zkCallback-17-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3316ba31 name:ZooKeeperConnection Watcher:127.0.0.1:38554/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 33657 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 33657 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 33668 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 33669 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] 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_460DDE939476F360-001/control-001/solr.xml
   [junit4]   2> 33677 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] 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_460DDE939476F360-001/control-001/cores
   [junit4]   2> 33677 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.CoreContainer New CoreContainer 1345304922
   [junit4]   2> 33677 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] 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_460DDE939476F360-001/control-001]
   [junit4]   2> 33677 WARN  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] 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_460DDE939476F360-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_460DDE939476F360-001/control-001/lib
   [junit4]   2> 33677 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] 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> 33684 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 33685 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 33685 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 33685 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38554/solr
   [junit4]   2> 33695 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 33695 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 33723 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 33724 INFO  (zkCallback-20-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5779ce10 name:ZooKeeperConnection Watcher:127.0.0.1:38554 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 33724 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 33725 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 33728 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$49/1942015045@e81bb13
   [junit4]   2> 33768 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 33805 INFO  (zkCallback-21-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1a8dfc5 name:ZooKeeperConnection Watcher:127.0.0.1:38554/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 33814 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 33935 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 33940 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
   [junit4]   2> 33952 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
   [junit4]   2> 33957 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
   [junit4]   2> 33959 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
   [junit4]   2> 33970 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 33972 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 33973 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 33975 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 33979 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 33987 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 33991 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 34005 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 34006 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
   [junit4]   2> 34008 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 34011 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000
   [junit4]   2> 34012 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:47308_f_gl%2Fnu
   [junit4]   2> 34012 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 34014 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.Overseer Overseer (id=95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000) starting
   [junit4]   2> 34018 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 34059 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 34069 INFO  (OverseerCollectionConfigSetProcessor-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
   [junit4]   2> 34069 INFO  (OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 34076 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:47308_f_gl%2Fnu
   [junit4]   2> 34085 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:47308_f_gl%2Fnu
   [junit4]   2> 34086 INFO  (OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 34091 INFO  (zkCallback-21-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu    ] 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> 34092 INFO  (zkCallback-21-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 34281 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 34281 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 34283 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] 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/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/control-001/cores
   [junit4]   2> 34284 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] 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> 34284 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] 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/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/control-001/cores/collection1
   [junit4]   2> 34284 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 34460 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] 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> 34460 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 34460 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 34461 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 34464 INFO  (OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000) [n:127.0.0.1:47308_f_gl%2Fnu    ] 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:47308/f_gl/nu",
   [junit4]   2>   "node_name":"127.0.0.1:47308_f_gl%2Fnu",
   [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> 34469 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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> 34472 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader Deleting data for [control_collection]
   [junit4]   2> 34475 INFO  (OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000) [n:127.0.0.1:47308_f_gl%2Fnu    ] 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:47308/f_gl/nu",
   [junit4]   2>   "node_name":"127.0.0.1:47308_f_gl%2Fnu",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 34477 INFO  (OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
   [junit4]   2> 34477 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection   x:collection1] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 34494 INFO  (OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 34515 INFO  (zkCallback-21-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu    ] 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> 35530 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection   x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 35530 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection   x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection
   [junit4]   2> 35532 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection   x:collection1] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 35532 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection]
   [junit4]   2> 35533 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper
   [junit4]   2> 35534 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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_460DDE939476F360-001/control-001/cores/collection1'
   [junit4]   2> 35534 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 35534 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 35540 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection   x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 
   [junit4]   2> 35563 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection   x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 35579 WARN  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection   x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 35580 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.1.0
   [junit4]   2> 35648 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection   x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 35681 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 36071 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 36079 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection   x:collection1] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 36079 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection   x:collection1] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 36111 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 36122 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 36126 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 36127 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 36128 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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> 36128 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
   [junit4]   2> 36129 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 36129 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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_460DDE939476F360-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/control-001/cores/collection1/data/]
   [junit4]   2> 36129 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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@7f39382
   [junit4]   2> 36135 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/control-001/cores/collection1/data
   [junit4]   2> 36136 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/control-001/cores/collection1/data/index/
   [junit4]   2> 36136 WARN  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 36136 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/control-001/cores/collection1/data/index
   [junit4]   2> 36136 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=41, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 36149 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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@60d25427 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@257235e3),segFN=segments_1,generation=1}
   [junit4]   2> 36150 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 36189 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 36200 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 36205 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:47308_f_gl%2Fnu 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/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/control-001/cores/collection1/data/
   [junit4]   2> 36205 WARN  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:47308_f_gl%2Fnu 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/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
   [junit4]   2> 36249 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 36249 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 36249 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 36249 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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> 36249 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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> 36250 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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> 36250 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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> 36250 WARN  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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> 36251 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 36254 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 36264 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 36266 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 36314 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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> 36315 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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> 36437 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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> 36437 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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> 36437 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 36437 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 36439 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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@46d7b36f
   [junit4]   2> 36444 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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@60d25427 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@257235e3),segFN=segments_1,generation=1}
   [junit4]   2> 36444 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 36445 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7142ea7e[collection1] main]
   [junit4]   2> 36445 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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> 36446 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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> 36446 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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> 36447 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 36447 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 36447 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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> 36447 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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> 36448 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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> 36448 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 36448 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 36450 INFO  (searcherExecutor-74-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7142ea7e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 36450 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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> 36450 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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> 36450 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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> 36450 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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 1535938208772128768
   [junit4]   2> 36451 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu 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 1535938208772128768
   [junit4]   2> 36452 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 36457 INFO  (coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 36464 INFO  (coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47308_f_gl%2Fnu 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:47308/f_gl/nu collection:control_collection shard:shard1
   [junit4]   2> 36473 INFO  (coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47308_f_gl%2Fnu 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> 36476 INFO  (coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47308_f_gl%2Fnu 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/95996137802498052-core_node1-n_0000000000
   [junit4]   2> 36476 INFO  (coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47308_f_gl%2Fnu 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> 36478 INFO  (coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 36478 INFO  (coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47308_f_gl%2Fnu 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> 36479 INFO  (coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:47308/f_gl/nu/collection1/
   [junit4]   2> 36479 INFO  (coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 36479 INFO  (coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:47308/f_gl/nu/collection1/ has no replicas
   [junit4]   2> 36481 INFO  (OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000) [n:127.0.0.1:47308_f_gl%2Fnu    ] 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> 36487 INFO  (coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 36488 INFO  (zkCallback-21-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu    ] 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> 36502 INFO  (coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47308_f_gl%2Fnu 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/95996137802498052-core_node1-n_0000000000
   [junit4]   2> 36518 INFO  (coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47308_f_gl%2Fnu 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:47308/f_gl/nu/collection1/ shard1
   [junit4]   2> 36519 INFO  (OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000) [n:127.0.0.1:47308_f_gl%2Fnu    ] 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:47308/f_gl/nu",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 2
   [junit4]   2> 36537 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.s.i.CloudSolrClient Final constructed zkHost string: 127.0.0.1:38554/solr
   [junit4]   2> 36539 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 36568 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 36601 INFO  (zkCallback-25-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@68a30b70 name:ZooKeeperConnection Watcher:127.0.0.1:38554/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 36601 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 36601 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 36601 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 36602 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 36603 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 36605 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 36605 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
   [junit4]   2> 36605 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 36615 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 36621 INFO  (zkCallback-21-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu    ] 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> 36627 INFO  (coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:47308/f_gl/nu/collection1/ and leader is http://127.0.0.1:47308/f_gl/nu/collection1/
   [junit4]   2> 36627 INFO  (coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47308_f_gl%2Fnu 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:47308/f_gl/nu
   [junit4]   2> 36627 INFO  (coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 36627 INFO  (coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active
   [junit4]   2> 36627 INFO  (coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47308_f_gl%2Fnu 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> 36638 INFO  (zkCallback-25-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
   [junit4]   2> 36641 INFO  (zkCallback-26-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@299b7a7b name:ZooKeeperConnection Watcher:127.0.0.1:38554/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 36641 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 36642 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 36642 INFO  (OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000) [n:127.0.0.1:47308_f_gl%2Fnu    ] 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:47308/f_gl/nu",
   [junit4]   2>   "node_name":"127.0.0.1:47308_f_gl%2Fnu",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"} current state version: 3
   [junit4]   2> 36643 INFO  (OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.o.ReplicaMutator Update state numShards=2 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:47308/f_gl/nu",
   [junit4]   2>   "node_name":"127.0.0.1:47308_f_gl%2Fnu",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 36647 INFO  (OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"create",
   [junit4]   2>   "name":"collection1",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "stateFormat":"2"} current state version: 3
   [junit4]   2> 36647 INFO  (OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1
   [junit4]   2> 36647 INFO  (zkCallback-25-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
   [junit4]   2> 36647 INFO  (zkCallback-21-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu    ] 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> 36758 INFO  (OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json
   [junit4]   2> 37000 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] 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_460DDE939476F360-001/shard-1-001/cores/collection1
   [junit4]   2> 37018 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] 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_460DDE939476F360-001/shard-1-001
   [junit4]   2> 37020 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 37072 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@123a0d40{/f_gl/nu,null,AVAILABLE}
   [junit4]   2> 37085 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.e.j.s.ServerConnector Started ServerConnector@dbc8fbe{HTTP/1.1,[http/1.1]}{127.0.0.1:46014}
   [junit4]   2> 37085 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.e.j.s.Server Started @41019ms
   [junit4]   2> 37085 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] 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_460DDE939476F360-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/f_gl/nu, hostPort=46014, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/shard-1-001/cores}
   [junit4]   2> 37085 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
   [junit4]   2> 37085 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] 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_460DDE939476F360-001/shard-1-001'
   [junit4]   2> 37085 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 37085 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 37085 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 37093 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 37116 INFO  (zkCallback-27-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7012ca9f name:ZooKeeperConnection Watcher:127.0.0.1:38554/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 37117 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 37117 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 37121 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 37121 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] 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_460DDE939476F360-001/shard-1-001/solr.xml
   [junit4]   2> 37125 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] 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_460DDE939476F360-001/shard-1-001/cores
   [junit4]   2> 37126 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.CoreContainer New CoreContainer 962351997
   [junit4]   2> 37126 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] 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_460DDE939476F360-001/shard-1-001]
   [junit4]   2> 37126 WARN  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] 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_460DDE939476F360-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_460DDE939476F360-001/shard-1-001/lib
   [junit4]   2> 37126 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] 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> 37127 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 37127 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 37127 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 37127 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38554/solr
   [junit4]   2> 37127 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 37127 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 37148 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 37161 INFO  (zkCallback-30-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3e617cd4 name:ZooKeeperConnection Watcher:127.0.0.1:38554 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 37172 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 37173 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 37175 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:46014_f_gl%2Fnu    ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$49/1942015045@659dd8d5
   [junit4]   2> 37177 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:46014_f_gl%2Fnu    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 37196 INFO  (zkCallback-31-thread-1-processing-n:127.0.0.1:46014_f_gl%2Fnu) [n:127.0.0.1:46014_f_gl%2Fnu    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@637a59ca name:ZooKeeperConnection Watcher:127.0.0.1:38554/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 37196 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:46014_f_gl%2Fnu    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 37210 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:46014_f_gl%2Fnu    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 37211 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:46014_f_gl%2Fnu    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 37211 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:46014_f_gl%2Fnu    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 37213 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:46014_f_gl%2Fnu    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 37214 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:46014_f_gl%2Fnu    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95996137802498057-127.0.0.1:46014_f_gl%2Fnu-n_0000000001
   [junit4]   2> 37215 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:46014_f_gl%2Fnu    ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000 to know if I could be the leader
   [junit4]   2> 37215 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [n:127.0.0.1:46014_f_gl%2Fnu    ] o.a.s.c.ZkController Publish node=127.0.0.1:46014_f_gl%2Fnu as DOWN
   [junit4]   2> 37217 INFO  (OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"downnode",
   [junit4]   2>   "node_name":"127.0.0.1:46014_f_gl%2Fnu"} current state version: 4
   [junit4]   2> 37218 INFO  (OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:46014_f_gl%2Fnu
   [junit4]   2> 37219 INFO  (zkCallback-25-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
   [junit4]   2> 37219 INFO  (zkCallback-21-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu) [n:127.0.0.1:47308_f_gl%2Fnu    ] 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> 37221 INFO  (zkCallback-31-thread-1-processing-n:127.0.0.1:46014_f_gl%2Fnu) [n:127.0.0.1:46014_f_gl%2Fnu    ] 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> 37324 INFO  (OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000) [n:127.0.0.1:47308_f_gl%2Fnu    ] o.a.s.c.o.

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

uteProduceConsume.run(ExecuteProduceConsume.java:156)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 
   [junit4]   2> 4905167 WARN  (Thread-13311) [n:127.0.0.1:54891_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController listener throws error
   [junit4]   2> org.apache.solr.common.SolrException: Unable to reload core [testschemaapi_shard1_replica2]
   [junit4]   2> 	at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:920)
   [junit4]   2> 	at org.apache.solr.core.SolrCore.lambda$getConfListener$6(SolrCore.java:2510)
   [junit4]   2> 	at org.apache.solr.cloud.ZkController$4.run(ZkController.java:2408)
   [junit4]   2> Caused by: org.apache.solr.common.SolrException: Failed to verify znode at /configs/conf1 due to: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /configs/conf1
   [junit4]   2> 	at org.apache.solr.core.SolrCore.<init>(SolrCore.java:773)
   [junit4]   2> 	at org.apache.solr.core.SolrCore.reload(SolrCore.java:462)
   [junit4]   2> 	at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:914)
   [junit4]   2> 	... 2 more
   [junit4]   2> Caused by: org.apache.solr.common.SolrException: Failed to verify znode at /configs/conf1 due to: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /configs/conf1
   [junit4]   2> 	at org.apache.solr.rest.ManagedResourceStorage$ZooKeeperStorageIO.configure(ManagedResourceStorage.java:236)
   [junit4]   2> 	at org.apache.solr.rest.ManagedResourceStorage.newStorageIO(ManagedResourceStorage.java:141)
   [junit4]   2> 	at org.apache.solr.core.SolrCore.initRestManager(SolrCore.java:2305)
   [junit4]   2> 	at org.apache.solr.core.SolrCore.<init>(SolrCore.java:749)
   [junit4]   2> 	... 4 more
   [junit4]   2> Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /configs/conf1
   [junit4]   2> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
   [junit4]   2> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
   [junit4]   2> 	at org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java:1045)
   [junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$5.execute(SolrZkClient.java:314)
   [junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$5.execute(SolrZkClient.java:311)
   [junit4]   2> 	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
   [junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:311)
   [junit4]   2> 	at org.apache.solr.rest.ManagedResourceStorage$ZooKeeperStorageIO.configure(ManagedResourceStorage.java:229)
   [junit4]   2> 	... 7 more
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene60): {_version_=PostingsFormat(name=MockRandom), id=Lucene50(blocksize=128), myNewField1=Lucene50(blocksize=128), myNewField=Lucene50(blocksize=128)}, docValues:{}, maxPointsInLeafNode=88, maxMBSortInHeap=7.964266233061676, sim=RandomSimilarity(queryNorm=true,coord=yes): {}, locale=pl-PL, timezone=America/Curacao
   [junit4]   2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_74 (64-bit)/cpus=4,threads=1,free=133589376,total=525336576
   [junit4]   2> NOTE: All tests run in this JVM: [DistributedFacetPivotLongTailTest, CircularListTest, TestSuggestSpellingConverter, LeaderElectionTest, TestDefaultStatsCache, TestFuzzyAnalyzedSuggestions, UpdateParamsTest, TestIndexSearcher, TestCollationField, SuggesterTest, TestSearchPerf, TestImplicitCoreProperties, SimpleCollectionCreateDeleteTest, CleanupOldIndexTest, ShardSplitTest, TestExactStatsCache, BinaryUpdateRequestHandlerTest, TestOverriddenPrefixQueryForCustomFieldType, TestRuleBasedAuthorizationPlugin, TestStressLiveNodes, TestManagedSchema, TestSQLHandler, TestNamedUpdateProcessors, FieldMutatingUpdateProcessorTest, AnalysisErrorHandlingTest, TestSchemaVersionResource, FieldAnalysisRequestHandlerTest, RequestLoggingTest, TestCryptoKeys, TestSolrConfigHandler, HdfsBasicDistributedZk2Test, RemoteQueryErrorTest, TestWriterPerf, TestStressRecovery, TestManagedSynonymFilterFactory, TestSolrJ, TestTolerantSearch, TestFileDictionaryLookup, TestCoreDiscovery, JavabinLoaderTest, RollingRestartTest, InfoHandlerTest, TestStandardQParsers, DistanceUnitsTest, TestZkChroot, TestMaxScoreQueryParser, QueryResultKeyTest, OverriddenZkACLAndCredentialsProvidersTest, TestSolrCLIRunExample, TestPerFieldSimilarityClassic, TestGroupingSearch, TestSortingResponseWriter, DateMathParserTest, TestReversedWildcardFilterFactory, BigEndianAscendingWordSerializerTest, SliceStateTest, TestGraphTermsQParserPlugin, CoreAdminCreateDiscoverTest, BadComponentTest, TestSolrQueryParser, SpatialRPTFieldTypeTest, CollectionStateFormat2Test, TestSchemaNameResource, ClassificationUpdateProcessorFactoryTest, OverseerStatusTest, TestNRTOpen, RequestHandlersTest, AnalyticsQueryTest, SmileWriterTest, BlockJoinFacetDistribTest, SampleTest, TestJettySolrRunner, SearchHandlerTest, RankQueryTest, CollectionsAPISolrJTest, SparseHLLTest, BadIndexSchemaTest, HdfsRecoverLeaseTest, TestCSVResponseWriter, CoreAdminHandlerTest, TestSweetSpotSimilarityFactory, BasicDistributedZk2Test, HdfsUnloadDistributedZkTest, TestSolrDeletionPolicy1, DocValuesMultiTest, VersionInfoTest, LeaderFailoverAfterPartitionTest, CurrencyFieldXmlFileTest, TestFieldCacheSort, BaseCdcrDistributedZkTest, TestFieldCache, StatelessScriptUpdateProcessorFactoryTest, TestXIncludeConfig, TestXmlQParser, DebugComponentTest, DistributedFacetPivotWhiteBoxTest, TestPHPSerializedResponseWriter, IgnoreCommitOptimizeUpdateProcessorFactoryTest, HdfsSyncSliceTest, DirectoryFactoryTest, OverseerTest, LeaderElectionIntegrationTest, ShardRoutingTest, BasicZkTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest, TestReplicationHandler, CdcrVersionReplicationTest, CollectionTooManyReplicasTest, ConnectionManagerTest, CustomCollectionTest, DistributedQueueTest, DistributedVersionInfoTest, HttpPartitionTest, SSLMigrationTest, SaslZkACLProviderTest, SharedFSAutoReplicaFailoverTest, TestCloudBackupRestore, TestCloudDeleteByQuery, TestCloudInspectUtil, TestCloudPivotFacet, TestConfigSetsAPI, TestConfigSetsAPIExclusivity, TestDistribDocBasedVersion, TestMiniSolrCloudClusterBase, TestRandomRequestDistribution, TestRequestForwarding, TestRequestStatusCollectionAPI, TestSizeLimitedDistributedMap, TestStressCloudBlindAtomicUpdates, HdfsRecoveryZkTest, HdfsThreadLeakTest, HdfsWriteToMultipleCollectionsTest, BlobRepositoryCloudTest, ExitableDirectoryReaderTest, TestConfigSetProperties, TestConfigSets, TestInitParams, TestSolrDynamicMBean, TestSolrXml, TestReplicationHandlerBackup, CoreMergeIndexesAdminHandlerTest, DistributedDebugComponentTest, DistributedFacetPivotLargeTest, DistributedFacetPivotSmallAdvancedTest, DistributedQueryComponentCustomSortTest, DistributedQueryComponentOptimizationTest, DistributedSuggestComponentTest, SpatialHeatmapFacetsTest, HighlighterMaxOffsetTest, TestSlowCompositeReaderWrapper, WrapperMergePolicyFactoryTest, TestIntervalFaceting, TestCustomDocTransformer, TestGeoJSONResponseWriter, TestGraphMLResponseWriter, TestRawResponseWriter, TestChildDocTransformer, TestSubQueryTransformer, TestSubQueryTransformerCrossCore, TestSubQueryTransformerDistrib, TestManagedSchemaAPI]
   [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=TestManagedSchemaAPI -Dtests.seed=460DDE939476F360 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt -Dtests.locale=pl-PL -Dtests.timezone=America/Curacao -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR   0.00s J2 | TestManagedSchemaAPI (suite) <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: ObjectTracker found 1 object(s) that were not released!!! [MockDirectoryWrapper]
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([460DDE939476F360]:0)
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:256)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4] Completed [549/612 (5!)] on J2 in 14.84s, 1 test, 1 failure <<< FAILURES!

[...truncated 200 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:531: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1427: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:984: There were test failures: 612 suites (10 ignored), 2564 tests, 3 suite-level errors, 2 errors, 33 ignored (18 assumptions) [seed: 460DDE939476F360]

Total time: 252 minutes 11 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