Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.x/52/
3 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=1094, name=collection4, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=1094, name=collection4, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] at __randomizedtesting.SeedInfo.seed([D87C2A1E874D0DFE:502815C429B16006]:0) Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:55263: collection already exists: awholynewstresscollection_collection4_5 at __randomizedtesting.SeedInfo.seed([D87C2A1E874D0DFE]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:590) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:259) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:404) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:357) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1192) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:962) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:898) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1595) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1616) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:987) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.TestTolerantUpdateProcessorRandomCloud Error Message: Could not find collection:test_col Stack Trace: java.lang.AssertionError: Could not find collection:test_col at __randomizedtesting.SeedInfo.seed([D87C2A1E874D0DFE]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertNotNull(Assert.java:526) at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:150) at org.apache.solr.cloud.TestTolerantUpdateProcessorRandomCloud.createMiniSolrCloudCluster(TestTolerantUpdateProcessorRandomCloud.java:135) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:811) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at java.lang.Thread.run(Thread.java:745) FAILED: org.apache.solr.handler.TestReplicationHandler.doTestStressReplication Error Message: timed out waiting for collection1 startAt time to exceed: Sat Apr 30 07:51:23 ICT 2016 Stack Trace: java.lang.AssertionError: timed out waiting for collection1 startAt time to exceed: Sat Apr 30 07:51:23 ICT 2016 at __randomizedtesting.SeedInfo.seed([D87C2A1E874D0DFE:3D72AD88265644D]:0) at org.junit.Assert.fail(Assert.java:93) at org.apache.solr.handler.TestReplicationHandler.watchCoreStartAt(TestReplicationHandler.java:1428) at org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:780) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 11310 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/init-core-data-001 [junit4] 2> 65590 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[D87C2A1E874D0DFE]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 65590 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[D87C2A1E874D0DFE]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 65594 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 65595 INFO (Thread-344) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 65595 INFO (Thread-344) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 65695 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.ZkTestServer start zk server on port:39223 [junit4] 2> 65695 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 65696 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 65714 INFO (zkCallback-60-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1c45fb97 name:ZooKeeperConnection Watcher:127.0.0.1:39223 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 65714 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 65714 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 65715 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 65718 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 65730 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 65735 INFO (zkCallback-61-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@327c3eb4 name:ZooKeeperConnection Watcher:127.0.0.1:39223/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 65735 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 65735 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 65735 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 65737 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 65739 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 65740 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 65741 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 65741 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 65745 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 65745 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 65750 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 65751 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 65753 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 65753 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 65755 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 65755 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 65756 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 65756 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 65757 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 65758 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 65759 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 65759 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 65761 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 65761 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 65765 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 65765 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 65767 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 65767 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 65878 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/cores/collection1 [junit4] 2> 65880 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 65882 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@23bfc24b{/,null,AVAILABLE} [junit4] 2> 65885 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.e.j.s.ServerConnector Started ServerConnector@7ec9621e{HTTP/1.1,[http/1.1]}{127.0.0.1:53731} [junit4] 2> 65885 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.e.j.s.Server Started @68824ms [junit4] 2> 65885 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/tempDir-001/control/data, hostContext=/, hostPort=53731, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/cores} [junit4] 2> 65885 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93 [junit4] 2> 65885 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001' [junit4] 2> 65885 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 65885 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 65886 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 65886 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 65889 INFO (zkCallback-62-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@222ef9fb name:ZooKeeperConnection Watcher:127.0.0.1:39223/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 65889 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 65890 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 65892 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 65892 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/solr.xml [junit4] 2> 65899 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/cores [junit4] 2> 65899 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.CoreContainer New CoreContainer 3858867 [junit4] 2> 65899 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001] [junit4] 2> 65900 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/lib [junit4] 2> 65900 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] 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> 65901 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 65902 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 65902 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 65902 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39223/solr [junit4] 2> 65902 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 65902 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 65903 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 65906 INFO (zkCallback-65-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3ddf3a9b name:ZooKeeperConnection Watcher:127.0.0.1:39223 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 65906 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 65907 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 65909 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$38/887802213@2304a10c [junit4] 2> 65910 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 65912 INFO (zkCallback-66-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4c9e88c8 name:ZooKeeperConnection Watcher:127.0.0.1:39223/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 65912 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 65915 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 65917 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 65919 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 65921 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 65922 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 65924 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 65927 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 65928 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 65929 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 65930 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 65931 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 65932 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 65933 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 65934 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 65935 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95812075095588868-127.0.0.1:53731_-n_0000000000 [junit4] 2> 65935 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:53731_ [junit4] 2> 65935 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 65936 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.Overseer Overseer (id=95812075095588868-127.0.0.1:53731_-n_0000000000) starting [junit4] 2> 65938 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 65943 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 65944 INFO (OverseerCollectionConfigSetProcessor-95812075095588868-127.0.0.1:53731_-n_0000000000) [n:127.0.0.1:53731_ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 65944 INFO (OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000) [n:127.0.0.1:53731_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 65944 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53731_ [junit4] 2> 65945 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:53731_ [junit4] 2> 65946 INFO (zkCallback-66-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0]) [junit4] 2> 65946 INFO (OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000) [n:127.0.0.1:53731_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 65949 INFO (zkCallback-66-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (1) [junit4] 2> 65971 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 65971 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 65972 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/cores [junit4] 2> 65973 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=control_collection, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 65973 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/cores/collection1 [junit4] 2> 65973 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 65974 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1 [junit4] 2> 65974 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:53731_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 65974 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 65974 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 65976 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.ZkController Collection control_collection not visible yet, but flagging it so a watch is registered when it becomes visible [junit4] 2> 65976 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader addZkWatch [control_collection] [junit4] 2> 65976 INFO (OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000) [n:127.0.0.1:53731_ ] 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:53731", [junit4] 2> "node_name":"127.0.0.1:53731_", [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> 65977 INFO (OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000) [n:127.0.0.1:53731_ ] 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:53731", [junit4] 2> "node_name":"127.0.0.1:53731_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 65977 INFO (OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000) [n:127.0.0.1:53731_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 65977 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Deleting data for [control_collection] [junit4] 2> 65978 INFO (OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000) [n:127.0.0.1:53731_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 65979 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 65980 INFO (zkCallback-66-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ ] 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> 66979 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 66980 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 66980 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 66980 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection] [junit4] 2> 66981 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 66981 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/cores/collection1' [junit4] 2> 66981 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 66981 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 66983 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 66996 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 67002 WARN (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 67003 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.1.0 [junit4] 2> 67012 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 67018 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 67111 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 67117 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 67118 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 67132 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 67134 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 67136 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 67137 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 67142 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1 [junit4] 2> 67142 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 67143 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 67143 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/cores/collection1/data/] [junit4] 2> 67143 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ 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@3a7ad154 [junit4] 2> 67144 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/cores/collection1/data [junit4] 2> 67145 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/cores/collection1/data/index/ [junit4] 2> 67145 WARN (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 67145 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/cores/collection1/data/index [junit4] 2> 67145 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ 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=9, maxMergeAtOnceExplicit=6, maxMergedSegmentMB=77.5712890625, floorSegmentMB=1.3564453125, forceMergeDeletesPctAllowed=8.889765143520206, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7228485563731526 [junit4] 2> 67146 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7ecab59b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4c7ce6fe),segFN=segments_1,generation=1} [junit4] 2> 67146 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 67148 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/cores/collection1/data/ [junit4] 2> 67149 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 67152 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 67152 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 67152 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 67152 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 67153 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 67153 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ 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> 67153 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ 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> 67153 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ 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> 67153 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ 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> 67153 WARN (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ 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> 67155 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 67156 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 67157 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 67158 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 67162 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/dump,/update/csv,/sql,/update/json/docs,/admin/luke,/export,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins,/admin/logging,/update/json,/admin/ping,/admin/threads,/update,/admin/file [junit4] 2> 67162 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ 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> 67162 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ 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> 67162 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ 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> 67163 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 67163 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 67165 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ 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=45, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8426334006169524] [junit4] 2> 67165 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7ecab59b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4c7ce6fe),segFN=segments_1,generation=1} [junit4] 2> 67165 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 67165 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@30712c90[collection1] main] [junit4] 2> 67165 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ 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> 67168 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ 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> 67168 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ 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> 67168 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 67169 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 67169 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ 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> 67169 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ 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> 67169 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ 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> 67169 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 67169 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 67170 INFO (searcherExecutor-293-thread-1-processing-n:127.0.0.1:53731_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@30712c90[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 67171 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ 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> 67171 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ 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> 67171 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ 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> 67171 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ 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 1532993203175686144 [junit4] 2> 67172 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ 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 1532993203175686144 [junit4] 2> 67172 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 67173 INFO (coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 67173 INFO (coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_ 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:53731 collection:control_collection shard:shard1 [junit4] 2> 67174 INFO (coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_ 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> 67178 INFO (coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_ 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/95812075095588868-core_node1-n_0000000000 [junit4] 2> 67179 INFO (coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard1 and weAreReplacement=false and leaderVoteWait=180000 [junit4] 2> 67181 INFO (coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 67181 INFO (coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_ 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> 67181 INFO (coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:53731/collection1/ [junit4] 2> 67181 INFO (coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 67181 INFO (coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:53731/collection1/ has no replicas [junit4] 2> 67182 INFO (coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 67184 INFO (coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/95812075095588868-core_node1-n_0000000000 [junit4] 2> 67184 INFO (OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000) [n:127.0.0.1:53731_ ] 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> 67188 INFO (coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_ 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:53731/collection1/ shard1 [junit4] 2> 67188 INFO (OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000) [n:127.0.0.1:53731_ ] 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:53731", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 67291 INFO (zkCallback-66-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ ] 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> 67339 INFO (coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:53731/collection1/ and leader is http://127.0.0.1:53731/collection1/ [junit4] 2> 67339 INFO (coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_ 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:53731 [junit4] 2> 67339 INFO (coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 67340 INFO (coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 67340 INFO (coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_ 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> 67342 INFO (OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000) [n:127.0.0.1:53731_ ] 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:53731", [junit4] 2> "node_name":"127.0.0.1:53731_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 67343 INFO (OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000) [n:127.0.0.1:53731_ ] 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:53731", [junit4] 2> "node_name":"127.0.0.1:53731_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 67446 INFO (zkCallback-66-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ ] 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> 67476 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 67477 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 67479 INFO (zkCallback-68-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7c3d14d8 name:ZooKeeperConnection Watcher:127.0.0.1:39223/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 67479 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 67479 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 67479 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 67485 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 67486 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 67595 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/shard-1-001/cores/collection1 [junit4] 2> 67595 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/shard-1-001 [junit4] 2> 67596 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 67598 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2f928fd0{/,null,AVAILABLE} [junit4] 2> 67598 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.e.j.s.ServerConnector Started ServerConnector@48131c0d{HTTP/1.1,[http/1.1]}{127.0.0.1:40003} [junit4] 2> 67599 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.e.j.s.Server Started @70538ms [junit4] 2> 67599 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=40003, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/shard-1-001/cores} [junit4] 2> 67599 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93 [junit4] 2> 67599 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/shard-1-001' [junit4] 2> 67599 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 67599 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 67600 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 67600 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 67606 INFO (zkCallback-69-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2930bb33 name:ZooKeeperConnection Watcher:127.0.0.1:39223/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 67606 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 67606 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 67610 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 67610 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/shard-1-001/solr.xml [junit4] 2> 67615 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/shard-1-001/cores [junit4] 2> 67615 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.CoreContainer New CoreContainer 1650000496 [junit4] 2> 67615 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/shard-1-001] [junit4] 2> 67616 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/shard-1-001/lib [junit4] 2> 67616 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] 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> 67617 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 67618 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 67618 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 67618 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39223/solr [junit4] 2> 67618 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 67618 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 67623 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 67628 INFO (zkCallback-72-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@42eff37a name:ZooKeeperConnection Watcher:127.0.0.1:39223 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 67629 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 67630 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 67631 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:40003_ ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$38/887802213@6c184750 [junit4] 2> 67636 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:40003_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 67643 INFO (zkCallback-73-thread-1-processing-n:127.0.0.1:40003_) [n:127.0.0.1:40003_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6852de2 name:ZooKeeperConnection Watcher:127.0.0.1:39223/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 67643 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:40003_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 67649 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:40003_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 67650 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:40003_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 67652 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:40003_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 67652 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:40003_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95812075095588872-127.0.0.1:40003_-n_0000000001 [junit4] 2> 67653 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:40003_ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95812075095588868-127.0.0.1:53731_-n_0000000000 to know if I could be the leader [junit4] 2> 67653 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:40003_ ] o.a.s.c.ZkController Publish node=127.0.0.1:40003_ as DOWN [junit4] 2> 67655 INFO (OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000) [n:127.0.0.1:53731_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:40003_"} current state version: 3 [junit4] 2> 67655 INFO (OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000) [n:127.0.0.1:53731_ ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:40003_ [junit4] 2> 67758 INFO (zkCallback-68-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> 67758 INFO (zkCallback-66-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ ] 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> 67758 INFO (zkCallback-73-thread-1-processing-n:127.0.0.1:40003_) [n:127.0.0.1:40003_ ] 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> 68655 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:40003_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40003_ [junit4] 2> 68656 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:40003_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:40003_ [junit4] 2> 68658 INFO (zkCallback-73-thread-1-processing-n:127.0.0.1:40003_) [n:127.0.0.1:40003_ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1]) [junit4] 2> 68660 INFO (zkCallback-66-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1]) [junit4] 2> 68660 INFO (zkCallback-68-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1]) [junit4] 2> 68660 INFO (zkCallback-68-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 68660 INFO (zkCallback-73-thread-1-processing-n:127.0.0.1:40003_) [n:127.0.0.1:40003_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 68668 INFO (zkCallback-66-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 68669 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:40003_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 68670 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:40003_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 68670 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:40003_ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/shard-1-001/cores [junit4] 2> 68671 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:40003_ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=collection1, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 68671 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:40003_ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/shard-1-001/cores/collection1 [junit4] 2> 68671 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:40003_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 68673 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:40003_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1 [junit4] 2> 68673 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [n:127.0.0.1:40003_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 68674 INFO (coreLoadExecutor-303-thread-1-processing-n:127.0.0.1:40003_) [n:127.0.0.1:40003_ c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 68674 INFO (coreLoadExecutor-303-thread-1-processing-n:127.0.0.1:40003_) [n:127.0.0.1:40003_ c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 68675 INFO (coreLoadExecutor-303-thread-1-processing-n:127.0.0.1:40003_) [n:127.0.0.1:40003_ c:collection1 x:collection1] o.a.s.c.ZkController Collection collection1 not visible yet, but flagging it so a watch is registered when it becomes visible [junit4] 2> 68675 INFO (coreLoadExecutor-303-thread-1-processing-n:127.0.0.1:40003_) [n:127.0.0.1:40003_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader addZkWatch [collection1] [junit4] 2> 68675 INFO (OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000) [n:127.0.0.1:53731_ ] 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:40003", [junit4] 2> "node_name":"127.0.0.1:40003_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 4 [junit4] 2> 68675 INFO (coreLoadExecutor-303-thread-1-processing-n:127.0.0.1:40003_) [n:127.0.0.1:40003_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Deleting data for [collection1] [junit4] 2> 68675 INFO (OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000) [n:127.0.0.1:53731_ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:40003", [junit4] 2> "node_name":"127.0.0.1:40003_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 68675 INFO (coreLoadExecutor-303-thread-1-processing-n:127.0.0.1:40003_) [n:127.0.0.1:40003_ c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 68676 INFO (OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000) [n:127.0.0.1:53731_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 68676 INFO (OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000) [n:127.0.0.1:53731_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 68778 INFO (zkCallback-73-thread-1-processing-n:127.0.0.1:40003_) [n:127.0.0.1:40003_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2]) [junit4] 2> 68778 INFO (zkCallback-66-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2]) [junit4] 2> 68778 INFO (zkCallback-68-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: [2]) [junit4] 2> 69676 INFO (coreLoadExecutor-303-thread-1-processing-n:127.0.0.1:40003_) [n:127.0.0.1:40003_ c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 69676 INFO (coreLoadExecutor-303-thread-1-processing-n:127.0.0.1:40003_) [n:127.0.0.1:40003_ c:collection1 x:colle [...truncated too long message...] ess$200(LeaderElector.java:56) [junit4] 2> at org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:348) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.lambda$process$0(SolrZkClient.java:266) [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.lambda$execute$0(ExecutorUtil.java:229) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> 3930538 WARN (zkCallback-3794-thread-2-processing-n:127.0.0.1:39387_solr) [n:127.0.0.1:39387_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 3930536 WARN (zkCallback-3817-thread-2-processing-n:127.0.0.1:42884_solr) [n:127.0.0.1:42884_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 3930538 WARN (zkCallback-3815-thread-2-processing-n:127.0.0.1:46941_solr) [n:127.0.0.1:46941_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 3930537 INFO (jetty-launcher-3775-thread-13) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3359f584{/solr,null,UNAVAILABLE} [junit4] 2> 3930539 INFO (jetty-launcher-3775-thread-7) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2883f82c{/solr,null,UNAVAILABLE} [junit4] 2> 3930539 INFO (jetty-launcher-3775-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@654d6eda{/solr,null,UNAVAILABLE} [junit4] 2> 3930539 INFO (jetty-launcher-3775-thread-8) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@78bf24a3{/solr,null,UNAVAILABLE} [junit4] 2> 3930540 INFO (jetty-launcher-3775-thread-6) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3fb36723{/solr,null,UNAVAILABLE} [junit4] 2> 3930543 INFO (SUITE-TestTolerantUpdateProcessorRandomCloud-seed#[D87C2A1E874D0DFE]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:34152 34152 [junit4] 2> 3930721 INFO (Thread-100393) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:34152 34152 [junit4] 2> 3930722 WARN (Thread-100393) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 14 /solr/aliases.json [junit4] 2> 13 /solr/security.json [junit4] 2> 11 /solr/configs/solrCloudCollectionConfig/managed-schema [junit4] 2> 11 /solr/configs/solrCloudCollectionConfig [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 14 /solr/clusterstate.json [junit4] 2> 12 /solr/collections/test_col/state.json [junit4] 2> 2 /solr/collections/test_col/leader_elect/shard2/election/95812325951733789-core_node11-n_0000000001 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 14 /solr/live_nodes [junit4] 2> 14 /solr/collections [junit4] 2> [junit4] 2> 3930722 INFO (SUITE-TestTolerantUpdateProcessorRandomCloud-seed#[D87C2A1E874D0DFE]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> Apr 30, 2016 1:35:08 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=20452, maxDocsPerChunk=3, blockSize=31), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=20452, blockSize=31)), sim=ClassicSimilarity, locale=sk, timezone=Etc/GMT-7 [junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_74 (64-bit)/cpus=4,threads=1,free=199675744,total=515375104 [junit4] 2> NOTE: All tests run in this JVM: [SolrCmdDistributorTest, SolrIndexSplitterTest, TestRequestStatusCollectionAPI, TimeZoneUtilsTest, SuggestComponentTest, DirectSolrSpellCheckerTest, TestTrie, CollectionsAPIDistributedZkTest, HdfsBasicDistributedZkTest, SoftAutoCommitTest, TestQueryUtils, AddSchemaFieldsUpdateProcessorFactoryTest, TestExclusionRuleCollectionAccess, TestCustomSort, DeleteReplicaTest, SpellingQueryConverterTest, TestAnalyzeInfixSuggestions, TestJsonFacets, CSVRequestHandlerTest, TriLevelCompositeIdRoutingTest, TestDynamicFieldCollectionResource, TestLFUCache, CloudMLTQParserTest, TolerantUpdateProcessorTest, TestSolrQueryResponse, TestUseDocValuesAsStored, TestSimpleTrackingShardHandler, TestObjectReleaseTracker, TestMacros, TestTestInjection, TestSolr4Spatial2, TestCoreContainer, TestPartialUpdateDeduplication, SecurityConfHandlerTest, TestReloadAndDeleteDocs, CollectionsAPIAsyncDistributedZkTest, DistributedExpandComponentTest, NumericFieldsTest, TestDFISimilarityFactory, TestLRUStatsCache, ShardRoutingCustomTest, SolrInfoMBeanTest, TestCodecSupport, TestSchemaVersionResource, TestSolrConfigHandlerCloud, ZkStateReaderTest, BasicFunctionalityTest, SolrIndexConfigTest, CdcrUpdateLogTest, SpellCheckCollatorTest, TestConfigOverlay, AliasIntegrationTest, TestMissingGroups, CustomCollectionTest, CollectionReloadTest, TestQuerySenderNoQuery, DocValuesTest, RecoveryAfterSoftCommitTest, UniqFieldsUpdateProcessorFactoryTest, FastVectorHighlighterTest, TestCloudInspectUtil, TestSolrDeletionPolicy2, LoggingHandlerTest, SaslZkACLProviderTest, TestAuthorizationFramework, HighlighterTest, DistribCursorPagingTest, FileUtilsTest, TestSortByMinMaxFunction, TestHashPartitioner, TestManagedSynonymFilterFactory, TestHashQParserPlugin, FacetPivotSmallTest, SchemaVersionSpecificBehaviorTest, ConvertedLegacyTest, TestDFRSimilarityFactory, BlockJoinFacetRandomTest, TestBinaryResponseWriter, DateRangeFieldTest, TestFreeTextSuggestions, HighlighterMaxOffsetTest, TestWordDelimiterFilterFactory, TestBlobHandler, TestSurroundQueryParser, NotRequiredUniqueKeyTest, OutOfBoxZkACLAndCredentialsProvidersTest, TestSchemaNameResource, HdfsThreadLeakTest, DocumentBuilderTest, TestGeoJSONResponseWriter, SpatialFilterTest, AnalyticsQueryTest, TermsComponentTest, HdfsCollectionsAPIDistributedZkTest, RuleEngineTest, MoreLikeThisHandlerTest, OverseerTest, RecoveryZkTest, ZkSolrClientTest, TestZkChroot, ZkCLITest, TestDistributedSearch, TestCursorMarkWithoutUniqueKey, TestDistributedMissingSort, TestEmbeddedSolrServerConstructors, TestJettySolrRunner, AsyncMigrateRouteKeyTest, CdcrReplicationHandlerTest, CollectionTooManyReplicasTest, ConcurrentDeleteAndCreateCollectionTest, DeleteStatusTest, DistributedQueueTest, HttpPartitionTest, LeaderInitiatedRecoveryOnShardRestartTest, RemoteQueryErrorTest, ShardSplitTest, TestOnReconnectListenerSupport, TestRebalanceLeaders, TestTolerantUpdateProcessorRandomCloud] [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=TestTolerantUpdateProcessorRandomCloud -Dtests.seed=D87C2A1E874D0DFE -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt -Dtests.locale=sk -Dtests.timezone=Etc/GMT-7 -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 0.00s J1 | TestTolerantUpdateProcessorRandomCloud (suite) <<< [junit4] > Throwable #1: java.lang.AssertionError: Could not find collection:test_col [junit4] > at __randomizedtesting.SeedInfo.seed([D87C2A1E874D0DFE]:0) [junit4] > at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:150) [junit4] > at org.apache.solr.cloud.TestTolerantUpdateProcessorRandomCloud.createMiniSolrCloudCluster(TestTolerantUpdateProcessorRandomCloud.java:135) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] Completed [474/592 (3!)] on J1 in 37.89s, 0 tests, 1 failure <<< FAILURES! [...truncated 385 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:747: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:684: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/common-build.xml:529: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1457: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1014: There were test failures: 592 suites (10 ignored), 2397 tests, 1 suite-level error, 1 error, 1 failure, 31 ignored (16 assumptions) [seed: D87C2A1E874D0DFE] Total time: 253 minutes 11 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts No prior successful build to compare, so performing full copy of artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org