lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-NightlyTests-5.5 - Build # 6 - Still Failing
Date Thu, 18 Feb 2016 16:15:01 GMT
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.5/6/

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

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

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=10546, name=collection4, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:56434/_ov: collection already exists: awholynewstresscollection_collection4_2
	at __randomizedtesting.SeedInfo.seed([7F9B80506B2CE5E8]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:576)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:240)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:229)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806)
	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1573)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1594)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:960)


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

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

Stack Trace:
java.lang.AssertionError: ObjectTracker found 6 object(s) that were not released!!! [NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory]
	at __randomizedtesting.SeedInfo.seed([7F9B80506B2CE5E8]: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:228)
	at sun.reflect.GeneratedMethodAccessor25.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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.search.TestIndexSearcher.testReopen

Error Message:
expected:<_1a(5.5.0):c2> but was:<_1d(5.5.0):C4>

Stack Trace:
java.lang.AssertionError: expected:<_1a(5.5.0):c2> but was:<_1d(5.5.0):C4>
	at __randomizedtesting.SeedInfo.seed([7F9B80506B2CE5E8:53D3514618106ACB]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.failNotEquals(Assert.java:647)
	at org.junit.Assert.assertEquals(Assert.java:128)
	at org.junit.Assert.assertEquals(Assert.java:147)
	at org.apache.solr.search.TestIndexSearcher.testReopen(TestIndexSearcher.java:121)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 11824 lines...]
   [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/init-core-data-001
   [junit4]   2> 1553635 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[7F9B80506B2CE5E8]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 1553635 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[7F9B80506B2CE5E8]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_ov/
   [junit4]   2> 1553640 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1553641 INFO  (Thread-3825) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1553641 INFO  (Thread-3825) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1553741 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.ZkTestServer start zk server on port:54021
   [junit4]   2> 1553741 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1553742 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1553749 INFO  (zkCallback-1386-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@57a1bca8 name:ZooKeeperConnection Watcher:127.0.0.1:54021 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1553749 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1553750 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1553750 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 1553752 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1553752 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1553754 INFO  (zkCallback-1387-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1c196199 name:ZooKeeperConnection Watcher:127.0.0.1:54021/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1553754 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1553754 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1553754 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 1553756 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 1553757 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 1553759 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 1553760 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 1553761 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 1553764 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
   [junit4]   2> 1553764 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 1553766 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1553766 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1553768 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
   [junit4]   2> 1553768 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 1553770 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
   [junit4]   2> 1553770 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 1553771 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
   [junit4]   2> 1553772 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 1553773 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
   [junit4]   2> 1553774 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 1553775 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 1553775 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 1553777 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1553777 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1553779 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
   [junit4]   2> 1553779 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 1553781 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
   [junit4]   2> 1553781 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 1553783 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1553784 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1553785 INFO  (zkCallback-1388-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@cac9502 name:ZooKeeperConnection Watcher:127.0.0.1:54021 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1553785 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1553786 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1553786 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 1553787 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1553788 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1553789 INFO  (zkCallback-1389-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@111dc3b8 name:ZooKeeperConnection Watcher:127.0.0.1:54021/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1553789 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1553789 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1553790 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml
   [junit4]   2> 1553790 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml
   [junit4]   2> 1553792 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml
   [junit4]   2> 1553792 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml
   [junit4]   2> 1553794 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml
   [junit4]   2> 1553794 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml
   [junit4]   2> 1553796 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1553796 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1553797 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt
   [junit4]   2> 1553798 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt
   [junit4]   2> 1553800 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt
   [junit4]   2> 1553800 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt
   [junit4]   2> 1553803 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml
   [junit4]   2> 1553803 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml
   [junit4]   2> 1553805 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json
   [junit4]   2> 1553805 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json
   [junit4]   2> 1553806 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1553807 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1553808 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt
   [junit4]   2> 1553808 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt
   [junit4]   2> 1553810 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt
   [junit4]   2> 1553810 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt
   [junit4]   2> 1553812 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml
   [junit4]   2> 1553812 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml
   [junit4]   2> 1553815 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 0x152f4fecd0d0003, 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> 1553921 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/control-001/cores/collection1
   [junit4]   2> 1553929 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.e.j.s.Server jetty-9.2.13.v20150730
   [junit4]   2> 1553931 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1cc2c574{/_ov,null,AVAILABLE}
   [junit4]   2> 1553932 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.e.j.s.ServerConnector Started ServerConnector@3731516c{HTTP/1.1}{127.0.0.1:58328}
   [junit4]   2> 1553932 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.e.j.s.Server Started @1556675ms
   [junit4]   2> 1553932 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/tempDir-001/control/data, hostContext=/_ov, hostPort=58328, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/control-001/cores}
   [junit4]   2> 1553933 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6
   [junit4]   2> 1553933 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/control-001'
   [junit4]   2> 1553933 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1553933 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 1553934 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1553935 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1553935 INFO  (zkCallback-1390-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@39fef2db name:ZooKeeperConnection Watcher:127.0.0.1:54021/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1553935 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1553936 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1553937 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 1553937 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/control-001/solr.xml
   [junit4]   2> 1553952 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/control-001/cores
   [junit4]   2> 1553952 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.CoreContainer New CoreContainer 952481345
   [junit4]   2> 1553952 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/control-001]
   [junit4]   2> 1553953 WARN  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/control-001/lib
   [junit4]   2> 1553953 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] 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> 1553954 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1553955 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1553955 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1553955 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54021/solr
   [junit4]   2> 1553955 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 1553955 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1553957 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1553958 INFO  (zkCallback-1393-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4c1d0597 name:ZooKeeperConnection Watcher:127.0.0.1:54021 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1553959 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1553959 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1553967 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1553971 INFO  (zkCallback-1394-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3fbcbfb7 name:ZooKeeperConnection Watcher:127.0.0.1:54021/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1553971 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1553979 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 1553983 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
   [junit4]   2> 1553986 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
   [junit4]   2> 1553988 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
   [junit4]   2> 1553989 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
   [junit4]   2> 1553991 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 1553993 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 1553994 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 1553995 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 1553996 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58328__ov
   [junit4]   2> 1553996 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:58328__ov
   [junit4]   2> 1553998 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 1553999 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
   [junit4]   2> 1554000 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1554000 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95407917327253510-127.0.0.1:58328__ov-n_0000000000
   [junit4]   2> 1554001 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:58328__ov
   [junit4]   2> 1554001 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 1554002 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.c.Overseer Overseer (id=95407917327253510-127.0.0.1:58328__ov-n_0000000000) starting
   [junit4]   2> 1554004 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 1554008 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 1554016 INFO  (OverseerCollectionConfigSetProcessor-95407917327253510-127.0.0.1:58328__ov-n_0000000000) [n:127.0.0.1:58328__ov    ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
   [junit4]   2> 1554017 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1554017 INFO  (OverseerStateUpdate-95407917327253510-127.0.0.1:58328__ov-n_0000000000) [n:127.0.0.1:58328__ov    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 1554036 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 1554036 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1554037 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/control-001/cores
   [junit4]   2> 1554038 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {config=solrconfig.xml, name=collection1, shard=, dataDir=data/, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, transient=false, collection=control_collection, coreNodeName=}
   [junit4]   2> 1554038 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/control-001/cores/collection1
   [junit4]   2> 1554039 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 1554046 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 1554046 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 1554046 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1
   [junit4]   2> 1554046 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:58328__ov    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 1554048 INFO  (OverseerStateUpdate-95407917327253510-127.0.0.1:58328__ov-n_0000000000) [n:127.0.0.1:58328__ov    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "roles":null,
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "node_name":"127.0.0.1:58328__ov",
   [junit4]   2>   "base_url":"http://127.0.0.1:58328/_ov",
   [junit4]   2>   "operation":"state"} current state version: 0
   [junit4]   2> 1554048 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection   x:collection1] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 1554048 INFO  (OverseerStateUpdate-95407917327253510-127.0.0.1:58328__ov-n_0000000000) [n:127.0.0.1:58328__ov    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "roles":null,
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "node_name":"127.0.0.1:58328__ov",
   [junit4]   2>   "base_url":"http://127.0.0.1:58328/_ov",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 1554049 INFO  (OverseerStateUpdate-95407917327253510-127.0.0.1:58328__ov-n_0000000000) [n:127.0.0.1:58328__ov    ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
   [junit4]   2> 1554050 INFO  (OverseerStateUpdate-95407917327253510-127.0.0.1:58328__ov-n_0000000000) [n:127.0.0.1:58328__ov    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 1554052 INFO  (zkCallback-1394-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov    ] 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> 1555048 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection   x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 1555049 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection   x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection
   [junit4]   2> 1555050 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection   x:collection1] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 1555050 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection]
   [junit4]   2> 1555050 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper
   [junit4]   2> 1555050 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/control-001/cores/collection1'
   [junit4]   2> 1555051 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1555051 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 1555053 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection   x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 
   [junit4]   2> 1555059 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection   x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1555073 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
   [junit4]   2> 1555103 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection   x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1555110 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1555369 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1555378 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection   x:collection1] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1555380 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection   x:collection1] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1555391 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1555394 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1555406 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1555406 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1555417 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection   x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1
   [junit4]   2> 1555417 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
   [junit4]   2> 1555418 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1555418 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/control-001/cores/collection1/data/]
   [junit4]   2> 1555418 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov 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@43668596
   [junit4]   2> 1555419 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/control-001/cores/collection1/data
   [junit4]   2> 1555419 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/control-001/cores/collection1/data/index/
   [junit4]   2> 1555419 WARN  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 1555420 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/control-001/cores/collection1/data/index
   [junit4]   2> 1555420 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=15.5419921875, floorSegmentMB=2.0146484375, forceMergeDeletesPctAllowed=11.556802371181195, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1555423 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 	commit{dir=RawDirectoryWrapper(SimpleFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/index-SimpleFSDirectory-001 lockFactory=org.apache.lucene.store.NativeFSLockFactory@535f9db),segFN=segments_1,generation=1}
   [junit4]   2> 1555423 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 1555424 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/control-001/cores/collection1/data/
   [junit4]   2> 1555424 WARN  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
   [junit4]   2> 1555428 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 1555429 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 1555429 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1555429 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1555429 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1555429 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov 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> 1555429 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov 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> 1555429 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov 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> 1555429 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov 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> 1555430 WARN  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov 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> 1555432 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1555434 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1555435 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1555436 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1555440 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/segments,/schema,/replication,/update/json,/update,/admin/logging,/get,/admin/properties,/admin/file,/admin/system,/admin/plugins,/update/json/docs,/admin/mbeans,/dump,/admin/ping,standard,/admin/threads,/admin/luke,/config,/update/csv
   [junit4]   2> 1555441 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov 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> 1555442 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov 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> 1555442 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov 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> 1555443 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1555443 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1555443 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=37.951171875, floorSegmentMB=1.978515625, forceMergeDeletesPctAllowed=13.327152974967708, segmentsPerTier=19.0, maxCFSSegmentSizeMB=0.259765625, noCFSRatio=1.0
   [junit4]   2> 1555444 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 	commit{dir=RawDirectoryWrapper(SimpleFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/index-SimpleFSDirectory-001 lockFactory=org.apache.lucene.store.NativeFSLockFactory@535f9db),segFN=segments_1,generation=1}
   [junit4]   2> 1555444 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 1555445 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@254e79a7[collection1] main
   [junit4]   2> 1555445 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov 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> 1555446 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov 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> 1555446 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov 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> 1555446 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 1555446 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 1555446 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov 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> 1555449 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov 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> 1555449 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov 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> 1555449 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1555449 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1555450 INFO  (searcherExecutor-3369-thread-1-processing-x:collection1 c:control_collection s:shard1 r:core_node1 n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@254e79a7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1555451 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov 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> 1555451 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov 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> 1555451 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov 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> 1555451 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov 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 1526526679132930048
   [junit4]   2> 1555453 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with highest version 1526526679132930048
   [junit4]   2> 1555453 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 1555454 INFO  (coreLoadExecutor-3368-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 1555454 INFO  (coreZkRegister-3362-thread-1-processing-x:collection1 c:control_collection s:shard1 r:core_node1 n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov 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:58328/_ov collection:control_collection shard:shard1
   [junit4]   2> 1555455 INFO  (coreZkRegister-3362-thread-1-processing-x:collection1 c:control_collection s:shard1 r:core_node1 n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov 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> 1555458 INFO  (coreZkRegister-3362-thread-1-processing-x:collection1 c:control_collection s:shard1 r:core_node1 n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov 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/95407917327253510-core_node1-n_0000000000
   [junit4]   2> 1555459 INFO  (coreZkRegister-3362-thread-1-processing-x:collection1 c:control_collection s:shard1 r:core_node1 n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov 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> 1555460 INFO  (coreZkRegister-3362-thread-1-processing-x:collection1 c:control_collection s:shard1 r:core_node1 n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1555460 INFO  (coreZkRegister-3362-thread-1-processing-x:collection1 c:control_collection s:shard1 r:core_node1 n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov 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> 1555460 INFO  (coreZkRegister-3362-thread-1-processing-x:collection1 c:control_collection s:shard1 r:core_node1 n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:58328/_ov/collection1/
   [junit4]   2> 1555460 INFO  (coreZkRegister-3362-thread-1-processing-x:collection1 c:control_collection s:shard1 r:core_node1 n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 1555461 INFO  (coreZkRegister-3362-thread-1-processing-x:collection1 c:control_collection s:shard1 r:core_node1 n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:58328/_ov/collection1/ has no replicas
   [junit4]   2> 1555461 INFO  (OverseerStateUpdate-95407917327253510-127.0.0.1:58328__ov-n_0000000000) [n:127.0.0.1:58328__ov    ] 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> 1555462 INFO  (coreZkRegister-3362-thread-1-processing-x:collection1 c:control_collection s:shard1 r:core_node1 n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 1555466 INFO  (coreZkRegister-3362-thread-1-processing-x:collection1 c:control_collection s:shard1 r:core_node1 n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
   [junit4]   2> 1555468 INFO  (coreZkRegister-3362-thread-1-processing-x:collection1 c:control_collection s:shard1 r:core_node1 n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov 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:58328/_ov/collection1/ shard1
   [junit4]   2> 1555468 INFO  (OverseerStateUpdate-95407917327253510-127.0.0.1:58328__ov-n_0000000000) [n:127.0.0.1:58328__ov    ] 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:58328/_ov",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 1
   [junit4]   2> 1555548 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1555548 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1555550 INFO  (zkCallback-1396-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@44d6bb28 name:ZooKeeperConnection Watcher:127.0.0.1:54021/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1555550 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1555550 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1555550 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1555553 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 1555553 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
   [junit4]   2> 1555553 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1555553 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1555554 INFO  (zkCallback-1397-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@116a34c8 name:ZooKeeperConnection Watcher:127.0.0.1:54021/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1555554 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1555555 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1555561 INFO  (OverseerStateUpdate-95407917327253510-127.0.0.1:58328__ov-n_0000000000) [n:127.0.0.1:58328__ov    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"create",
   [junit4]   2>   "name":"collection1",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "stateFormat":"2"} current state version: 1
   [junit4]   2> 1555561 INFO  (OverseerStateUpdate-95407917327253510-127.0.0.1:58328__ov-n_0000000000) [n:127.0.0.1:58328__ov    ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1
   [junit4]   2> 1555561 INFO  (zkCallback-1396-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> 1555561 INFO  (zkCallback-1394-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov    ] 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> 1555569 INFO  (coreZkRegister-3362-thread-1-processing-x:collection1 c:control_collection s:shard1 r:core_node1 n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:58328/_ov/collection1/ and leader is http://127.0.0.1:58328/_ov/collection1/
   [junit4]   2> 1555569 INFO  (coreZkRegister-3362-thread-1-processing-x:collection1 c:control_collection s:shard1 r:core_node1 n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov 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:58328/_ov
   [junit4]   2> 1555569 INFO  (coreZkRegister-3362-thread-1-processing-x:collection1 c:control_collection s:shard1 r:core_node1 n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1555569 INFO  (coreZkRegister-3362-thread-1-processing-x:collection1 c:control_collection s:shard1 r:core_node1 n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active
   [junit4]   2> 1555569 INFO  (coreZkRegister-3362-thread-1-processing-x:collection1 c:control_collection s:shard1 r:core_node1 n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov 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> 1555571 INFO  (OverseerStateUpdate-95407917327253510-127.0.0.1:58328__ov-n_0000000000) [n:127.0.0.1:58328__ov    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "node_name":"127.0.0.1:58328__ov",
   [junit4]   2>   "base_url":"http://127.0.0.1:58328/_ov",
   [junit4]   2>   "operation":"state"} current state version: 2
   [junit4]   2> 1555571 INFO  (OverseerStateUpdate-95407917327253510-127.0.0.1:58328__ov-n_0000000000) [n:127.0.0.1:58328__ov    ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "node_name":"127.0.0.1:58328__ov",
   [junit4]   2>   "base_url":"http://127.0.0.1:58328/_ov",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 1555572 INFO  (OverseerStateUpdate-95407917327253510-127.0.0.1:58328__ov-n_0000000000) [n:127.0.0.1:58328__ov    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json
   [junit4]   2> 1555658 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/shard-1-001/cores/collection1
   [junit4]   2> 1555659 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/shard-1-001
   [junit4]   2> 1555661 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.e.j.s.Server jetty-9.2.13.v20150730
   [junit4]   2> 1555662 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@74b8a208{/_ov,null,AVAILABLE}
   [junit4]   2> 1555663 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.e.j.s.ServerConnector Started ServerConnector@595f716{HTTP/1.1}{127.0.0.1:56434}
   [junit4]   2> 1555663 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.e.j.s.Server Started @1558406ms
   [junit4]   2> 1555663 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/_ov, hostPort=56434, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/shard-1-001/cores, solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/tempDir-001/jetty1, solrconfig=solrconfig.xml}
   [junit4]   2> 1555663 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6
   [junit4]   2> 1555663 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/shard-1-001'
   [junit4]   2> 1555664 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1555664 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 1555664 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1555664 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1555666 INFO  (zkCallback-1398-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@20406f24 name:ZooKeeperConnection Watcher:127.0.0.1:54021/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1555666 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1555666 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1555667 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 1555667 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/shard-1-001/solr.xml
   [junit4]   2> 1555676 INFO  (zkCallback-1394-thread-1-processing-n:127.0.0.1:58328__ov) [n:127.0.0.1:58328__ov    ] 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> 1555676 INFO  (zkCallback-1396-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> 1555682 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/shard-1-001/cores
   [junit4]   2> 1555682 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.CoreContainer New CoreContainer 437054772
   [junit4]   2> 1555682 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/shard-1-001]
   [junit4]   2> 1555682 WARN  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7F9B80506B2CE5E8-001/shard-1-001/lib
   [junit4]   2> 1555682 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] 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> 1555683 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1555684 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1555684 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1555684 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54021/solr
   [junit4]   2> 1555684 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 1555684 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1555685 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1555686 INFO  (zkCallback-1401-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1cfc7d89 name:ZooKeeperConnection Watcher:127.0.0.1:54021 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1555687 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1555687 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1555688 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:56434__ov    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1555689 INFO  (zkCallback-1402-thread-1-processing-n:127.0.0.1:56434__ov) [n:127.0.0.1:56434__ov    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@311790b3 name:ZooKeeperConnection Watcher:127.0.0.1:54021/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1555689 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:56434__ov    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1555695 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:56434__ov    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1556701 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7F9B80506B2CE5E8]) [n:127.0.0.1:56434__ov    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:5643

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

e Closing SolrCoreState
   [junit4]   2> 2898275 INFO  (coreCloseExecutor-6646-thread-1-processing-x:core1) [    x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 2898275 INFO  (coreCloseExecutor-6646-thread-1-processing-x:core1) [    x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
   [junit4]   2> 2898283 INFO  (coreCloseExecutor-6646-thread-1-processing-x:core1) [    x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
   [junit4]   2> 2898296 INFO  (coreCloseExecutor-6646-thread-1-processing-x:core1) [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 2910580 ERROR (coreCloseExecutor-6646-thread-1-processing-x:core1) [    x:collection1] o.a.s.c.CachingDirectoryFactory Timeout waiting for all directory ref counts to be released - gave up waiting on CachedDir<<refCount=3;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.search.TestIndexSearcher_7F9B80506B2CE5E8-001/init-core-data-001/index;done=false>>
   [junit4]   2> 2910581 ERROR (coreCloseExecutor-6646-thread-1-processing-x:core1) [    x:collection1] o.a.s.c.CachingDirectoryFactory Error closing directory:org.apache.solr.common.SolrException: Timeout waiting for all directory ref counts to be released - gave up waiting on CachedDir<<refCount=3;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.search.TestIndexSearcher_7F9B80506B2CE5E8-001/init-core-data-001/index;done=false>>
   [junit4]   2> 	at org.apache.solr.core.CachingDirectoryFactory.close(CachingDirectoryFactory.java:187)
   [junit4]   2> 	at org.apache.solr.core.SolrCore.close(SolrCore.java:1275)
   [junit4]   2> 	at org.apache.solr.core.SolrCores$2.call(SolrCores.java:134)
   [junit4]   2> 	at org.apache.solr.core.SolrCores$2.call(SolrCores.java:129)
   [junit4]   2> 	at java.util.concurrent.FutureTask.run(FutureTask.java:262)
   [junit4]   2> 	at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:231)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 
   [junit4]   2> 2910581 INFO  (coreCloseExecutor-6646-thread-1-processing-x:core1) [    x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.search.TestIndexSearcher_7F9B80506B2CE5E8-001/init-core-data-001 [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.search.TestIndexSearcher_7F9B80506B2CE5E8-001/init-core-data-001;done=false>>]
   [junit4]   2> 2910581 INFO  (coreCloseExecutor-6646-thread-1-processing-x:core1) [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.search.TestIndexSearcher_7F9B80506B2CE5E8-001/init-core-data-001
   [junit4]   2> 2910581 ERROR (coreCloseExecutor-6646-thread-1-processing-x:core1) [    x:collection1] o.a.s.c.SolrCore java.lang.AssertionError: 3
   [junit4]   2> 	at org.apache.solr.core.CachingDirectoryFactory.close(CachingDirectoryFactory.java:201)
   [junit4]   2> 	at org.apache.solr.core.SolrCore.close(SolrCore.java:1275)
   [junit4]   2> 	at org.apache.solr.core.SolrCores$2.call(SolrCores.java:134)
   [junit4]   2> 	at org.apache.solr.core.SolrCores$2.call(SolrCores.java:129)
   [junit4]   2> 	at java.util.concurrent.FutureTask.run(FutureTask.java:262)
   [junit4]   2> 	at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:231)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 
   [junit4]   2> 2910581 ERROR (coreCloseExecutor-6646-thread-1-processing-x:core1) [    x:collection1] o.a.s.c.SolrCores Error shutting down core:java.lang.AssertionError: 3
   [junit4]   2> 	at org.apache.solr.core.CachingDirectoryFactory.close(CachingDirectoryFactory.java:201)
   [junit4]   2> 	at org.apache.solr.core.SolrCore.close(SolrCore.java:1275)
   [junit4]   2> 	at org.apache.solr.core.SolrCores$2.call(SolrCores.java:134)
   [junit4]   2> 	at org.apache.solr.core.SolrCores$2.call(SolrCores.java:129)
   [junit4]   2> 	at java.util.concurrent.FutureTask.run(FutureTask.java:262)
   [junit4]   2> 	at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:231)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 
   [junit4]   2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.search.TestIndexSearcher_7F9B80506B2CE5E8-001
   [junit4]   2> NOTE: test params are: codec=Lucene54, sim=RandomSimilarity(queryNorm=false,coord=yes): {}, locale=es-HN, timezone=Africa/Kigali
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=4,threads=1,free=128678936,total=532676608
   [junit4]   2> NOTE: All tests run in this JVM: [SynonymTokenizerTest, ResourceLoaderTest, RequiredFieldsTest, TestFoldingMultitermQuery, TestSimpleQParserPlugin, SyncSliceTest, QueryElevationComponentTest, TestSolrCloudWithKerberosAlt, TestHashQParserPlugin, AlternateDirectoryTest, DateFieldTest, CustomCollectionTest, TestComponentsName, TestCSVLoader, TestSerializedLuceneMatchVersion, TestManagedSchemaFieldTypeResource, TestConfigSetsAPIExclusivity, TestBinaryField, PrimUtilsTest, HdfsChaosMonkeySafeLeaderTest, PathHierarchyTokenizerFactoryTest, RAMDirectoryFactoryTest, RestartWhileUpdatingTest, TestConfig, UUIDFieldTest, TestRTimerTree, TestSolr4Spatial, TestReloadAndDeleteDocs, TestPKIAuthenticationPlugin, TestFieldTypeResource, TestLazyCores, ConcurrentDeleteAndCreateCollectionTest, TestOrdValues, ActionThrottleTest, TestDocSet, TestAnalyzedSuggestions, DeleteReplicaTest, TestDistributedStatsComponentCardinality, ExternalFileFieldSortTest, TestChildDocTransformer, TestRequestForwarding, CacheHeaderTest, TestSchemaResource, PeerSyncTest, SmileWriterTest, TestInfoStreamLogging, ParsingFieldUpdateProcessorsTest, TestReqParamsAPI, TestPhraseSuggestions, LeaderInitiatedRecoveryOnShardRestartTest, BadCopyFieldTest, DistributedFacetPivotSmallTest, TestBulkSchemaAPI, BufferStoreTest, TestOmitPositions, QueryEqualityTest, TestCloudPivotFacet, TestBlobHandler, IndexSchemaTest, TestFieldCollectionResource, TestTrackingShardHandlerFactory, TestMiniSolrCloudClusterBase, AnalyticsMergeStrategyTest, TestPseudoReturnFields, CurrencyFieldOpenExchangeTest, TestDefaultSearchFieldResource, TestRecoveryHdfs, TestBulkSchemaConcurrent, PreAnalyzedFieldTest, TestArbitraryIndexDir, SolrRequestParserTest, TestRandomCollapseQParserPlugin, TestMergePolicyConfig, TestLRUCache, TestBlendedInfixSuggestions, HdfsBasicDistributedZk2Test, CloneFieldUpdateProcessorFactoryTest, ZkStateReaderTest, TestLuceneMatchVersion, TestConfigOverlay, EchoParamsTest, TestFunctionQuery, SegmentsInfoRequestHandlerTest, TestLeaderElectionZkExpiry, FileBasedSpellCheckerTest, ConnectionReuseTest, TestRandomDVFaceting, VersionInfoTest, RulesTest, TestScoreJoinQPNoScore, OverseerRolesTest, BigEndianAscendingWordDeserializerTest, SpatialHeatmapFacetsTest, XsltUpdateRequestHandlerTest, UnloadDistributedZkTest, LeaderElectionIntegrationTest, ShardRoutingTest, BasicZkTest, FullSolrCloudDistribCmdsTest, TestReplicationHandler, TestDistributedGrouping, TestFaceting, TermVectorComponentDistributedTest, TestStressReorder, HardAutoCommitTest, DistributedTermsComponentTest, SolrCoreTest, TestGroupingSearch, ConvertedLegacyTest, BasicFunctionalityTest, TestBadConfig, TestIndexSearcher]
   [junit4] Completed [302/566 (3!)] on J0 in 21.18s, 5 tests, 1 failure <<< FAILURES!

[...truncated 826 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/build.xml:757: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/build.xml:694: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/build.xml:59: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build.xml:233: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/common-build.xml:534: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/lucene/common-build.xml:1477: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/lucene/common-build.xml:1033: There were test failures: 566 suites (7 ignored), 2275 tests, 1 suite-level error, 1 error, 1 failure, 34 ignored (18 assumptions) [seed: 7F9B80506B2CE5E8]

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



Mime
View raw message