Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/3866/ Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseG1GC
2 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI Error Message: Error from server at http://127.0.0.1:65481/solr/awhollynewcollection_0: Expected mime type application/octet-stream but got text/html. <html> <head> <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/> <title>Error 510 </title> </head> <body> <h2>HTTP ERROR: 510</h2> <p>Problem accessing /solr/awhollynewcollection_0/select. Reason: <pre> {metadata={error-class=org.apache.solr.common.SolrException,root-error-class=org.apache.solr.common.SolrException},msg={"awhollynewcollection_0":7},code=510}</pre></p> <hr /><a href="http://eclipse.org/jetty";>Powered by Jetty:// 9.3.14.v20161028</a><hr/> </body> </html> Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:65481/solr/awhollynewcollection_0: Expected mime type application/octet-stream but got text/html. <html> <head> <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/> <title>Error 510 </title> </head> <body> <h2>HTTP ERROR: 510</h2> <p>Problem accessing /solr/awhollynewcollection_0/select. Reason: <pre> {metadata={error-class=org.apache.solr.common.SolrException,root-error-class=org.apache.solr.common.SolrException},msg={"awhollynewcollection_0":7},code=510}</pre></p> <hr /><a href="http://eclipse.org/jetty";>Powered by Jetty:// 9.3.14.v20161028</a><hr/> </body> </html> at __randomizedtesting.SeedInfo.seed([23F772886C475875:6B82063C6A7477E0]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:595) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:279) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:268) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:439) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:391) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1361) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1112) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1215) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1215) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1215) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1215) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1215) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:1042) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:160) at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:942) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI(CollectionsAPIDistributedZkTest.java:523) 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:1713) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957) 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:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) 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:368) at java.lang.Thread.run(Thread.java:745) FAILED: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.test Error Message: Could not find collection:collection2 Stack Trace: java.lang.AssertionError: Could not find collection:collection2 at __randomizedtesting.SeedInfo.seed([23F772886C475875:ABA34D52C2BB358D]: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:159) at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:144) at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:139) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:865) at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.testIndexingBatchPerRequestWithHttpSolrClient(FullSolrCloudDistribCmdsTest.java:620) at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.test(FullSolrCloudDistribCmdsTest.java:152) 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:1713) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:985) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960) 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:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) 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:368) at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 11087 lines...] [junit4] Suite: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest [junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/init-core-data-001 [junit4] 2> 325159 INFO (SUITE-FullSolrCloudDistribCmdsTest-seed#[23F772886C475875]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields [junit4] 2> 325162 INFO (SUITE-FullSolrCloudDistribCmdsTest-seed#[23F772886C475875]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-5776) w/ MAC_OS_X supressed clientAuth [junit4] 2> 325163 INFO (SUITE-FullSolrCloudDistribCmdsTest-seed#[23F772886C475875]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /b_i/ [junit4] 2> 325164 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 325165 INFO (Thread-598) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 325165 INFO (Thread-598) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 325266 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.ZkTestServer start zk server on port:65014 [junit4] 2> 325300 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 325304 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml [junit4] 2> 325308 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 325311 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 325314 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 325317 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 325320 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 325323 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 325325 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 325328 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 325331 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 325618 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/control-001/cores/collection1 [junit4] 2> 325620 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 325623 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@62e42435{/b_i,null,AVAILABLE} [junit4] 2> 325625 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@37459a34{HTTP/1.1,[http/1.1]}{127.0.0.1:65017} [junit4] 2> 325625 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.e.j.s.Server Started @332113ms [junit4] 2> 325625 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/tempDir-001/control/data, hostContext=/b_i, hostPort=65017, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/control-001/cores} [junit4] 2> 325625 ERROR (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 325625 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0 [junit4] 2> 325625 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 325625 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 325625 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-03-02T15:11:01.052Z [junit4] 2> 325630 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 325630 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/control-001/solr.xml [junit4] 2> 325640 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:65014/solr [junit4] 2> 325705 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [n:127.0.0.1:65017_b_i ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 325708 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [n:127.0.0.1:65017_b_i ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:65017_b_i [junit4] 2> 325710 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [n:127.0.0.1:65017_b_i ] o.a.s.c.Overseer Overseer (id=97548203497553924-127.0.0.1:65017_b_i-n_0000000000) starting [junit4] 2> 325734 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [n:127.0.0.1:65017_b_i ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:65017_b_i [junit4] 2> 325738 INFO (zkCallback-399-thread-1-processing-n:127.0.0.1:65017_b_i) [n:127.0.0.1:65017_b_i ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 325867 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [n:127.0.0.1:65017_b_i ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/control-001/cores [junit4] 2> 325868 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [n:127.0.0.1:65017_b_i ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 325890 INFO (OverseerStateUpdate-97548203497553924-127.0.0.1:65017_b_i-n_0000000000) [n:127.0.0.1:65017_b_i ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 326917 INFO (coreLoadExecutor-1201-thread-1-processing-n:127.0.0.1:65017_b_i) [n:127.0.0.1:65017_b_i c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 326932 INFO (coreLoadExecutor-1201-thread-1-processing-n:127.0.0.1:65017_b_i) [n:127.0.0.1:65017_b_i c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 326998 WARN (coreLoadExecutor-1201-thread-1-processing-n:127.0.0.1:65017_b_i) [n:127.0.0.1:65017_b_i c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead. [junit4] 2> 326999 INFO (coreLoadExecutor-1201-thread-1-processing-n:127.0.0.1:65017_b_i) [n:127.0.0.1:65017_b_i c:control_collection x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 327035 INFO (coreLoadExecutor-1201-thread-1-processing-n:127.0.0.1:65017_b_i) [n:127.0.0.1:65017_b_i c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 327035 INFO (coreLoadExecutor-1201-thread-1-processing-n:127.0.0.1:65017_b_i) [n:127.0.0.1:65017_b_i c:control_collection x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/control-001/cores/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/control-001/cores/collection1/data/] [junit4] 2> 327035 INFO (coreLoadExecutor-1201-thread-1-processing-n:127.0.0.1:65017_b_i) [n:127.0.0.1:65017_b_i c:control_collection x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6fba3f14 [junit4] 2> 327036 INFO (coreLoadExecutor-1201-thread-1-processing-n:127.0.0.1:65017_b_i) [n:127.0.0.1:65017_b_i c:control_collection x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=76.4033203125, floorSegmentMB=0.5751953125, forceMergeDeletesPctAllowed=11.342128596103489, segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7837550056295374 [junit4] 2> 327061 WARN (coreLoadExecutor-1201-thread-1-processing-n:127.0.0.1:65017_b_i) [n:127.0.0.1:65017_b_i c:control_collection x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 327133 INFO (coreLoadExecutor-1201-thread-1-processing-n:127.0.0.1:65017_b_i) [n:127.0.0.1:65017_b_i c:control_collection x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 327133 INFO (coreLoadExecutor-1201-thread-1-processing-n:127.0.0.1:65017_b_i) [n:127.0.0.1:65017_b_i c:control_collection x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 327134 INFO (coreLoadExecutor-1201-thread-1-processing-n:127.0.0.1:65017_b_i) [n:127.0.0.1:65017_b_i c:control_collection x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 327134 INFO (coreLoadExecutor-1201-thread-1-processing-n:127.0.0.1:65017_b_i) [n:127.0.0.1:65017_b_i c:control_collection x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 327135 INFO (coreLoadExecutor-1201-thread-1-processing-n:127.0.0.1:65017_b_i) [n:127.0.0.1:65017_b_i c:control_collection x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=2004927035, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 327135 INFO (coreLoadExecutor-1201-thread-1-processing-n:127.0.0.1:65017_b_i) [n:127.0.0.1:65017_b_i c:control_collection x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@44d0bfdb[collection1] main] [junit4] 2> 327137 INFO (coreLoadExecutor-1201-thread-1-processing-n:127.0.0.1:65017_b_i) [n:127.0.0.1:65017_b_i c:control_collection x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 327138 INFO (coreLoadExecutor-1201-thread-1-processing-n:127.0.0.1:65017_b_i) [n:127.0.0.1:65017_b_i c:control_collection x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 327138 INFO (coreLoadExecutor-1201-thread-1-processing-n:127.0.0.1:65017_b_i) [n:127.0.0.1:65017_b_i c:control_collection x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 327139 INFO (searcherExecutor-1202-thread-1-processing-n:127.0.0.1:65017_b_i x:collection1 c:control_collection) [n:127.0.0.1:65017_b_i c:control_collection x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@44d0bfdb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 327140 INFO (coreLoadExecutor-1201-thread-1-processing-n:127.0.0.1:65017_b_i) [n:127.0.0.1:65017_b_i c:control_collection x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1560771258028654592 [junit4] 2> 327152 INFO (coreZkRegister-1194-thread-1-processing-n:127.0.0.1:65017_b_i x:collection1 c:control_collection) [n:127.0.0.1:65017_b_i c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 327152 INFO (coreZkRegister-1194-thread-1-processing-n:127.0.0.1:65017_b_i x:collection1 c:control_collection) [n:127.0.0.1:65017_b_i 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> 327152 INFO (coreZkRegister-1194-thread-1-processing-n:127.0.0.1:65017_b_i x:collection1 c:control_collection) [n:127.0.0.1:65017_b_i c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:65017/b_i/collection1/ [junit4] 2> 327152 INFO (coreZkRegister-1194-thread-1-processing-n:127.0.0.1:65017_b_i x:collection1 c:control_collection) [n:127.0.0.1:65017_b_i c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 327152 INFO (coreZkRegister-1194-thread-1-processing-n:127.0.0.1:65017_b_i x:collection1 c:control_collection) [n:127.0.0.1:65017_b_i c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:65017/b_i/collection1/ has no replicas [junit4] 2> 327152 INFO (coreZkRegister-1194-thread-1-processing-n:127.0.0.1:65017_b_i x:collection1 c:control_collection) [n:127.0.0.1:65017_b_i c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 327161 INFO (coreZkRegister-1194-thread-1-processing-n:127.0.0.1:65017_b_i x:collection1 c:control_collection) [n:127.0.0.1:65017_b_i 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:65017/b_i/collection1/ shard1 [junit4] 2> 327322 INFO (coreZkRegister-1194-thread-1-processing-n:127.0.0.1:65017_b_i x:collection1 c:control_collection) [n:127.0.0.1:65017_b_i c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 327549 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 327551 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:65014/solr ready [junit4] 2> 327551 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 327551 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 328669 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/shard-1-001/cores/collection1 [junit4] 2> 328670 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/shard-1-001 [junit4] 2> 328671 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 328673 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@30d646fe{/b_i,null,AVAILABLE} [junit4] 2> 328674 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@29944b42{HTTP/1.1,[http/1.1]}{127.0.0.1:65043} [junit4] 2> 328674 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.e.j.s.Server Started @335163ms [junit4] 2> 328674 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/b_i, hostPort=65043, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/shard-1-001/cores} [junit4] 2> 328675 ERROR (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 328675 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0 [junit4] 2> 328675 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 328675 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 328675 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-03-02T15:11:04.102Z [junit4] 2> 328680 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 328680 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/shard-1-001/solr.xml [junit4] 2> 328755 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:65014/solr [junit4] 2> 328775 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [n:127.0.0.1:65043_b_i ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 328781 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [n:127.0.0.1:65043_b_i ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 328786 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [n:127.0.0.1:65043_b_i ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:65043_b_i [junit4] 2> 328788 INFO (zkCallback-399-thread-2-processing-n:127.0.0.1:65017_b_i) [n:127.0.0.1:65017_b_i ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 328790 INFO (zkCallback-403-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 328791 INFO (zkCallback-409-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 328885 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [n:127.0.0.1:65043_b_i ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/shard-1-001/cores [junit4] 2> 328885 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [n:127.0.0.1:65043_b_i ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 328889 INFO (OverseerStateUpdate-97548203497553924-127.0.0.1:65017_b_i-n_0000000000) [n:127.0.0.1:65017_b_i ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 328995 INFO (zkCallback-409-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2]) [junit4] 2> 329911 INFO (coreLoadExecutor-1212-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 329924 INFO (coreLoadExecutor-1212-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 330024 WARN (coreLoadExecutor-1212-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead. [junit4] 2> 330026 INFO (coreLoadExecutor-1212-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i c:collection1 x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 330063 INFO (coreLoadExecutor-1212-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i c:collection1 x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 330064 INFO (coreLoadExecutor-1212-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i c:collection1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/shard-1-001/cores/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/shard-1-001/cores/collection1/data/] [junit4] 2> 330064 INFO (coreLoadExecutor-1212-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i c:collection1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6fba3f14 [junit4] 2> 330066 INFO (coreLoadExecutor-1212-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i c:collection1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=76.4033203125, floorSegmentMB=0.5751953125, forceMergeDeletesPctAllowed=11.342128596103489, segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7837550056295374 [junit4] 2> 330078 WARN (coreLoadExecutor-1212-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i c:collection1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 330162 INFO (coreLoadExecutor-1212-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i c:collection1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 330162 INFO (coreLoadExecutor-1212-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i c:collection1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 330164 INFO (coreLoadExecutor-1212-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i c:collection1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 330164 INFO (coreLoadExecutor-1212-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i c:collection1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 330166 INFO (coreLoadExecutor-1212-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i c:collection1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=2004927035, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 330166 INFO (coreLoadExecutor-1212-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i c:collection1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@568f526f[collection1] main] [junit4] 2> 330168 INFO (coreLoadExecutor-1212-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i c:collection1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 330170 INFO (coreLoadExecutor-1212-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i c:collection1 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 330170 INFO (coreLoadExecutor-1212-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i c:collection1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 330172 INFO (searcherExecutor-1213-thread-1-processing-n:127.0.0.1:65043_b_i x:collection1 c:collection1) [n:127.0.0.1:65043_b_i c:collection1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@568f526f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 330172 INFO (coreLoadExecutor-1212-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i c:collection1 x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1560771261207937024 [junit4] 2> 330185 INFO (coreZkRegister-1207-thread-1-processing-n:127.0.0.1:65043_b_i x:collection1 c:collection1) [n:127.0.0.1:65043_b_i c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 330185 INFO (coreZkRegister-1207-thread-1-processing-n:127.0.0.1:65043_b_i x:collection1 c:collection1) [n:127.0.0.1:65043_b_i c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 330185 INFO (coreZkRegister-1207-thread-1-processing-n:127.0.0.1:65043_b_i x:collection1 c:collection1) [n:127.0.0.1:65043_b_i c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:65043/b_i/collection1/ [junit4] 2> 330185 INFO (coreZkRegister-1207-thread-1-processing-n:127.0.0.1:65043_b_i x:collection1 c:collection1) [n:127.0.0.1:65043_b_i c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 330185 INFO (coreZkRegister-1207-thread-1-processing-n:127.0.0.1:65043_b_i x:collection1 c:collection1) [n:127.0.0.1:65043_b_i c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:65043/b_i/collection1/ has no replicas [junit4] 2> 330185 INFO (coreZkRegister-1207-thread-1-processing-n:127.0.0.1:65043_b_i x:collection1 c:collection1) [n:127.0.0.1:65043_b_i c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 330193 INFO (coreZkRegister-1207-thread-1-processing-n:127.0.0.1:65043_b_i x:collection1 c:collection1) [n:127.0.0.1:65043_b_i c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:65043/b_i/collection1/ shard2 [junit4] 2> 330310 INFO (zkCallback-409-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2]) [junit4] 2> 330359 INFO (coreZkRegister-1207-thread-1-processing-n:127.0.0.1:65043_b_i x:collection1 c:collection1) [n:127.0.0.1:65043_b_i c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 330473 INFO (zkCallback-409-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2]) [junit4] 2> 330884 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/shard-2-001/cores/collection1 [junit4] 2> 330885 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/shard-2-001 [junit4] 2> 330886 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 330889 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@c1633f6{/b_i,null,AVAILABLE} [junit4] 2> 330889 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@47d913d9{HTTP/1.1,[http/1.1]}{127.0.0.1:65055} [junit4] 2> 330890 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.e.j.s.Server Started @337378ms [junit4] 2> 330890 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/tempDir-001/jetty2, solrconfig=solrconfig.xml, hostContext=/b_i, hostPort=65055, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/shard-2-001/cores} [junit4] 2> 330890 ERROR (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 330891 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0 [junit4] 2> 330891 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 330891 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 330891 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-03-02T15:11:06.318Z [junit4] 2> 330897 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 330897 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/shard-2-001/solr.xml [junit4] 2> 330919 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:65014/solr [junit4] 2> 330943 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [n:127.0.0.1:65055_b_i ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 330949 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [n:127.0.0.1:65055_b_i ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 330954 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [n:127.0.0.1:65055_b_i ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:65055_b_i [junit4] 2> 330968 INFO (zkCallback-409-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 330972 INFO (zkCallback-415-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 330968 INFO (zkCallback-399-thread-2-processing-n:127.0.0.1:65017_b_i) [n:127.0.0.1:65017_b_i ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 330975 INFO (zkCallback-403-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 331048 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [n:127.0.0.1:65055_b_i ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/shard-2-001/cores [junit4] 2> 331048 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [n:127.0.0.1:65055_b_i ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 331052 INFO (OverseerStateUpdate-97548203497553924-127.0.0.1:65017_b_i-n_0000000000) [n:127.0.0.1:65017_b_i ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard3 [junit4] 2> 331165 INFO (zkCallback-409-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3]) [junit4] 2> 331165 INFO (zkCallback-415-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3]) [junit4] 2> 332082 INFO (coreLoadExecutor-1223-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 332126 INFO (coreLoadExecutor-1223-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 332194 WARN (coreLoadExecutor-1223-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead. [junit4] 2> 332195 INFO (coreLoadExecutor-1223-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i c:collection1 x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 332230 INFO (coreLoadExecutor-1223-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i c:collection1 x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 332230 INFO (coreLoadExecutor-1223-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i c:collection1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/shard-2-001/cores/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/shard-2-001/cores/collection1/data/] [junit4] 2> 332230 INFO (coreLoadExecutor-1223-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i c:collection1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6fba3f14 [junit4] 2> 332233 INFO (coreLoadExecutor-1223-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i c:collection1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=76.4033203125, floorSegmentMB=0.5751953125, forceMergeDeletesPctAllowed=11.342128596103489, segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7837550056295374 [junit4] 2> 332241 WARN (coreLoadExecutor-1223-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i c:collection1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 332319 INFO (coreLoadExecutor-1223-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i c:collection1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 332320 INFO (coreLoadExecutor-1223-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i c:collection1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 332321 INFO (coreLoadExecutor-1223-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i c:collection1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 332321 INFO (coreLoadExecutor-1223-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i c:collection1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 332322 INFO (coreLoadExecutor-1223-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i c:collection1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=2004927035, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 332322 INFO (coreLoadExecutor-1223-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i c:collection1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@61da348b[collection1] main] [junit4] 2> 332324 INFO (coreLoadExecutor-1223-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i c:collection1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 332325 INFO (coreLoadExecutor-1223-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i c:collection1 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 332325 INFO (coreLoadExecutor-1223-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i c:collection1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 332326 INFO (searcherExecutor-1224-thread-1-processing-n:127.0.0.1:65055_b_i x:collection1 c:collection1) [n:127.0.0.1:65055_b_i c:collection1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@61da348b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 332328 INFO (coreLoadExecutor-1223-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i c:collection1 x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1560771263468666880 [junit4] 2> 332339 INFO (coreZkRegister-1218-thread-1-processing-n:127.0.0.1:65055_b_i x:collection1 c:collection1) [n:127.0.0.1:65055_b_i c:collection1 s:shard3 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 332339 INFO (coreZkRegister-1218-thread-1-processing-n:127.0.0.1:65055_b_i x:collection1 c:collection1) [n:127.0.0.1:65055_b_i c:collection1 s:shard3 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 332339 INFO (coreZkRegister-1218-thread-1-processing-n:127.0.0.1:65055_b_i x:collection1 c:collection1) [n:127.0.0.1:65055_b_i c:collection1 s:shard3 r:core_node2 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:65055/b_i/collection1/ [junit4] 2> 332339 INFO (coreZkRegister-1218-thread-1-processing-n:127.0.0.1:65055_b_i x:collection1 c:collection1) [n:127.0.0.1:65055_b_i c:collection1 s:shard3 r:core_node2 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 332339 INFO (coreZkRegister-1218-thread-1-processing-n:127.0.0.1:65055_b_i x:collection1 c:collection1) [n:127.0.0.1:65055_b_i c:collection1 s:shard3 r:core_node2 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:65055/b_i/collection1/ has no replicas [junit4] 2> 332339 INFO (coreZkRegister-1218-thread-1-processing-n:127.0.0.1:65055_b_i x:collection1 c:collection1) [n:127.0.0.1:65055_b_i c:collection1 s:shard3 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 332346 INFO (coreZkRegister-1218-thread-1-processing-n:127.0.0.1:65055_b_i x:collection1 c:collection1) [n:127.0.0.1:65055_b_i c:collection1 s:shard3 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:65055/b_i/collection1/ shard3 [junit4] 2> 332458 INFO (zkCallback-415-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3]) [junit4] 2> 332458 INFO (zkCallback-409-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3]) [junit4] 2> 332507 INFO (coreZkRegister-1218-thread-1-processing-n:127.0.0.1:65055_b_i x:collection1 c:collection1) [n:127.0.0.1:65055_b_i c:collection1 s:shard3 r:core_node2 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 332626 INFO (zkCallback-415-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3]) [junit4] 2> 332626 INFO (zkCallback-409-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3]) [junit4] 2> 333052 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/shard-3-001/cores/collection1 [junit4] 2> 333054 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/shard-3-001 [junit4] 2> 333054 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 333056 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1521b855{/b_i,null,AVAILABLE} [junit4] 2> 333057 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@356105e0{HTTP/1.1,[http/1.1]}{127.0.0.1:65063} [junit4] 2> 333057 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.e.j.s.Server Started @339545ms [junit4] 2> 333057 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/tempDir-001/jetty3, solrconfig=solrconfig.xml, hostContext=/b_i, hostPort=65063, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/shard-3-001/cores} [junit4] 2> 333058 ERROR (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 333058 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0 [junit4] 2> 333058 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 333058 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 333058 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-03-02T15:11:08.485Z [junit4] 2> 333065 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 333065 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/shard-3-001/solr.xml [junit4] 2> 333087 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:65014/solr [junit4] 2> 333107 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [n:127.0.0.1:65063_b_i ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 333114 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [n:127.0.0.1:65063_b_i ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 333118 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [n:127.0.0.1:65063_b_i ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:65063_b_i [junit4] 2> 333121 INFO (zkCallback-409-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 333122 INFO (zkCallback-415-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 333123 INFO (zkCallback-421-thread-1-processing-n:127.0.0.1:65063_b_i) [n:127.0.0.1:65063_b_i ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 333123 INFO (zkCallback-403-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 333123 INFO (zkCallback-399-thread-3-processing-n:127.0.0.1:65017_b_i) [n:127.0.0.1:65017_b_i ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 333234 INFO (zkCallback-409-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4]) [junit4] 2> 333234 INFO (zkCallback-415-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4]) [junit4] 2> 333275 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [n:127.0.0.1:65063_b_i ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/shard-3-001/cores [junit4] 2> 333275 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [n:127.0.0.1:65063_b_i ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 333280 INFO (OverseerStateUpdate-97548203497553924-127.0.0.1:65017_b_i-n_0000000000) [n:127.0.0.1:65017_b_i ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 333391 INFO (zkCallback-409-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4]) [junit4] 2> 333391 INFO (zkCallback-421-thread-1-processing-n:127.0.0.1:65063_b_i) [n:127.0.0.1:65063_b_i ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4]) [junit4] 2> 333391 INFO (zkCallback-415-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4]) [junit4] 2> 334303 INFO (coreLoadExecutor-1234-thread-1-processing-n:127.0.0.1:65063_b_i) [n:127.0.0.1:65063_b_i c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 334315 INFO (coreLoadExecutor-1234-thread-1-processing-n:127.0.0.1:65063_b_i) [n:127.0.0.1:65063_b_i c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 334387 WARN (coreLoadExecutor-1234-thread-1-processing-n:127.0.0.1:65063_b_i) [n:127.0.0.1:65063_b_i c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead. [junit4] 2> 334388 INFO (coreLoadExecutor-1234-thread-1-processing-n:127.0.0.1:65063_b_i) [n:127.0.0.1:65063_b_i c:collection1 x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 334421 INFO (coreLoadExecutor-1234-thread-1-processing-n:127.0.0.1:65063_b_i) [n:127.0.0.1:65063_b_i c:collection1 x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 334421 INFO (coreLoadExecutor-1234-thread-1-processing-n:127.0.0.1:65063_b_i) [n:127.0.0.1:65063_b_i c:collection1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/shard-3-001/cores/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/shard-3-001/cores/collection1/data/] [junit4] 2> 334421 INFO (coreLoadExecutor-1234-thread-1-processing-n:127.0.0.1:65063_b_i) [n:127.0.0.1:65063_b_i c:collection1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6fba3f14 [junit4] 2> 334422 INFO (coreLoadExecutor-1234-thread-1-processing-n:127.0.0.1:65063_b_i) [n:127.0.0.1:65063_b_i c:collection1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=76.4033203125, floorSegmentMB=0.5751953125, forceMergeDeletesPctAllowed=11.342128596103489, segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7837550056295374 [junit4] 2> 334428 WARN (coreLoadExecutor-1234-thread-1-processing-n:127.0.0.1:65063_b_i) [n:127.0.0.1:65063_b_i c:collection1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 334494 INFO (coreLoadExecutor-1234-thread-1-processing-n:127.0.0.1:65063_b_i) [n:127.0.0.1:65063_b_i c:collection1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 334494 INFO (coreLoadExecutor-1234-thread-1-processing-n:127.0.0.1:65063_b_i) [n:127.0.0.1:65063_b_i c:collection1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 334496 INFO (coreLoadExecutor-1234-thread-1-processing-n:127.0.0.1:65063_b_i) [n:127.0.0.1:65063_b_i c:collection1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 334496 INFO (coreLoadExecutor-1234-thread-1-processing-n:127.0.0.1:65063_b_i) [n:127.0.0.1:65063_b_i c:collection1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 334497 INFO (coreLoadExecutor-1234-thread-1-processing-n:127.0.0.1:65063_b_i) [n:127.0.0.1:65063_b_i c:collection1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=2004927035, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 334497 INFO (coreLoadExecutor-1234-thread-1-processing-n:127.0.0.1:65063_b_i) [n:127.0.0.1:65063_b_i c:collection1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2cfb6308[collection1] main] [junit4] 2> 334499 INFO (coreLoadExecutor-1234-thread-1-processing-n:127.0.0.1:65063_b_i) [n:127.0.0.1:65063_b_i c:collection1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 334500 INFO (coreLoadExecutor-1234-thread-1-processing-n:127.0.0.1:65063_b_i) [n:127.0.0.1:65063_b_i c:collection1 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 334500 INFO (coreLoadExecutor-1234-thread-1-processing-n:127.0.0.1:65063_b_i) [n:127.0.0.1:65063_b_i c:collection1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 334502 INFO (searcherExecutor-1235-thread-1-processing-n:127.0.0.1:65063_b_i x:collection1 c:collection1) [n:127.0.0.1:65063_b_i c:collection1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2cfb6308[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 334503 INFO (coreLoadExecutor-1234-thread-1-processing-n:127.0.0.1:65063_b_i) [n:127.0.0.1:65063_b_i c:collection1 x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1560771265749319680 [junit4] 2> 334515 INFO (coreZkRegister-1229-thread-1-processing-n:127.0.0.1:65063_b_i x:collection1 c:collection1) [n:127.0.0.1:65063_b_i c:collection1 s:shard1 r:core_node3 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 334515 INFO (coreZkRegister-1229-thread-1-processing-n:127.0.0.1:65063_b_i x:collection1 c:collection1) [n:127.0.0.1:65063_b_i c:collection1 s:shard1 r:core_node3 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 334515 INFO (coreZkRegister-1229-thread-1-processing-n:127.0.0.1:65063_b_i x:collection1 c:collection1) [n:127.0.0.1:65063_b_i c:collection1 s:shard1 r:core_node3 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:65063/b_i/collection1/ [junit4] 2> 334515 INFO (coreZkRegister-1229-thread-1-processing-n:127.0.0.1:65063_b_i x:collection1 c:collection1) [n:127.0.0.1:65063_b_i c:collection1 s:shard1 r:core_node3 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 334515 INFO (coreZkRegister-1229-thread-1-processing-n:127.0.0.1:65063_b_i x:collection1 c:collection1) [n:127.0.0.1:65063_b_i c:collection1 s:shard1 r:core_node3 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:65063/b_i/collection1/ has no replicas [junit4] 2> 334516 INFO (coreZkRegister-1229-thread-1-processing-n:127.0.0.1:65063_b_i x:collection1 c:collection1) [n:127.0.0.1:65063_b_i c:collection1 s:shard1 r:core_node3 x:collection1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 334523 INFO (coreZkRegister-1229-thread-1-processing-n:127.0.0.1:65063_b_i x:collection1 c:collection1) [n:127.0.0.1:65063_b_i c:collection1 s:shard1 r:core_node3 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:65063/b_i/collection1/ shard1 [junit4] 2> 334636 INFO (zkCallback-409-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4]) [junit4] 2> 334636 INFO (zkCallback-421-thread-1-processing-n:127.0.0.1:65063_b_i) [n:127.0.0.1:65063_b_i ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4]) [junit4] 2> 334636 INFO (zkCallback-415-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4]) [junit4] 2> 334687 INFO (coreZkRegister-1229-thread-1-processing-n:127.0.0.1:65063_b_i x:collection1 c:collection1) [n:127.0.0.1:65063_b_i c:collection1 s:shard1 r:core_node3 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 334804 INFO (zkCallback-409-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4]) [junit4] 2> 334804 INFO (zkCallback-421-thread-1-processing-n:127.0.0.1:65063_b_i) [n:127.0.0.1:65063_b_i ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4]) [junit4] 2> 334804 INFO (zkCallback-415-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4]) [junit4] 2> 335252 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/shard-4-001/cores/collection1 [junit4] 2> 335253 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/shard-4-001 [junit4] 2> 335254 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 335256 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3058878e{/b_i,null,AVAILABLE} [junit4] 2> 335258 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4743baf2{HTTP/1.1,[http/1.1]}{127.0.0.1:65071} [junit4] 2> 335258 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.e.j.s.Server Started @341746ms [junit4] 2> 335258 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/tempDir-001/jetty4, solrconfig=solrconfig.xml, hostContext=/b_i, hostPort=65071, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/shard-4-001/cores} [junit4] 2> 335259 ERROR (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 335260 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0 [junit4] 2> 335260 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 335260 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 335260 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-03-02T15:11:10.687Z [junit4] 2> 335265 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 335265 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/shard-4-001/solr.xml [junit4] 2> 335278 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:65014/solr [junit4] 2> 335302 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [n:127.0.0.1:65071_b_i ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 335309 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [n:127.0.0.1:65071_b_i ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 335314 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [n:127.0.0.1:65071_b_i ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:65071_b_i [junit4] 2> 335322 INFO (zkCallback-427-thread-1-processing-n:127.0.0.1:65071_b_i) [n:127.0.0.1:65071_b_i ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 335322 INFO (zkCallback-409-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 335323 INFO (zkCallback-403-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 335323 INFO (zkCallback-399-thread-1-processing-n:127.0.0.1:65017_b_i) [n:127.0.0.1:65017_b_i ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 335323 INFO (zkCallback-415-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 335323 INFO (zkCallback-421-thread-1-processing-n:127.0.0.1:65063_b_i) [n:127.0.0.1:65063_b_i ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 335439 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [n:127.0.0.1:65071_b_i ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/shard-4-001/cores [junit4] 2> 335439 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[23F772886C475875]) [n:127.0.0.1:65071_b_i ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 335442 INFO (zkCallback-409-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [5]) [junit4] 2> 335443 INFO (zkCallback-415-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [5]) [junit4] 2> 335443 INFO (zkCallback-421-thread-1-processing-n:127.0.0.1:65063_b_i) [n:127.0.0.1:65063_b_i ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [5]) [junit4] 2> 335448 INFO (OverseerStateUpdate-97548203497553924-127.0.0.1:65017_b_i-n_0000000000) [n:127.0.0.1:65017_b_i ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 335560 INFO (zkCallback-415-thread-1-processing-n:127.0.0.1:65055_b_i) [n:127.0.0.1:65055_b_i ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [5]) [junit4] 2> 335560 INFO (zkCallback-409-thread-1-processing-n:127.0.0.1:65043_b_i) [n:127.0.0.1:65043_b_i ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [5]) [junit4] 2> 335560 INFO (zkCallback-421-thread-1-processing-n:127.0.0.1:65063_b_i) [n:127.0.0.1:65063_b_i ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [5]) [junit4] 2> 335560 INFO (zkCallback-427-thread-1-processing-n:127.0.0.1:65071_b_i) [n:127.0.0.1:65071_b_i ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [5]) [junit4] 2> 336467 INFO (coreLoadExecutor-1245-thread-1-processing-n:127.0.0.1:65071_b_i) [n:127.0.0.1:65071_b_i c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 336482 INFO (coreLoadExecutor-1245-thread-1-processing-n:127.0.0.1:65071_b_i) [n:127.0.0.1:65071_b_i c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 336577 WARN (coreLoadExecutor-1245-thread-1-processing-n:127.0.0.1:65071_b_i) [n:127.0.0.1:65071_b_i c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead. [junit4] 2> 336579 INFO (coreLoadExecutor-1245-thread-1-processing-n:127.0.0.1:65071_b_i) [n:127.0.0.1:65071_b_i c:collection1 x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 336616 INFO (coreLoadExecutor-1245-thread-1-processing-n:127.0.0.1:65071_b_i) [n:127.0.0.1:65071_b_i c:collection1 x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 336616 INFO (coreLoadExecutor-1245-thread-1-processing-n:127.0.0.1:65071_b_i) [n:127.0.0.1:65071_b_i c:collection1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/shard-4-001/cores/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_23F772886C475875-001/shard-4-001/cores/collection1/data/] [junit4] 2> 336617 INFO (coreLoadExecutor-1245-thread-1-processing-n:127.0.0.1:65071_b_i) [n:127.0.0.1:65071_b_i c:collection1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6fba3f14 [junit4] 2> 336618 INFO (coreLoadExecutor-1245-thread-1-processing-n:127.0.0.1:65071_b_i) [n:127.0.0.1:65071_b_i c:collection1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=76.4033203125, floorSegmentMB=0.5751953125, forceMergeDeletesPctAllowed=11.342128596103489, segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7837550056295374 [junit4] 2> 336624 WARN (coreLoadExecutor-1245-thread-1-processing-n:127.0.0.1:65071_b_i) [n:127.0.0.1:65071_b_i c:collection1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 336717 INFO (coreLoadExecutor-1245-thread-1-processing-n:127.0.0.1:65071_b_i) [n:127.0.0.1:65071_b_i c:collection1 x:collection1] o.a.s [...truncated too long message...] lback-526-thread-7-processing-n:127.0.0.1:65479_solr) [n:127.0.0.1:65479_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (1) [junit4] 2> 540689 INFO (jetty-closer-511-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node [junit4] 2> 540690 INFO (jetty-closer-511-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4fdc38d4{/solr,null,UNAVAILABLE} [junit4] 2> 540691 INFO (jetty-closer-511-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node [junit4] 2> 540692 INFO (jetty-closer-511-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@181e97ec{/solr,null,UNAVAILABLE} [junit4] 2> 540694 WARN (zkCallback-526-thread-2-processing-n:127.0.0.1:65479_solr) [n:127.0.0.1:65479_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 540696 INFO (jetty-closer-511-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node [junit4] 2> 540696 INFO (jetty-closer-511-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@55e656d2{/solr,null,UNAVAILABLE} [junit4] 2> 541009 ERROR (qtp1752365093-3287) [n:127.0.0.1:65480_solr ] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: Solr is shutting down [junit4] 2> at org.apache.solr.handler.admin.PrepRecoveryOp.execute(PrepRecoveryOp.java:183) [junit4] 2> at org.apache.solr.handler.admin.CoreAdminOperation.execute(CoreAdminOperation.java:370) [junit4] 2> at org.apache.solr.handler.admin.CoreAdminHandler$CallInfo.call(CoreAdminHandler.java:388) [junit4] 2> at org.apache.solr.handler.admin.CoreAdminHandler.handleRequestBody(CoreAdminHandler.java:174) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:171) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:747) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:728) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:509) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:347) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:298) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462) [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) [junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:534) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320) [junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251) [junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95) [junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> [junit4] 2> 541010 INFO (qtp1752365093-3287) [n:127.0.0.1:65480_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={nodeName=127.0.0.1:65480_solr&onlyIfLeaderActive=true&core=addReplicaColl_shard1_replica1&coreNodeName=core_node7&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2} status=400 QTime=18098 [junit4] 2> 542194 WARN (zkCallback-530-thread-7-processing-n:127.0.0.1:65480_solr) [n:127.0.0.1:65480_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 542194 INFO (jetty-closer-511-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node [junit4] 2> 542194 INFO (jetty-closer-511-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6e04ac55{/solr,null,UNAVAILABLE} [junit4] 2> 542196 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[23F772886C475875]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:65477 65477 [junit4] 2> 542199 INFO (Thread-844) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:65477 65477 [junit4] 2> 544513 WARN (Thread-844) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 16 /solr/configs/conf [junit4] 2> 12 /solr/aliases.json [junit4] 2> 12 /solr/clusterprops.json [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 132 /solr/clusterstate.json [junit4] 2> 4 /solr/collections/nodes_used_collection/state.json [junit4] 2> 4 /solr/collections/addReplicaColl/state.json [junit4] 2> 4 /solr/collections/reloaded_collection/state.json [junit4] 2> 2 /solr/collections/onlyinzk/state.json [junit4] 2> 2 /solr/collections/addReplicaColl/leader_elect/shard1/election/97548211550945290-core_node1-n_0000000000 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 308 /solr/collections [junit4] 2> 93 /solr/overseer/queue [junit4] 2> 12 /solr/live_nodes [junit4] 2> 9 /solr/overseer/queue-work [junit4] 2> 8 /solr/overseer/collection-queue-work [junit4] 2> [junit4] 2> 544514 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[23F772886C475875]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_23F772886C475875-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {_version_=PostingsFormat(name=LuceneFixedGap), id=BlockTreeOrds(blocksize=128)}, docValues:{}, maxPointsInLeafNode=1697, maxMBSortInHeap=6.852568019911122, sim=RandomSimilarity(queryNorm=false): {}, locale=sv-SE, timezone=Pacific/Guadalcanal [junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_121 (64-bit)/cpus=3,threads=1,free=211820304,total=534773760 [junit4] 2> NOTE: All tests run in this JVM: [TestPushWriter, PreAnalyzedUpdateProcessorTest, TestReplicationHandlerBackup, TestObjectReleaseTracker, SolrMetricManagerTest, TestFieldCache, TestOmitPositions, AsyncCallRequestStatusResponseTest, TestNumericTerms64, CdcrReplicationHandlerTest, OverseerCollectionConfigSetProcessorTest, TestExactStatsCache, TestLuceneMatchVersion, CreateCollectionCleanupTest, ConjunctionSolrSpellCheckerTest, TestBadConfig, ReplicaListTransformerTest, DeleteNodeTest, BufferStoreTest, TestRangeQuery, TestRequestStatusCollectionAPI, TestZkChroot, TestReversedWildcardFilterFactory, TestLegacyFieldCache, GraphQueryTest, BadIndexSchemaTest, TestSolrQueryParser, TestTrackingShardHandlerFactory, V2ApiIntegrationTest, TestOnReconnectListenerSupport, TestBinaryResponseWriter, TestClassNameShortening, HdfsLockFactoryTest, TestLeaderInitiatedRecoveryThread, TestSolrIndexConfig, TestSolr4Spatial, HdfsRecoverLeaseTest, TestDocBasedVersionConstraints, TestSha256AuthenticationProvider, SolrMetricsIntegrationTest, ReturnFieldsTest, CleanupOldIndexTest, ExplicitHLLTest, TestDistributedMissingSort, PKIAuthenticationIntegrationTest, DistanceUnitsTest, TestNRTOpen, TestDynamicFieldResource, JsonLoaderTest, TestRestManager, BlockJoinFacetRandomTest, FullSolrCloudDistribCmdsTest, CloneFieldUpdateProcessorFactoryTest, DistributedFacetPivotLargeTest, CoreSorterTest, TestAtomicUpdateErrorCases, FacetPivotSmallTest, FieldAnalysisRequestHandlerTest, BooleanFieldTest, TestAnalyzedSuggestions, TestFieldCacheVsDocValues, ShowFileRequestHandlerTest, FieldMutatingUpdateProcessorTest, TestQuerySenderListener, TestXmlQParserPlugin, StatsComponentTest, TestFiltering, TestJmxMonitoredMap, DistributedFacetPivotWhiteBoxTest, CollectionsAPIDistributedZkTest] [junit4] Completed [117/693 (2!)] on J1 in 96.81s, 20 tests, 1 error <<< FAILURES! [...truncated 64512 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
