Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/845/
5 tests failed. FAILED: org.apache.solr.cloud.BasicDistributedZkTest.test Error Message: commitWithin did not work on node: http://127.0.0.1:60465/_p/m/collection1 expected:<68> but was:<67> Stack Trace: java.lang.AssertionError: commitWithin did not work on node: http://127.0.0.1:60465/_p/m/collection1 expected:<68> but was:<67> at __randomizedtesting.SeedInfo.seed([5DDFB81096E730DB:D58B87CA381B5D23]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.failNotEquals(Assert.java:647) at org.junit.Assert.assertEquals(Assert.java:128) at org.junit.Assert.assertEquals(Assert.java:472) at org.apache.solr.cloud.BasicDistributedZkTest.test(BasicDistributedZkTest.java:333) 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:1660) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:866) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:902) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:916) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:963) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java: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:875) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:777) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:811) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:822) 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.handler.TestReplicationHandler.doTestStressReplication Error Message: timed out waiting for collection1 startAt time to exceed: Sat Nov 07 15:00:19 IRST 2015 Stack Trace: java.lang.AssertionError: timed out waiting for collection1 startAt time to exceed: Sat Nov 07 15:00:19 IRST 2015 at __randomizedtesting.SeedInfo.seed([5DDFB81096E730DB:8674B8D693CF5968]:0) at org.junit.Assert.fail(Assert.java:93) at org.apache.solr.handler.TestReplicationHandler.watchCoreStartAt(TestReplicationHandler.java:1417) at org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:769) 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:1660) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:866) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:902) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:916) 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:875) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:777) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:811) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:822) 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.LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR Error Message: Captured an uncaught exception in thread: Thread[id=68659, name=coreZkRegister-5381-thread-2, state=RUNNABLE, group=TGRP-LeaderInitiatedRecoveryOnShardRestartTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=68659, name=coreZkRegister-5381-thread-2, state=RUNNABLE, group=TGRP-LeaderInitiatedRecoveryOnShardRestartTest] at __randomizedtesting.SeedInfo.seed([5DDFB81096E730DB:8355D66DAD455228]:0) Caused by: java.lang.AssertionError at __randomizedtesting.SeedInfo.seed([5DDFB81096E730DB]:0) at org.apache.solr.cloud.ZkController.updateLeaderInitiatedRecoveryState(ZkController.java:2126) at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:451) at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:197) at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:157) at org.apache.solr.cloud.LeaderElector.joinElection(LeaderElector.java:346) at org.apache.solr.cloud.ZkController.joinElection(ZkController.java:1113) at org.apache.solr.cloud.ZkController.register(ZkController.java:926) at org.apache.solr.cloud.ZkController.register(ZkController.java:881) at org.apache.solr.core.ZkContainer$2.run(ZkContainer.java:183) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:231) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) FAILED: org.apache.solr.cloud.OverseerTest.testOverseerStatsReset Error Message: expected:<1> but was:<2> Stack Trace: java.lang.AssertionError: expected:<1> but was:<2> at __randomizedtesting.SeedInfo.seed([5DDFB81096E730DB:F68B5F2C033D93D5]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.failNotEquals(Assert.java:647) at org.junit.Assert.assertEquals(Assert.java:128) at org.junit.Assert.assertEquals(Assert.java:472) at org.junit.Assert.assertEquals(Assert.java:456) at org.apache.solr.cloud.OverseerTest.testOverseerStatsReset(OverseerTest.java:722) 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:1660) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:866) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:902) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:916) 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:875) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:777) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:811) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:822) 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=54627, name=collection5, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=54627, name=collection5, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:56622: collection already exists: awholynewstresscollection_collection5_0 at __randomizedtesting.SeedInfo.seed([5DDFB81096E730DB]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:575) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1574) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1595) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:888) Build Log: [...truncated 9820 lines...] [junit4] Suite: org.apache.solr.cloud.BasicDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/init-core-data-001 [junit4] 2> 26952 INFO (SUITE-BasicDistributedZkTest-seed#[5DDFB81096E730DB]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_p/m [junit4] 2> 26999 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 27003 INFO (Thread-29) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 27004 INFO (Thread-29) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 27103 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.ZkTestServer start zk server on port:54055 [junit4] 2> 27149 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 27294 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 27319 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Exception causing close of session 0x0 due to java.io.IOException: ZooKeeperServer not running [junit4] 2> 29002 INFO (zkCallback-6-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1200813a name:ZooKeeperConnection Watcher:127.0.0.1:54055 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 29002 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 29003 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 29007 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 29049 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x150e18ddb360000, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> 29050 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 29055 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 29067 INFO (zkCallback-7-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2209b038 name:ZooKeeperConnection Watcher:127.0.0.1:54055/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 29067 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 29067 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 29068 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 29078 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 29083 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 29087 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 29091 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] 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> 29092 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 29099 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] 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> 29099 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 29105 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] 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> 29106 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 29114 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] 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> 29114 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 29118 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] 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> 29118 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 29121 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] 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> 29121 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 29124 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] 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> 29124 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 29127 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] 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> 29128 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 29130 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] 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> 29131 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 29134 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] 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> 29134 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 29137 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] 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> 29137 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 29469 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/control-001/cores/collection1 [junit4] 2> 29479 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 29528 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6fb5a11f{/_p/m,null,AVAILABLE} [junit4] 2> 29529 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.e.j.s.ServerConnector Started ServerConnector@589a1a4e{HTTP/1.1}{127.0.0.1:46199} [junit4] 2> 29529 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.e.j.s.Server Started @32429ms [junit4] 2> 29542 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] 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/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/tempDir-001/control/data, hostContext=/_p/m, hostPort=46199, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/control-001/cores} [junit4] 2> 29543 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 29546 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/control-001/' [junit4] 2> 29546 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 29547 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 29568 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 29584 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 29591 INFO (zkCallback-8-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@cfd51e6 name:ZooKeeperConnection Watcher:127.0.0.1:54055/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 29591 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 29591 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 29598 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 29598 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/control-001/solr.xml [junit4] 2> 29605 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/control-001/cores [junit4] 2> 29605 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.CoreContainer New CoreContainer 1661136603 [junit4] 2> 29605 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/control-001/] [junit4] 2> 29605 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/control-001/lib [junit4] 2> 29605 WARN (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/control-001/lib). [junit4] 2> 29647 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] 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> 29653 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 29654 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 29654 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 29654 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54055/solr [junit4] 2> 29675 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 29675 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 29686 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 29702 INFO (zkCallback-10-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@41049763 name:ZooKeeperConnection Watcher:127.0.0.1:54055 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 29703 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 29703 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 29716 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 29731 INFO (zkCallback-11-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@cd25d8d name:ZooKeeperConnection Watcher:127.0.0.1:54055/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 29731 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 29743 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 29757 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 29765 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 29771 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 29774 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 29788 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 29793 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 29797 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 29799 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 29801 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46199__p%2Fm [junit4] 2> 29804 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:46199__p%2Fm [junit4] 2> 29814 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 29817 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 29822 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 29823 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94823591559168004-127.0.0.1:46199__p%2Fm-n_0000000000 [junit4] 2> 29830 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:46199__p%2Fm [junit4] 2> 29830 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 29833 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.Overseer Overseer (id=94823591559168004-127.0.0.1:46199__p%2Fm-n_0000000000) starting [junit4] 2> 29841 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 30026 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 30039 INFO (OverseerCollectionConfigSetProcessor-94823591559168004-127.0.0.1:46199__p%2Fm-n_0000000000) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 30039 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 30036 INFO (OverseerStateUpdate-94823591559168004-127.0.0.1:46199__p%2Fm-n_0000000000) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 30100 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 30100 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 30102 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/control-001/cores [junit4] 2> 30105 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/control-001/cores/collection1, collection=control_collection, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 30105 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/control-001/cores/collection1/ [junit4] 2> 30105 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 30155 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0 [junit4] 2> 30155 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:46199__p%2Fm ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 30152 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 30155 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 30168 INFO (OverseerStateUpdate-94823591559168004-127.0.0.1:46199__p%2Fm-n_0000000000) [n:127.0.0.1:46199__p%2Fm ] 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:46199/_p/m";, [junit4] 2> "node_name":"127.0.0.1:46199__p%2Fm", [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> 30171 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 30192 INFO (OverseerStateUpdate-94823591559168004-127.0.0.1:46199__p%2Fm-n_0000000000) [n:127.0.0.1:46199__p%2Fm ] 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:46199/_p/m";, [junit4] 2> "node_name":"127.0.0.1:46199__p%2Fm", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 30195 INFO (OverseerStateUpdate-94823591559168004-127.0.0.1:46199__p%2Fm-n_0000000000) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 30216 INFO (OverseerStateUpdate-94823591559168004-127.0.0.1:46199__p%2Fm-n_0000000000) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 30224 INFO (zkCallback-11-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm ] 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> 31172 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 31172 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 31173 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 31173 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 31174 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 31175 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm 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/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/control-001/cores/collection1/' [junit4] 2> 31175 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 31175 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 31215 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 31243 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 31248 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 31269 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 31281 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 31307 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 31610 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 31640 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 31641 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 31676 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 31681 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 31683 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 31685 WARN (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 31685 WARN (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 31685 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 31686 WARN (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 31686 WARN (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 31693 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm 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/J0 [junit4] 2> 31693 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 31693 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 31694 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm 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/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 31694 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm 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@60910b9b [junit4] 2> 31702 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm 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/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/control-001/cores/collection1/data [junit4] 2> 31702 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm 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/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/control-001/cores/collection1/data/index/ [junit4] 2> 31702 WARN (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm 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/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 31702 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm 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/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/control-001/cores/collection1/data/index [junit4] 2> 31703 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=22, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=34.4375, floorSegmentMB=0.6533203125, forceMergeDeletesPctAllowed=2.3108198870638397, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 31712 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@58c298f1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2498ce63; maxCacheMB=0.36328125 maxMergeSizeMB=0.0283203125)),segFN=segments_1,generation=1} [junit4] 2> 31712 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 31721 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:46199__p%2Fm 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/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/control-001/cores/collection1/data/ [junit4] 2> 31728 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 31736 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:46199__p%2Fm 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/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 31740 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 31740 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 31740 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 31740 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 31740 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm 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> 31741 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm 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> 31741 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm 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> 31741 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm 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> 31742 WARN (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm 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> 31744 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 31750 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 31752 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 31755 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 31786 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/dump,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 31787 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm 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> 31788 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm 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> 31788 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm 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> 31789 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 31789 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 31789 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=34, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7945918042205389] [junit4] 2> 31791 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@58c298f1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2498ce63; maxCacheMB=0.36328125 maxMergeSizeMB=0.0283203125)),segFN=segments_1,generation=1} [junit4] 2> 31791 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 31791 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@10d5b332[collection1] main [junit4] 2> 31791 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm 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> 31793 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm 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> 31793 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm 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> 31796 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 31796 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 31796 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm 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> 31797 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm 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> 31798 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm 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> 31798 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 31798 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 31799 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm 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> 31799 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm 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> 31799 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm 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> 31799 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm 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 1517177469859266560 [junit4] 2> 31801 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1517177469859266560 [junit4] 2> 31802 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 31807 INFO (searcherExecutor-70-thread-1-processing-n:127.0.0.1:46199__p%2Fm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@10d5b332[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 31809 INFO (coreLoadExecutor-69-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 31849 INFO (coreZkRegister-63-thread-1-processing-n:127.0.0.1:46199__p%2Fm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46199__p%2Fm 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:46199/_p/m collection:control_collection shard:shard1 [junit4] 2> 31858 INFO (coreZkRegister-63-thread-1-processing-n:127.0.0.1:46199__p%2Fm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46199__p%2Fm 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> 31864 INFO (coreZkRegister-63-thread-1-processing-n:127.0.0.1:46199__p%2Fm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46199__p%2Fm 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/94823591559168004-core_node1-n_0000000000 [junit4] 2> 31865 INFO (coreZkRegister-63-thread-1-processing-n:127.0.0.1:46199__p%2Fm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 31870 INFO (coreZkRegister-63-thread-1-processing-n:127.0.0.1:46199__p%2Fm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 31870 INFO (coreZkRegister-63-thread-1-processing-n:127.0.0.1:46199__p%2Fm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46199__p%2Fm 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> 31871 INFO (OverseerStateUpdate-94823591559168004-127.0.0.1:46199__p%2Fm-n_0000000000) [n:127.0.0.1:46199__p%2Fm ] 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> 31871 INFO (coreZkRegister-63-thread-1-processing-n:127.0.0.1:46199__p%2Fm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:46199/_p/m/collection1/ [junit4] 2> 31871 INFO (coreZkRegister-63-thread-1-processing-n:127.0.0.1:46199__p%2Fm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 31871 INFO (coreZkRegister-63-thread-1-processing-n:127.0.0.1:46199__p%2Fm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:46199/_p/m/collection1/ has no replicas [junit4] 2> 31871 INFO (coreZkRegister-63-thread-1-processing-n:127.0.0.1:46199__p%2Fm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46199__p%2Fm 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:46199/_p/m/collection1/ shard1 [junit4] 2> 31877 INFO (coreZkRegister-63-thread-1-processing-n:127.0.0.1:46199__p%2Fm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 31877 INFO (zkCallback-11-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm ] 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> 31895 INFO (coreZkRegister-63-thread-1-processing-n:127.0.0.1:46199__p%2Fm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node [junit4] 2> 31915 INFO (OverseerStateUpdate-94823591559168004-127.0.0.1:46199__p%2Fm-n_0000000000) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"state", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:46199/_p/m";, [junit4] 2> "node_name":"127.0.0.1:46199__p%2Fm", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "core":"collection1"} current state version: 2 [junit4] 2> 31916 INFO (OverseerStateUpdate-94823591559168004-127.0.0.1:46199__p%2Fm-n_0000000000) [n:127.0.0.1:46199__p%2Fm ] o.a.s.c.o.ReplicaMutator Update state numShards=null message={ [junit4] 2> "operation":"state", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:46199/_p/m";, [junit4] 2> "node_name":"127.0.0.1:46199__p%2Fm", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "core":"collection1"} [junit4] 2> 31920 INFO (OverseerStateUpdate-94823591559168004-127.0.0.1:46199__p%2Fm-n_0000000000) [n:127.0.0.1:46199__p%2Fm ] 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:46199/_p/m";, [junit4] 2> "core":"collection1"} current state version: 2 [junit4] 2> 32025 INFO (zkCallback-11-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm ] 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> 32066 INFO (coreZkRegister-63-thread-1-processing-n:127.0.0.1:46199__p%2Fm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:46199/_p/m/collection1/ and leader is http://127.0.0.1:46199/_p/m/collection1/ [junit4] 2> 32067 INFO (coreZkRegister-63-thread-1-processing-n:127.0.0.1:46199__p%2Fm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46199__p%2Fm 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:46199/_p/m [junit4] 2> 32290 INFO (coreZkRegister-63-thread-1-processing-n:127.0.0.1:46199__p%2Fm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 32291 INFO (coreZkRegister-63-thread-1-processing-n:127.0.0.1:46199__p%2Fm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46199__p%2Fm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 32291 INFO (coreZkRegister-63-thread-1-processing-n:127.0.0.1:46199__p%2Fm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46199__p%2Fm 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> 32295 INFO (OverseerStateUpdate-94823591559168004-127.0.0.1:46199__p%2Fm-n_0000000000) [n:127.0.0.1:46199__p%2Fm ] 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:46199/_p/m";, [junit4] 2> "node_name":"127.0.0.1:46199__p%2Fm", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 32296 INFO (OverseerStateUpdate-94823591559168004-127.0.0.1:46199__p%2Fm-n_0000000000) [n:127.0.0.1:46199__p%2Fm ] 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:46199/_p/m";, [junit4] 2> "node_name":"127.0.0.1:46199__p%2Fm", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 32323 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 32339 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 32359 INFO (zkCallback-13-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@33244486 name:ZooKeeperConnection Watcher:127.0.0.1:54055/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 32359 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 32359 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 32359 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 32375 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 32401 INFO (zkCallback-13-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 32401 INFO (zkCallback-11-thread-1-processing-n:127.0.0.1:46199__p%2Fm) [n:127.0.0.1:46199__p%2Fm ] 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> 33014 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/shard-1-001/cores/collection1 [junit4] 2> 33015 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/shard-1-001 [junit4] 2> 33018 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 33032 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@37ad95bf{/_p/m,null,AVAILABLE} [junit4] 2> 33033 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1c7a657b{HTTP/1.1}{127.0.0.1:60465} [junit4] 2> 33033 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.e.j.s.Server Started @35933ms [junit4] 2> 33033 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] 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/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/_p/m, hostPort=60465, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/shard-1-001/cores} [junit4] 2> 33034 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 33034 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/shard-1-001/' [junit4] 2> 33034 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 33034 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 33063 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 33071 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 33090 INFO (zkCallback-14-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6bb71235 name:ZooKeeperConnection Watcher:127.0.0.1:54055/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 33155 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 33156 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 33170 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 33170 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/shard-1-001/solr.xml [junit4] 2> 33178 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/shard-1-001/cores [junit4] 2> 33178 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.CoreContainer New CoreContainer 1790460532 [junit4] 2> 33178 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/shard-1-001/] [junit4] 2> 33178 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/shard-1-001/lib [junit4] 2> 33178 WARN (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5DDFB81096E730DB-001/shard-1-001/lib). [junit4] 2> 33186 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] 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> 33193 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 33193 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 33193 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 33193 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54055/solr [junit4] 2> 33194 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 33194 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 33196 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 33215 INFO (zkCallback-16-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6f0920c0 name:ZooKeeperConnection Watcher:127.0.0.1:54055 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 33218 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 33219 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 33230 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:60465__p%2Fm ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 33247 INFO (zkCallback-17-thread-1-processing-n:127.0.0.1:60465__p%2Fm) [n:127.0.0.1:60465__p%2Fm ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@24a09042 name:ZooKeeperConnection Watcher:127.0.0.1:54055/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 33247 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:60465__p%2Fm ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 33256 INFO (TEST-BasicDistributedZkTest.test-seed#[5DDFB81096E730DB]) [n:127.0.0.1:60465__p%2Fm ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 34264 INF [...truncated too long message...] cancelElection(ElectionContext.java:169) [junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.cancelElection(ElectionContext.java:285) [junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:467) [junit4] 2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:197) [junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:157) [junit4] 2> at org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:58) [junit4] 2> at org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:388) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3$1.run(SolrZkClient.java:269) [junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:231) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> 2918278 INFO (TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[5DDFB81096E730DB]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@1aa83ee4{HTTP/1.1}{127.0.0.1:52952} [junit4] 2> 2918279 INFO (TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[5DDFB81096E730DB]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1703e786{/,null,UNAVAILABLE} [junit4] 2> 2918280 INFO (TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[5DDFB81096E730DB]) [n:127.0.0.1:52952_ c:all_in_lir s:shard1 r:core_node2 x:all_in_lir_shard1_replica1] o.a.s.c.ChaosMonkey monkey: stop shard! 58439 [junit4] 2> 2918282 INFO (TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[5DDFB81096E730DB]) [n:127.0.0.1:52952_ c:all_in_lir s:shard1 r:core_node2 x:all_in_lir_shard1_replica1] o.a.s.c.ZkTestServer connecting to 127.0.0.1:45514 45514 [junit4] 2> 2918453 INFO (Thread-60306) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:45514 45514 [junit4] 2> 2918455 WARN (Thread-60306) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 6 /solr/aliases.json [junit4] 2> 5 /solr/security.json [junit4] 2> 5 /solr/configs/conf1 [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 6 /solr/clusterstate.json [junit4] 2> 3 /solr/overseer_elect/election/94823778397913092-127.0.0.1:58439_-n_0000000000 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 6 /solr/collections [junit4] 2> 4 /solr/live_nodes [junit4] 2> 2 /solr/overseer/queue [junit4] 2> 2 /solr/overseer/collection-queue-work [junit4] 2> [junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory. [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=LeaderInitiatedRecoveryOnShardRestartTest -Dtests.method=testRestartWithAllInLIR -Dtests.seed=5DDFB81096E730DB -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt -Dtests.locale=sv_SE -Dtests.timezone=America/Edmonton -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 40.4s J0 | LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR <<< [junit4] > Throwable #1: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=68659, name=coreZkRegister-5381-thread-2, state=RUNNABLE, group=TGRP-LeaderInitiatedRecoveryOnShardRestartTest] [junit4] > at __randomizedtesting.SeedInfo.seed([5DDFB81096E730DB:8355D66DAD455228]:0) [junit4] > Caused by: java.lang.AssertionError [junit4] > at __randomizedtesting.SeedInfo.seed([5DDFB81096E730DB]:0) [junit4] > at org.apache.solr.cloud.ZkController.updateLeaderInitiatedRecoveryState(ZkController.java:2126) [junit4] > at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:451) [junit4] > at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:197) [junit4] > at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:157) [junit4] > at org.apache.solr.cloud.LeaderElector.joinElection(LeaderElector.java:346) [junit4] > at org.apache.solr.cloud.ZkController.joinElection(ZkController.java:1113) [junit4] > at org.apache.solr.cloud.ZkController.register(ZkController.java:926) [junit4] > at org.apache.solr.cloud.ZkController.register(ZkController.java:881) [junit4] > at org.apache.solr.core.ZkContainer$2.run(ZkContainer.java:183) [junit4] > at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:231) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 2918458 INFO (SUITE-LeaderInitiatedRecoveryOnShardRestartTest-seed#[5DDFB81096E730DB]-worker) [n:127.0.0.1:52952_ c:all_in_lir s:shard1 r:core_node2 x:all_in_lir_shard1_replica1] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: test params are: codec=Lucene60, sim=ClassicSimilarity, locale=sv_SE, timezone=America/Edmonton [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_66 (64-bit)/cpus=4,threads=1,free=116374744,total=526909440 [junit4] 2> NOTE: All tests run in this JVM: [DocExpirationUpdateProcessorFactoryTest, TestImplicitCoreProperties, TestDynamicFieldResource, TestPKIAuthenticationPlugin, EnumFieldTest, AnalysisAfterCoreReloadTest, PrimUtilsTest, TestCloudInspectUtil, BasicDistributedZkTest, TestBlobHandler, ExitableDirectoryReaderTest, QueryElevationComponentTest, DefaultValueUpdateProcessorTest, TestCloudSchemaless, HdfsRecoveryZkTest, TestAnalyzedSuggestions, TestRequestStatusCollectionAPI, RollingRestartTest, TestCryptoKeys, PreAnalyzedUpdateProcessorTest, TestInfoStreamLogging, HdfsChaosMonkeySafeLeaderTest, CurrencyFieldOpenExchangeTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, DistributedSuggestComponentTest, IndexSchemaRuntimeFieldTest, TestSolrCloudWithKerberosAlt, TestSimpleTrackingShardHandler, TestRTGBase, BJQParserTest, FileBasedSpellCheckerTest, CollectionStateFormat2Test, TestRealTimeGet, DocumentAnalysisRequestHandlerTest, TestFastOutputStream, TestReplicaProperties, TestDistributedSearch, TestFieldTypeResource, SuggestComponentContextFilterQueryTest, TestReqParamsAPI, AnalysisErrorHandlingTest, TestManagedSchemaDynamicFieldResource, BufferStoreTest, CollectionReloadTest, TestRandomFaceting, StressHdfsTest, TestRawResponseWriter, TestClassNameShortening, TestCloudManagedSchema, TestBadConfig, ReturnFieldsTest, TestCSVLoader, TestCustomSort, TestTolerantSearch, ZkNodePropsTest, CoreAdminRequestStatusTest, AlternateDirectoryTest, TestLazyCores, TestCollationFieldDocValues, RuleEngineTest, TestDefaultSearchFieldResource, TestAtomicUpdateErrorCases, PeerSyncTest, PingRequestHandlerTest, TestValueSourceCache, TestTrackingShardHandlerFactory, RAMDirectoryFactoryTest, DistributedQueueTest, TestRemoteStreaming, TestDynamicLoading, LeaderElectionIntegrationTest, ParsingFieldUpdateProcessorsTest, TestMergePolicyConfig, TestLuceneMatchVersion, TestPhraseSuggestions, ExplicitHLLTest, TestCollectionAPI, TestCustomDocTransformer, PreAnalyzedFieldTest, DateRangeFieldTest, TestSolr4Spatial, TestJettySolrRunner, SaslZkACLProviderTest, SecurityConfHandlerTest, TestScoreJoinQPScore, PathHierarchyTokenizerFactoryTest, TestShortCircuitedRequests, AddSchemaFieldsUpdateProcessorFactoryTest, TestMaxScoreQueryParser, TestLRUCache, StandardRequestHandlerTest, DeleteInactiveReplicaTest, SyncSliceTest, BasicZkTest, TestReplicationHandler, ForceLeaderTest, LeaderInitiatedRecoveryOnShardRestartTest] [junit4] Completed [425/555] on J0 in 40.83s, 1 test, 1 error <<< FAILURES! [...truncated 434 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:782: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:719: 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:516: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1432: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:987: There were test failures: 555 suites (8 ignored), 2200 tests, 2 errors, 3 failures, 32 ignored (18 assumptions) [seed: 5DDFB81096E730DB] Total time: 197 minutes 12 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
