Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/923/
2 tests failed. FAILED: org.apache.solr.handler.TestReplicationHandler.doTestReplicateAfterCoreReload Error Message: expected:<[{indexVersion=1454523269151,generation=2,filelist=[_ec7.cfe, _ec7.cfs, _ec7.si, _ed9.cfe, _ed9.cfs, _ed9.si, _eda.cfe, _eda.cfs, _eda.si, _edb.cfe, _edb.cfs, _edb.si, _edc.cfe, _edc.cfs, _edc.si, _edd.cfe, _edd.cfs, _edd.si, _ede.cfe, _ede.cfs, _ede.si, _edf.cfe, _edf.cfs, _edf.si, _edg.cfe, _edg.cfs, _edg.si, _edh.cfe, _edh.cfs, _edh.si, _edi.cfe, _edi.cfs, _edi.si, _edj.cfe, _edj.cfs, _edj.si, _edk.cfe, _edk.cfs, _edk.si, _edl.cfe, _edl.cfs, _edl.si, _edm.cfe, _edm.cfs, _edm.si, _edn.cfe, _edn.cfs, _edn.si, _edo.cfe, _edo.cfs, _edo.si, _edp.cfe, _edp.cfs, _edp.si, _edq.cfe, _edq.cfs, _edq.si, _edr.cfe, _edr.cfs, _edr.si, _eds.cfe, _eds.cfs, _eds.si, _edt.cfe, _edt.cfs, _edt.si, _edu.cfe, _edu.cfs, _edu.si, _edv.cfe, _edv.cfs, _edv.si, _edw.cfe, _edw.cfs, _edw.si, _edx.cfe, _edx.cfs, _edx.si, _edy.cfe, _edy.cfs, _edy.si, _edz.cfe, _edz.cfs, _edz.si, _ee0.cfe, _ee0.cfs, _ee0.si, _ee1.cfe, _ee1.cfs, _ee1.si, _ee2.cfe, _ee2.cfs, _ee2.si, _ee3.cfe, _ee3.cfs, _ee3.si, _ee4.cfe, _ee4.cfs, _ee4.si, _ee5.cfe, _ee5.cfs, _ee5.si, _ee6.cfe, _ee6.cfs, _ee6.si, _ee7.cfe, _ee7.cfs, _ee7.si, _ee8.cfe, _ee8.cfs, _ee8.si, _ee9.cfe, _ee9.cfs, _ee9.si, _eea.cfe, _eea.cfs, _eea.si, _eec.cfe, _eec.cfs, _eec.si, _eed.cfe, _eed.cfs, _eed.si, _eee.cfe, _eee.cfs, _eee.si, _eef.cfe, _eef.cfs, _eef.si, _eeg.cfe, _eeg.cfs, _eeg.si, _eeh.cfe, _eeh.cfs, _eeh.si, _eei.cfe, _eei.cfs, _eei.si, _eej.cfe, _eej.cfs, _eej.si, _eek.cfe, _eek.cfs, _eek.si, _eel.cfe, _eel.cfs, _eel.si, _eem.cfe, _eem.cfs, _eem.si, _een.cfe, _een.cfs, _een.si, _eeo.cfe, _eeo.cfs, _eeo.si, segments_2]}]> but was:<[{indexVersion=1454523269151,generation=2,filelist=[_ec7.cfe, _ec7.cfs, _ec7.si, _ed9.cfe, _ed9.cfs, _ed9.si, _eda.cfe, _eda.cfs, _eda.si, _edb.cfe, _edb.cfs, _edb.si, _edc.cfe, _edc.cfs, _edc.si, _edd.cfe, _edd.cfs, _edd.si, _ede.cfe, _ede.cfs, _ede.si, _edf.cfe, _edf.cfs, _edf.si, _edg.cfe, _edg.cfs, _edg.si, _edh.cfe, _edh.cfs, _edh.si, _edi.cfe, _edi.cfs, _edi.si, _edj.cfe, _edj.cfs, _edj.si, _edk.cfe, _edk.cfs, _edk.si, _edl.cfe, _edl.cfs, _edl.si, _edm.cfe, _edm.cfs, _edm.si, _edn.cfe, _edn.cfs, _edn.si, _edo.cfe, _edo.cfs, _edo.si, _edp.cfe, _edp.cfs, _edp.si, _edq.cfe, _edq.cfs, _edq.si, _edr.cfe, _edr.cfs, _edr.si, _eds.cfe, _eds.cfs, _eds.si, _edt.cfe, _edt.cfs, _edt.si, _edu.cfe, _edu.cfs, _edu.si, _edv.cfe, _edv.cfs, _edv.si, _edw.cfe, _edw.cfs, _edw.si, _edx.cfe, _edx.cfs, _edx.si, _edy.cfe, _edy.cfs, _edy.si, _edz.cfe, _edz.cfs, _edz.si, _ee0.cfe, _ee0.cfs, _ee0.si, _ee1.cfe, _ee1.cfs, _ee1.si, _ee2.cfe, _ee2.cfs, _ee2.si, _ee3.cfe, _ee3.cfs, _ee3.si, _ee4.cfe, _ee4.cfs, _ee4.si, _ee5.cfe, _ee5.cfs, _ee5.si, _ee6.cfe, _ee6.cfs, _ee6.si, _ee7.cfe, _ee7.cfs, _ee7.si, _ee8.cfe, _ee8.cfs, _ee8.si, _ee9.cfe, _ee9.cfs, _ee9.si, _eea.cfe, _eea.cfs, _eea.si, _eec.cfe, _eec.cfs, _eec.si, _eed.cfe, _eed.cfs, _eed.si, _eee.cfe, _eee.cfs, _eee.si, _eef.cfe, _eef.cfs, _eef.si, _eeg.cfe, _eeg.cfs, _eeg.si, _eeh.cfe, _eeh.cfs, _eeh.si, _eei.cfe, _eei.cfs, _eei.si, _eej.cfe, _eej.cfs, _eej.si, _eek.cfe, _eek.cfs, _eek.si, _eel.cfe, _eel.cfs, _eel.si, _eem.cfe, _eem.cfs, _eem.si, _een.cfe, _een.cfs, _een.si, _eeo.cfe, _eeo.cfs, _eeo.si, segments_2]}, {indexVersion=1454523269151,generation=3,filelist=[_eea.cfe, _eea.cfs, _eea.si, _eeb.cfe, _eeb.cfs, _eeb.si, _eec.cfe, _eec.cfs, _eec.si, _eed.cfe, _eed.cfs, _eed.si, _eee.cfe, _eee.cfs, _eee.si, _eef.cfe, _eef.cfs, _eef.si, _eeg.cfe, _eeg.cfs, _eeg.si, _eeh.cfe, _eeh.cfs, _eeh.si, _eei.cfe, _eei.cfs, _eei.si, _eej.cfe, _eej.cfs, _eej.si, _eek.cfe, _eek.cfs, _eek.si, _eel.cfe, _eel.cfs, _eel.si, _eem.cfe, _eem.cfs, _eem.si, _een.cfe, _een.cfs, _een.si, _eeo.cfe, _eeo.cfs, _eeo.si, segments_3]}]> Stack Trace: java.lang.AssertionError: expected:<[{indexVersion=1454523269151,generation=2,filelist=[_ec7.cfe, _ec7.cfs, _ec7.si, _ed9.cfe, _ed9.cfs, _ed9.si, _eda.cfe, _eda.cfs, _eda.si, _edb.cfe, _edb.cfs, _edb.si, _edc.cfe, _edc.cfs, _edc.si, _edd.cfe, _edd.cfs, _edd.si, _ede.cfe, _ede.cfs, _ede.si, _edf.cfe, _edf.cfs, _edf.si, _edg.cfe, _edg.cfs, _edg.si, _edh.cfe, _edh.cfs, _edh.si, _edi.cfe, _edi.cfs, _edi.si, _edj.cfe, _edj.cfs, _edj.si, _edk.cfe, _edk.cfs, _edk.si, _edl.cfe, _edl.cfs, _edl.si, _edm.cfe, _edm.cfs, _edm.si, _edn.cfe, _edn.cfs, _edn.si, _edo.cfe, _edo.cfs, _edo.si, _edp.cfe, _edp.cfs, _edp.si, _edq.cfe, _edq.cfs, _edq.si, _edr.cfe, _edr.cfs, _edr.si, _eds.cfe, _eds.cfs, _eds.si, _edt.cfe, _edt.cfs, _edt.si, _edu.cfe, _edu.cfs, _edu.si, _edv.cfe, _edv.cfs, _edv.si, _edw.cfe, _edw.cfs, _edw.si, _edx.cfe, _edx.cfs, _edx.si, _edy.cfe, _edy.cfs, _edy.si, _edz.cfe, _edz.cfs, _edz.si, _ee0.cfe, _ee0.cfs, _ee0.si, _ee1.cfe, _ee1.cfs, _ee1.si, _ee2.cfe, _ee2.cfs, _ee2.si, _ee3.cfe, _ee3.cfs, _ee3.si, _ee4.cfe, _ee4.cfs, _ee4.si, _ee5.cfe, _ee5.cfs, _ee5.si, _ee6.cfe, _ee6.cfs, _ee6.si, _ee7.cfe, _ee7.cfs, _ee7.si, _ee8.cfe, _ee8.cfs, _ee8.si, _ee9.cfe, _ee9.cfs, _ee9.si, _eea.cfe, _eea.cfs, _eea.si, _eec.cfe, _eec.cfs, _eec.si, _eed.cfe, _eed.cfs, _eed.si, _eee.cfe, _eee.cfs, _eee.si, _eef.cfe, _eef.cfs, _eef.si, _eeg.cfe, _eeg.cfs, _eeg.si, _eeh.cfe, _eeh.cfs, _eeh.si, _eei.cfe, _eei.cfs, _eei.si, _eej.cfe, _eej.cfs, _eej.si, _eek.cfe, _eek.cfs, _eek.si, _eel.cfe, _eel.cfs, _eel.si, _eem.cfe, _eem.cfs, _eem.si, _een.cfe, _een.cfs, _een.si, _eeo.cfe, _eeo.cfs, _eeo.si, segments_2]}]> but was:<[{indexVersion=1454523269151,generation=2,filelist=[_ec7.cfe, _ec7.cfs, _ec7.si, _ed9.cfe, _ed9.cfs, _ed9.si, _eda.cfe, _eda.cfs, _eda.si, _edb.cfe, _edb.cfs, _edb.si, _edc.cfe, _edc.cfs, _edc.si, _edd.cfe, _edd.cfs, _edd.si, _ede.cfe, _ede.cfs, _ede.si, _edf.cfe, _edf.cfs, _edf.si, _edg.cfe, _edg.cfs, _edg.si, _edh.cfe, _edh.cfs, _edh.si, _edi.cfe, _edi.cfs, _edi.si, _edj.cfe, _edj.cfs, _edj.si, _edk.cfe, _edk.cfs, _edk.si, _edl.cfe, _edl.cfs, _edl.si, _edm.cfe, _edm.cfs, _edm.si, _edn.cfe, _edn.cfs, _edn.si, _edo.cfe, _edo.cfs, _edo.si, _edp.cfe, _edp.cfs, _edp.si, _edq.cfe, _edq.cfs, _edq.si, _edr.cfe, _edr.cfs, _edr.si, _eds.cfe, _eds.cfs, _eds.si, _edt.cfe, _edt.cfs, _edt.si, _edu.cfe, _edu.cfs, _edu.si, _edv.cfe, _edv.cfs, _edv.si, _edw.cfe, _edw.cfs, _edw.si, _edx.cfe, _edx.cfs, _edx.si, _edy.cfe, _edy.cfs, _edy.si, _edz.cfe, _edz.cfs, _edz.si, _ee0.cfe, _ee0.cfs, _ee0.si, _ee1.cfe, _ee1.cfs, _ee1.si, _ee2.cfe, _ee2.cfs, _ee2.si, _ee3.cfe, _ee3.cfs, _ee3.si, _ee4.cfe, _ee4.cfs, _ee4.si, _ee5.cfe, _ee5.cfs, _ee5.si, _ee6.cfe, _ee6.cfs, _ee6.si, _ee7.cfe, _ee7.cfs, _ee7.si, _ee8.cfe, _ee8.cfs, _ee8.si, _ee9.cfe, _ee9.cfs, _ee9.si, _eea.cfe, _eea.cfs, _eea.si, _eec.cfe, _eec.cfs, _eec.si, _eed.cfe, _eed.cfs, _eed.si, _eee.cfe, _eee.cfs, _eee.si, _eef.cfe, _eef.cfs, _eef.si, _eeg.cfe, _eeg.cfs, _eeg.si, _eeh.cfe, _eeh.cfs, _eeh.si, _eei.cfe, _eei.cfs, _eei.si, _eej.cfe, _eej.cfs, _eej.si, _eek.cfe, _eek.cfs, _eek.si, _eel.cfe, _eel.cfs, _eel.si, _eem.cfe, _eem.cfs, _eem.si, _een.cfe, _een.cfs, _een.si, _eeo.cfe, _eeo.cfs, _eeo.si, segments_2]}, {indexVersion=1454523269151,generation=3,filelist=[_eea.cfe, _eea.cfs, _eea.si, _eeb.cfe, _eeb.cfs, _eeb.si, _eec.cfe, _eec.cfs, _eec.si, _eed.cfe, _eed.cfs, _eed.si, _eee.cfe, _eee.cfs, _eee.si, _eef.cfe, _eef.cfs, _eef.si, _eeg.cfe, _eeg.cfs, _eeg.si, _eeh.cfe, _eeh.cfs, _eeh.si, _eei.cfe, _eei.cfs, _eei.si, _eej.cfe, _eej.cfs, _eej.si, _eek.cfe, _eek.cfs, _eek.si, _eel.cfe, _eel.cfs, _eel.si, _eem.cfe, _eem.cfs, _eem.si, _een.cfe, _een.cfs, _een.si, _eeo.cfe, _eeo.cfs, _eeo.si, segments_3]}]> at __randomizedtesting.SeedInfo.seed([759A047477622D74:504D1F44072A2377]: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.handler.TestReplicationHandler.doTestReplicateAfterCoreReload(TestReplicationHandler.java:1140) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:497) 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:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java: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:46) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java: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:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at java.lang.Thread.run(Thread.java:745) FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=5698, name=collection5, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=5698, name=collection5, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Caused by: java.lang.RuntimeException: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:35952/ag_xou, http://127.0.0.1:49017/ag_xou, http://127.0.0.1:49145/ag_xou, http://127.0.0.1:34165/ag_xou, http://127.0.0.1:58172/ag_xou] at __randomizedtesting.SeedInfo.seed([759A047477622D74]:0) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:897) Caused by: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:35952/ag_xou, http://127.0.0.1:49017/ag_xou, http://127.0.0.1:49145/ag_xou, http://127.0.0.1:34165/ag_xou, http://127.0.0.1:58172/ag_xou] at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:352) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1100) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:871) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:807) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1593) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1614) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:887) Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:58172/ag_xou: KeeperErrorCode = Session expired for /overseer/collection-queue-work/qnr- 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) ... 7 more Build Log: [...truncated 10879 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_759A047477622D74-001/init-core-data-001 [junit4] 2> 246847 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[759A047477622D74]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 246848 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[759A047477622D74]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /ag_xou/ [junit4] 2> 246860 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 246861 INFO (Thread-4240) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 246861 INFO (Thread-4240) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 246962 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.ZkTestServer start zk server on port:40232 [junit4] 2> 246962 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 246988 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 247025 INFO (zkCallback-251-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@27c518 name:ZooKeeperConnection Watcher:127.0.0.1:40232 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 247025 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 247025 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 247025 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 247038 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 247058 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 247079 INFO (zkCallback-252-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4ea1a72c name:ZooKeeperConnection Watcher:127.0.0.1:40232/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 247079 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 247079 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 247079 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 247094 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 247095 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 247111 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 247112 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 247113 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 247114 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 247115 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 247116 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 247116 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 247117 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 247117 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 247123 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 247124 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 247127 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 247128 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 247129 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 247129 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 247130 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 247130 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 247131 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 247131 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 247132 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 247133 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 247137 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 247137 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 247148 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 247172 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 247198 INFO (zkCallback-253-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@787bc721 name:ZooKeeperConnection Watcher:127.0.0.1:40232 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 247198 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 247199 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 247199 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 247201 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 247245 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 247260 INFO (zkCallback-254-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6195d1d8 name:ZooKeeperConnection Watcher:127.0.0.1:40232/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 247260 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 247260 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 247261 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml [junit4] 2> 247261 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml [junit4] 2> 247268 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml [junit4] 2> 247269 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml [junit4] 2> 247270 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml [junit4] 2> 247270 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml [junit4] 2> 247271 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 247271 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 247273 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt [junit4] 2> 247273 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt [junit4] 2> 247274 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt [junit4] 2> 247274 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt [junit4] 2> 247276 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml [junit4] 2> 247276 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml [junit4] 2> 247277 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json [junit4] 2> 247277 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json [junit4] 2> 247278 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 247278 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 247279 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt [junit4] 2> 247279 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt [junit4] 2> 247281 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt [junit4] 2> 247281 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt [junit4] 2> 247282 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml [junit4] 2> 247282 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml [junit4] 2> 247510 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_759A047477622D74-001/control-001/cores/collection1 [junit4] 2> 247512 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 247525 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6db2937a{/ag_xou,null,AVAILABLE} [junit4] 2> 247526 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1481fbb2{HTTP/1.1}{127.0.0.1:58172} [junit4] 2> 247526 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.e.j.s.Server Started @251243ms [junit4] 2> 247526 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_759A047477622D74-001/tempDir-001/control/data, hostContext=/ag_xou, hostPort=58172, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_759A047477622D74-001/control-001/cores} [junit4] 2> 247526 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 247526 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_759A047477622D74-001/control-001' [junit4] 2> 247526 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 247526 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 247527 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 247527 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 247531 INFO (zkCallback-255-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@31ce77af name:ZooKeeperConnection Watcher:127.0.0.1:40232/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 247531 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 247531 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 247546 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 247546 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_759A047477622D74-001/control-001/solr.xml [junit4] 2> 247561 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_759A047477622D74-001/control-001/cores [junit4] 2> 247563 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.CoreContainer New CoreContainer 127530046 [junit4] 2> 247563 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_759A047477622D74-001/control-001] [junit4] 2> 247563 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_759A047477622D74-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_759A047477622D74-001/control-001/lib [junit4] 2> 247564 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] 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> 247565 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 247566 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 247566 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 247566 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40232/solr [junit4] 2> 247566 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 247566 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 247587 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 247599 INFO (zkCallback-258-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@42ec13f3 name:ZooKeeperConnection Watcher:127.0.0.1:40232 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 247599 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 247600 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 247602 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 247607 INFO (zkCallback-259-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3b7ed9d1 name:ZooKeeperConnection Watcher:127.0.0.1:40232/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 247607 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 247608 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 247610 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 247613 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 247615 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 247616 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 247617 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 247624 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 247625 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 247626 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 247627 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58172_ag_xou [junit4] 2> 247627 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:58172_ag_xou [junit4] 2> 247628 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 247629 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 247630 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 247631 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95323510980608006-127.0.0.1:58172_ag_xou-n_0000000000 [junit4] 2> 247631 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:58172_ag_xou [junit4] 2> 247631 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 247632 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.Overseer Overseer (id=95323510980608006-127.0.0.1:58172_ag_xou-n_0000000000) starting [junit4] 2> 247633 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 247638 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 247639 INFO (OverseerStateUpdate-95323510980608006-127.0.0.1:58172_ag_xou-n_0000000000) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 247639 INFO (OverseerStateUpdate-95323510980608006-127.0.0.1:58172_ag_xou-n_0000000000) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 247643 INFO (OverseerCollectionConfigSetProcessor-95323510980608006-127.0.0.1:58172_ag_xou-n_0000000000) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 247654 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 247668 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 247668 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 247683 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_759A047477622D74-001/control-001/cores [junit4] 2> 247684 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=control_collection, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 247684 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_759A047477622D74-001/control-001/cores/collection1 [junit4] 2> 247684 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 247692 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 247692 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 247697 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 247697 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [n:127.0.0.1:58172_ag_xou ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 247697 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 247698 INFO (OverseerStateUpdate-95323510980608006-127.0.0.1:58172_ag_xou-n_0000000000) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:58172/ag_xou", [junit4] 2> "node_name":"127.0.0.1:58172_ag_xou", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 247698 INFO (OverseerStateUpdate-95323510980608006-127.0.0.1:58172_ag_xou-n_0000000000) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:58172/ag_xou", [junit4] 2> "node_name":"127.0.0.1:58172_ag_xou", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 247698 INFO (OverseerStateUpdate-95323510980608006-127.0.0.1:58172_ag_xou-n_0000000000) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 247699 INFO (OverseerStateUpdate-95323510980608006-127.0.0.1:58172_ag_xou-n_0000000000) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 247701 INFO (zkCallback-259-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou ] 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> 247701 INFO (zkCallback-259-thread-2-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou ] 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> 248698 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 248698 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 248707 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 248707 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection] [junit4] 2> 248707 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 248707 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_759A047477622D74-001/control-001/cores/collection1' [junit4] 2> 248707 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 248707 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 248714 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 248723 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 248727 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 248736 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 248742 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 248902 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 248908 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 248910 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 248940 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 248942 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 248944 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 248944 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 248946 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou 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-trunk/solr/build/solr-core/test/J1 [junit4] 2> 248946 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 248946 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 248946 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_759A047477622D74-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_759A047477622D74-001/control-001/cores/collection1/data/] [junit4] 2> 248946 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou 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@67bf7365 [junit4] 2> 248947 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_759A047477622D74-001/control-001/cores/collection1/data [junit4] 2> 248948 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_759A047477622D74-001/control-001/cores/collection1/data/index/ [junit4] 2> 248948 WARN (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_759A047477622D74-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 248948 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_759A047477622D74-001/control-001/cores/collection1/data/index [junit4] 2> 248948 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1379827783, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 248949 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@44501e4a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@55d22588),segFN=segments_1,generation=1} [junit4] 2> 248949 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 248953 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 248953 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 248953 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 248953 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 248954 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 248954 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou 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> 248954 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou 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> 248954 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou 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> 248954 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou 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> 248954 WARN (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou 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> 248955 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_759A047477622D74-001/control-001/cores/collection1/data/ [junit4] 2> 248956 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_759A047477622D74-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 248956 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 248964 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 248965 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 248967 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 248971 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/dump,/update/csv,/sql,/update/json/docs,/admin/luke,/export,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 248972 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou 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> 248972 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou 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> 248972 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou 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> 248973 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 248973 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 248974 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=16, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 248974 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@44501e4a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@55d22588),segFN=segments_1,generation=1} [junit4] 2> 248974 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 248974 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1566e7ea[collection1] main] [junit4] 2> 248975 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou 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> 248975 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou 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> 248975 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou 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> 248975 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 248975 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 248976 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou 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> 248976 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou 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> 248976 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou 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> 248976 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 248976 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 248976 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou 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> 248977 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou 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> 248977 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou 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> 248977 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou 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 1525176177895931904 [junit4] 2> 248987 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 10.0ms to seed version buckets with highest version 1525176177895931904 [junit4] 2> 248994 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 248995 INFO (coreLoadExecutor-540-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 248998 INFO (searcherExecutor-541-thread-1-processing-n:127.0.0.1:58172_ag_xou x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1566e7ea[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 248998 INFO (coreZkRegister-534-thread-1-processing-n:127.0.0.1:58172_ag_xou x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58172_ag_xou 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:58172/ag_xou collection:control_collection shard:shard1 [junit4] 2> 248999 INFO (coreZkRegister-534-thread-1-processing-n:127.0.0.1:58172_ag_xou x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58172_ag_xou 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> 249003 INFO (coreZkRegister-534-thread-1-processing-n:127.0.0.1:58172_ag_xou x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58172_ag_xou 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/95323510980608006-core_node1-n_0000000000 [junit4] 2> 249003 INFO (coreZkRegister-534-thread-1-processing-n:127.0.0.1:58172_ag_xou x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58172_ag_xou 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> 249005 INFO (coreZkRegister-534-thread-1-processing-n:127.0.0.1:58172_ag_xou x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 249005 INFO (coreZkRegister-534-thread-1-processing-n:127.0.0.1:58172_ag_xou x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58172_ag_xou 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> 249005 INFO (coreZkRegister-534-thread-1-processing-n:127.0.0.1:58172_ag_xou x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:58172/ag_xou/collection1/ [junit4] 2> 249005 INFO (coreZkRegister-534-thread-1-processing-n:127.0.0.1:58172_ag_xou x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 249005 INFO (coreZkRegister-534-thread-1-processing-n:127.0.0.1:58172_ag_xou x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:58172/ag_xou/collection1/ has no replicas [junit4] 2> 249005 INFO (OverseerStateUpdate-95323510980608006-127.0.0.1:58172_ag_xou-n_0000000000) [n:127.0.0.1:58172_ag_xou ] 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> 249006 INFO (coreZkRegister-534-thread-1-processing-n:127.0.0.1:58172_ag_xou x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 249008 INFO (coreZkRegister-534-thread-1-processing-n:127.0.0.1:58172_ag_xou x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node [junit4] 2> 249022 INFO (OverseerStateUpdate-95323510980608006-127.0.0.1:58172_ag_xou-n_0000000000) [n:127.0.0.1:58172_ag_xou ] 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:58172/ag_xou", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 249023 INFO (coreZkRegister-534-thread-1-processing-n:127.0.0.1:58172_ag_xou x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58172_ag_xou 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:58172/ag_xou/collection1/ shard1 [junit4] 2> 249125 INFO (zkCallback-259-thread-2-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou ] 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> 249125 INFO (zkCallback-259-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou ] 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> 249127 INFO (coreZkRegister-534-thread-1-processing-n:127.0.0.1:58172_ag_xou x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:58172/ag_xou/collection1/ and leader is http://127.0.0.1:58172/ag_xou/collection1/ [junit4] 2> 249127 INFO (coreZkRegister-534-thread-1-processing-n:127.0.0.1:58172_ag_xou x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58172_ag_xou 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:58172/ag_xou [junit4] 2> 249127 INFO (coreZkRegister-534-thread-1-processing-n:127.0.0.1:58172_ag_xou x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 249127 INFO (coreZkRegister-534-thread-1-processing-n:127.0.0.1:58172_ag_xou x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58172_ag_xou c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 249127 INFO (coreZkRegister-534-thread-1-processing-n:127.0.0.1:58172_ag_xou x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58172_ag_xou 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> 249130 INFO (OverseerStateUpdate-95323510980608006-127.0.0.1:58172_ag_xou-n_0000000000) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:58172/ag_xou", [junit4] 2> "node_name":"127.0.0.1:58172_ag_xou", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 249130 INFO (OverseerStateUpdate-95323510980608006-127.0.0.1:58172_ag_xou-n_0000000000) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:58172/ag_xou", [junit4] 2> "node_name":"127.0.0.1:58172_ag_xou", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 249199 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 249210 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 249232 INFO (zkCallback-259-thread-2-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou ] 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> 249233 INFO (zkCallback-259-thread-1-processing-n:127.0.0.1:58172_ag_xou) [n:127.0.0.1:58172_ag_xou ] 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> 249256 INFO (zkCallback-261-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1ec9f99 name:ZooKeeperConnection Watcher:127.0.0.1:40232/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 249256 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 249256 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 249256 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 249259 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 249259 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 249259 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 249260 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 249264 INFO (zkCallback-262-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@59c2cb24 name:ZooKeeperConnection Watcher:127.0.0.1:40232/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 249264 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 249264 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 249277 INFO (OverseerStateUpdate-95323510980608006-127.0.0.1:58172_ag_xou-n_0000000000) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "stateFormat":"2"} current state version: 3 [junit4] 2> 249277 INFO (OverseerStateUpdate-95323510980608006-127.0.0.1:58172_ag_xou-n_0000000000) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 249379 INFO (OverseerStateUpdate-95323510980608006-127.0.0.1:58172_ag_xou-n_0000000000) [n:127.0.0.1:58172_ag_xou ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json [junit4] 2> 249461 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_759A047477622D74-001/shard-1-001/cores/collection1 [junit4] 2> 249463 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_759A047477622D74-001/shard-1-001 [junit4] 2> 249463 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 249486 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@41e7cceb{/ag_xou,null,AVAILABLE} [junit4] 2> 249487 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.e.j.s.ServerConnector Started ServerConnector@59fb776d{HTTP/1.1}{127.0.0.1:49017} [junit4] 2> 249487 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.e.j.s.Server Started @253204ms [junit4] 2> 249487 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_759A047477622D74-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/ag_xou, hostPort=49017, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_759A047477622D74-001/shard-1-001/cores} [junit4] 2> 249487 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 249487 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_759A047477622D74-001/shard-1-001' [junit4] 2> 249488 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 249488 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 249488 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 249505 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 249514 INFO (zkCallback-263-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6bb71aa3 name:ZooKeeperConnection Watcher:127.0.0.1:40232/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 249515 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 249515 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 249517 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 249517 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_759A047477622D74-001/shard-1-001/solr.xml [junit4] 2> 249522 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_759A047477622D74-001/shard-1-001/cores [junit4] 2> 249522 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.CoreContainer New CoreContainer 1198852408 [junit4] 2> 249522 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[759A047477622D74]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_759A047477622D74-001/s [...truncated too long message...] 7477622D74]) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_759A047477622D74-001/solr-instance-027/./collection1/data [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_759A047477622D74-001/solr-instance-027/./collection1/data;done=false>>] [junit4] 2> 2531535 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[759A047477622D74]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_759A047477622D74-001/solr-instance-027/./collection1/data [junit4] 2> 2531535 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[759A047477622D74]) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_759A047477622D74-001/solr-instance-027/./collection1/data/index [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_759A047477622D74-001/solr-instance-027/./collection1/data/index;done=false>>] [junit4] 2> 2531535 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[759A047477622D74]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_759A047477622D74-001/solr-instance-027/./collection1/data/index [junit4] 2> 2531536 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[759A047477622D74]) [ x:collection1] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3e6f203a{/solr,null,UNAVAILABLE} [junit4] 2> 2531537 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[759A047477622D74]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@8606469{HTTP/1.1}{127.0.0.1:0} [junit4] 2> 2531537 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[759A047477622D74]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=302399055 [junit4] 2> 2531537 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[759A047477622D74]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5b9b02d1 [junit4] 2> 2531538 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[759A047477622D74]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=5,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=5,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0} [junit4] 2> 2531538 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[759A047477622D74]) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState [junit4] 2> 2531538 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[759A047477622D74]) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter [junit4] 2> 2531538 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[759A047477622D74]) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser [junit4] 2> 2531538 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[759A047477622D74]) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request. [junit4] 2> 2531539 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[759A047477622D74]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked [junit4] 2> 2531539 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[759A047477622D74]) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_759A047477622D74-001/solr-instance-028/./collection1/data/index [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_759A047477622D74-001/solr-instance-028/./collection1/data/index;done=false>>] [junit4] 2> 2531539 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[759A047477622D74]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_759A047477622D74-001/solr-instance-028/./collection1/data/index [junit4] 2> 2531539 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[759A047477622D74]) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_759A047477622D74-001/solr-instance-028/./collection1/data [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_759A047477622D74-001/solr-instance-028/./collection1/data;done=false>>] [junit4] 2> 2531539 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[759A047477622D74]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_759A047477622D74-001/solr-instance-028/./collection1/data [junit4] 2> 2531540 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[759A047477622D74]) [ x:collection1] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7b857cb2{/solr,null,UNAVAILABLE} [junit4] 2> 2531545 INFO (SUITE-TestReplicationHandler-seed#[759A047477622D74]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_759A047477622D74-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene60): {name=PostingsFormat(name=LuceneVarGapFixedInterval), id=PostingsFormat(name=LuceneVarGapFixedInterval), newname=PostingsFormat(name=LuceneVarGapFixedInterval)}, docValues:{}, sim=RandomSimilarity(queryNorm=false,coord=crazy): {}, locale=lt-LT, timezone=Asia/Muscat [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_66 (64-bit)/cpus=4,threads=1,free=170876232,total=524812288 [junit4] 2> NOTE: All tests run in this JVM: [SSLMigrationTest, TestExpandComponent, ExternalFileFieldSortTest, TestNRTOpen, ParsingFieldUpdateProcessorsTest, SuggestComponentContextFilterQueryTest, TestRestManager, DistributedDebugComponentTest, ConfigSetsAPITest, CloudExitableDirectoryReaderTest, PreAnalyzedFieldTest, SortSpecParsingTest, TestSolr4Spatial, TestClassicSimilarityFactory, DistribJoinFromCollectionTest, TestMiniSolrCloudClusterSSL, TestConfigSetProperties, SystemInfoHandlerTest, TestCloudManagedSchemaConcurrent, TestInitQParser, CloneFieldUpdateProcessorFactoryTest, SolrRequestParserTest, TestCrossCoreJoin, TestAtomicUpdateErrorCases, TestCryptoKeys, CdcrReplicationDistributedZkTest, RequestLoggingTest, XsltUpdateRequestHandlerTest, TestSchemaNameResource, DateRangeFieldTest, CoreAdminCreateDiscoverTest, TestFieldSortValues, TestPseudoReturnFields, RuleEngineTest, TestReplicaProperties, AlternateDirectoryTest, TestPerFieldSimilarityClassic, TestExactStatsCache, TestJoin, TestSimpleQParserPlugin, HdfsRestartWhileUpdatingTest, LeaderInitiatedRecoveryOnCommitTest, TestRTimerTree, TestStandardQParsers, TestReplicationHandler] [junit4] Completed [228/577 (2!)] on J0 in 794.90s, 13 tests, 1 failure <<< FAILURES! [...truncated 1108 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:747: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:684: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:524: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1457: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1014: There were test failures: 577 suites (8 ignored), 2321 tests, 1 error, 1 failure, 33 ignored (18 assumptions) [seed: 759A047477622D74] Total time: 191 minutes 43 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
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org