Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/779/
3 tests failed. REGRESSION: org.apache.solr.cloud.BasicDistributedZkTest.test Error Message: commitWithin did not work on node: http://127.0.0.1:41317/collection1 expected:<68> but was:<67> Stack Trace: java.lang.AssertionError: commitWithin did not work on node: http://127.0.0.1:41317/collection1 expected:<68> but was:<67> at __randomizedtesting.SeedInfo.seed([E6CC20B8B641C97A:6E981F6218BDA482]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.failNotEquals(Assert.java:647) at org.junit.Assert.assertEquals(Assert.java:128) at org.junit.Assert.assertEquals(Assert.java:472) at org.apache.solr.cloud.BasicDistributedZkTest.test(BasicDistributedZkTest.java:333) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:497) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:963) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) REGRESSION: org.apache.solr.search.function.SortByFunctionTest.test Error Message: Exception during query Stack Trace: java.lang.RuntimeException: Exception during query at __randomizedtesting.SeedInfo.seed([E6CC20B8B641C97A:6E981F6218BDA482]:0) at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:765) at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:732) at org.apache.solr.search.function.SortByFunctionTest.test(SortByFunctionTest.java:50) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:497) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//result/doc[1]/int[@name='id'][.='1'] xml response was: <?xml version="1.0" encoding="UTF-8"?> <response> <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="4" start="0" maxScore="1.0"><doc><int name="id">3</int><arr name="range_facet_l"><long>3</long></arr><arr name="range_facet_l_dv"><long>3</long></arr><arr name="range_facet_i_dv"><int>3</int></arr><double name="x_td1">3.0</double><double name="y_td1">2.0</double><double name="w_td1">55.0</double><double name="z_td1">5.0</double><arr name="f_t"><str>ipod ipod ipod ipod ipod ipod ipod ipod ipod</str></arr><long name="_version_">1510834205387390976</long><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2015-08-29T10:22:58.026Z</date><float name="score">1.0</float></doc><doc><int name="id">4</int><arr name="range_facet_l"><long>4</long></arr><arr name="range_facet_l_dv"><long>4</long></arr><arr name="range_facet_i_dv"><int>4</int></arr><double name="x_td1">4.0</double><double name="y_td1">2.0</double><double name="w_td1">45.0</double><double name="z_td1">5.0</double><arr name="f_t"><str>ipod ipod ipod ipod ipod ipod ipod</str></arr><long name="_version_">1510834205389488128</long><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2015-08-29T10:22:58.028Z</date><float name="score">1.0</float></doc><doc><int name="id">1</int><arr name="range_facet_l"><long>1</long></arr><arr name="range_facet_l_dv"><long>1</long></arr><arr name="range_facet_i_dv"><int>1</int></arr><double name="x_td1">0.0</double><double name="y_td1">2.0</double><double name="w_td1">25.0</double><double name="z_td1">5.0</double><arr name="f_t"><str>ipod</str></arr><long name="_version_">1510834205379002368</long><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2015-08-29T10:22:58.018Z</date><float name="score">1.0</float></doc><doc><int name="id">2</int><arr name="range_facet_l"><long>2</long></arr><arr name="range_facet_l_dv"><long>2</long></arr><arr name="range_facet_i_dv"><int>2</int></arr><double name="x_td1">2.0</double><double name="y_td1">2.0</double><double name="w_td1">15.0</double><double name="z_td1">5.0</double><arr name="f_t"><str>ipod ipod ipod ipod ipod</str></arr><long name="_version_">1510834205381099520</long><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2015-08-29T10:22:58.02Z</date><float name="score">1.0</float></doc></result> </response> request was:q=*:*&fl=*,score&wt=xml at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:758) ... 41 more FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=10648, name=collection4, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=10648, name=collection4, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:52583/_hp: Could not find collection : awholynewstresscollection_collection4_7 at __randomizedtesting.SeedInfo.seed([E6CC20B8B641C97A]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:857) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:800) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:895) Build Log: [...truncated 10254 lines...] [junit4] Suite: org.apache.solr.cloud.BasicDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/init-core-data-001 [junit4] 2> 1407971 INFO (SUITE-BasicDistributedZkTest-seed#[E6CC20B8B641C97A]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 1407975 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1407976 INFO (Thread-44127) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1407976 INFO (Thread-44127) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1408076 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.ZkTestServer start zk server on port:46640 [junit4] 2> 1408076 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1408077 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1408084 INFO (zkCallback-974-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2726332c name:ZooKeeperConnection Watcher:127.0.0.1:46640 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1408085 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1408085 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1408085 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1408088 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1408089 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1408091 INFO (zkCallback-975-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4d5e9a89 name:ZooKeeperConnection Watcher:127.0.0.1:46640/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1408091 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1408092 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1408092 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 1408097 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 1408099 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 1408101 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 1408105 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 1408105 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 1408111 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 1408111 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 1408114 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1408115 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1408125 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 1408125 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 1408128 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 1408128 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 1408132 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 1408132 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 1408137 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1408138 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 1408142 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 1408142 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 1408144 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1408144 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1408146 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1408146 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 1408148 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 1408148 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 1408506 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/control-001/cores/collection1 [junit4] 2> 1408509 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 1408518 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@568bc46a{/,null,AVAILABLE} [junit4] 2> 1408519 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.e.j.s.ServerConnector Started ServerConnector@844f1a0{HTTP/1.1}{127.0.0.1:34913} [junit4] 2> 1408519 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.e.j.s.Server Started @1412544ms [junit4] 2> 1408519 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/tempDir-001/control/data, hostContext=/, hostPort=34913, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/control-001/cores} [junit4] 2> 1408520 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1408521 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/control-001/' [junit4] 2> 1408535 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1408536 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1408538 INFO (zkCallback-976-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7e8b5a3f name:ZooKeeperConnection Watcher:127.0.0.1:46640/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1408538 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1408539 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1408542 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1408543 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/control-001/solr.xml [junit4] 2> 1408565 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/control-001/cores [junit4] 2> 1408565 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.CoreContainer New CoreContainer 1578693486 [junit4] 2> 1408565 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/control-001/] [junit4] 2> 1408566 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/control-001/lib [junit4] 2> 1408566 WARN (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/control-001/lib). [junit4] 2> 1408576 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 1408577 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1408578 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1408578 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1408578 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46640/solr [junit4] 2> 1408578 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1408578 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1408579 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1408580 INFO (zkCallback-978-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@79da5497 name:ZooKeeperConnection Watcher:127.0.0.1:46640 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1408581 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1408581 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1408586 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1408587 INFO (zkCallback-979-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6e547d47 name:ZooKeeperConnection Watcher:127.0.0.1:46640/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1408587 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1408589 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 1408591 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 1408595 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 1408597 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 1408599 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 1408601 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1408602 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1408603 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1408605 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1408606 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34913_ [junit4] 2> 1408606 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:34913_ [junit4] 2> 1408608 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 1408610 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 1408612 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1408613 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94427075404890116-127.0.0.1:34913_-n_0000000000 [junit4] 2> 1408614 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:34913_ [junit4] 2> 1408614 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 1408616 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.c.Overseer Overseer (id=94427075404890116-127.0.0.1:34913_-n_0000000000) starting [junit4] 2> 1408619 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 1408645 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 1408645 INFO (OverseerCollectionConfigSetProcessor-94427075404890116-127.0.0.1:34913_-n_0000000000) [n:127.0.0.1:34913_ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 1408646 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1408646 INFO (OverseerStateUpdate-94427075404890116-127.0.0.1:34913_-n_0000000000) [n:127.0.0.1:34913_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 1408662 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1408662 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1408663 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/control-001/cores [junit4] 2> 1408672 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/control-001/cores/collection1, collection=control_collection, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 1408673 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/control-001/cores/collection1/ [junit4] 2> 1408673 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 1408675 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1408675 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1408677 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1408700 INFO (OverseerStateUpdate-94427075404890116-127.0.0.1:34913_-n_0000000000) [n:127.0.0.1:34913_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:34913";, [junit4] 2> "node_name":"127.0.0.1:34913_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 1408701 INFO (OverseerStateUpdate-94427075404890116-127.0.0.1:34913_-n_0000000000) [n:127.0.0.1:34913_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:34913";, [junit4] 2> "node_name":"127.0.0.1:34913_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1408702 INFO (OverseerStateUpdate-94427075404890116-127.0.0.1:34913_-n_0000000000) [n:127.0.0.1:34913_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 1408703 INFO (OverseerStateUpdate-94427075404890116-127.0.0.1:34913_-n_0000000000) [n:127.0.0.1:34913_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 1408705 INFO (zkCallback-979-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 1408709 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2 [junit4] 2> 1408709 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:34913_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1409678 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1409678 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 1409679 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1409679 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 1409679 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1409680 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/control-001/cores/collection1/' [junit4] 2> 1409698 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1409703 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1409707 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1409716 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 1409717 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 1409724 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1409833 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1409838 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1409839 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1409852 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1409862 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1409864 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1409865 WARN (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1409865 WARN (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1409865 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1409866 WARN (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1409866 WARN (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1409867 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2 [junit4] 2> 1409867 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 1409869 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1409869 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 1409870 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@50f470bd [junit4] 2> 1409871 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/control-001/cores/collection1/data [junit4] 2> 1409871 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/control-001/cores/collection1/data/index/ [junit4] 2> 1409872 WARN (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1409872 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/control-001/cores/collection1/data/index [junit4] 2> 1409872 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=25, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5915262332208673] [junit4] 2> 1409873 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1ff0418a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@24b768f1),segFN=segments_1,generation=1} [junit4] 2> 1409873 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 1409874 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/control-001/cores/collection1/data/ [junit4] 2> 1409874 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 1409883 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1409883 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 1409884 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1409884 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1409885 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1409885 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1409885 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1409886 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1409886 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1409890 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1409891 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1409893 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1409894 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1409898 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 1409899 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 1409900 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1409900 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1409901 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 1409901 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 1409901 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=5, maxMergeAtOnceExplicit=2, maxMergedSegmentMB=92.3857421875, floorSegmentMB=1.65625, forceMergeDeletesPctAllowed=25.044409621378808, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.15547708972871244 [junit4] 2> 1409902 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1ff0418a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@24b768f1),segFN=segments_1,generation=1} [junit4] 2> 1409902 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 1409902 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@204ce458[collection1] main [junit4] 2> 1409902 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 1409904 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1409904 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 1409905 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1409905 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 1409905 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1409906 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 1409906 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1409906 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1409906 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1409907 INFO (searcherExecutor-2753-thread-1-processing-n:127.0.0.1:34913_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@204ce458[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1409907 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets [junit4] 2> 1409907 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index [junit4] 2> 1409907 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index [junit4] 2> 1409907 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1510833208500944896 [junit4] 2> 1409910 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with highest version 1510833208500944896 [junit4] 2> 1409910 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1409911 INFO (coreLoadExecutor-2752-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 1409911 INFO (coreZkRegister-2746-thread-1-processing-n:127.0.0.1:34913_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address:http://127.0.0.1:34913 collection:control_collection shard:shard1 [junit4] 2> 1409912 INFO (coreZkRegister-2746-thread-1-processing-n:127.0.0.1:34913_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 1409918 INFO (coreZkRegister-2746-thread-1-processing-n:127.0.0.1:34913_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.LeaderElector Joined leadership election with path: /collections/control_collection/leader_elect/shard1/election/94427075404890116-core_node1-n_0000000000 [junit4] 2> 1409920 INFO (coreZkRegister-2746-thread-1-processing-n:127.0.0.1:34913_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 1409923 INFO (coreZkRegister-2746-thread-1-processing-n:127.0.0.1:34913_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1409923 INFO (coreZkRegister-2746-thread-1-processing-n:127.0.0.1:34913_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:34913_ 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> 1409924 INFO (OverseerStateUpdate-94427075404890116-127.0.0.1:34913_-n_0000000000) [n:127.0.0.1:34913_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection"} current state version: 1 [junit4] 2> 1409924 INFO (coreZkRegister-2746-thread-1-processing-n:127.0.0.1:34913_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:34913/collection1/ [junit4] 2> 1409924 INFO (coreZkRegister-2746-thread-1-processing-n:127.0.0.1:34913_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1409924 INFO (coreZkRegister-2746-thread-1-processing-n:127.0.0.1:34913_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:34913/collection1/ has no replicas [junit4] 2> 1409924 INFO (coreZkRegister-2746-thread-1-processing-n:127.0.0.1:34913_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:34913_ 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:34913/collection1/ shard1 [junit4] 2> 1409925 INFO (coreZkRegister-2746-thread-1-processing-n:127.0.0.1:34913_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 1409933 INFO (OverseerStateUpdate-94427075404890116-127.0.0.1:34913_-n_0000000000) [n:127.0.0.1:34913_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:34913";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 1410035 INFO (zkCallback-979-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 1410081 INFO (coreZkRegister-2746-thread-1-processing-n:127.0.0.1:34913_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:34913/collection1/ and leader is http://127.0.0.1:34913/collection1/ [junit4] 2> 1410082 INFO (coreZkRegister-2746-thread-1-processing-n:127.0.0.1:34913_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:34913 [junit4] 2> 1410082 INFO (coreZkRegister-2746-thread-1-processing-n:127.0.0.1:34913_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1410082 INFO (coreZkRegister-2746-thread-1-processing-n:127.0.0.1:34913_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 1410082 INFO (coreZkRegister-2746-thread-1-processing-n:127.0.0.1:34913_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:34913_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1410085 INFO (OverseerStateUpdate-94427075404890116-127.0.0.1:34913_-n_0000000000) [n:127.0.0.1:34913_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:34913";, [junit4] 2> "node_name":"127.0.0.1:34913_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 1410085 INFO (OverseerStateUpdate-94427075404890116-127.0.0.1:34913_-n_0000000000) [n:127.0.0.1:34913_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:34913";, [junit4] 2> "node_name":"127.0.0.1:34913_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1410187 INFO (zkCallback-979-thread-2-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 1410211 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1410213 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1410218 INFO (zkCallback-981-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2b96fd74 name:ZooKeeperConnection Watcher:127.0.0.1:46640/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1410218 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1410219 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1410219 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1410225 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 1410562 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/shard-1-001/cores/collection1 [junit4] 2> 1410563 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/shard-1-001 [junit4] 2> 1410566 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 1410582 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3f02d040{/,null,AVAILABLE} [junit4] 2> 1410583 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.e.j.s.ServerConnector Started ServerConnector@63805a44{HTTP/1.1}{127.0.0.1:41317} [junit4] 2> 1410583 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.e.j.s.Server Started @1414608ms [junit4] 2> 1410583 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=41317, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/shard-1-001/cores} [junit4] 2> 1410583 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1410584 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/shard-1-001/' [junit4] 2> 1410600 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1410601 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1410601 INFO (zkCallback-982-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3f70fa27 name:ZooKeeperConnection Watcher:127.0.0.1:46640/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1410602 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1410602 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1410603 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1410603 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/shard-1-001/solr.xml [junit4] 2> 1410622 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/shard-1-001/cores [junit4] 2> 1410623 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.CoreContainer New CoreContainer 443654638 [junit4] 2> 1410623 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/shard-1-001/] [junit4] 2> 1410623 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/shard-1-001/lib [junit4] 2> 1410623 WARN (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/shard-1-001/lib). [junit4] 2> 1410631 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 1410633 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1410633 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1410633 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1410633 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46640/solr [junit4] 2> 1410634 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1410635 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1410636 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1410637 INFO (zkCallback-984-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@79b72c95 name:ZooKeeperConnection Watcher:127.0.0.1:46640 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1410639 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1410639 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1410643 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:41317_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1410647 INFO (zkCallback-985-thread-1-processing-n:127.0.0.1:41317_) [n:127.0.0.1:41317_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5c3ef054 name:ZooKeeperConnection Watcher:127.0.0.1:46640/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1410647 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:41317_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1410655 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:41317_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1411662 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:41317_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41317_ [junit4] 2> 1411662 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:41317_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:41317_ [junit4] 2> 1411663 INFO (zkCallback-981-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1) [junit4] 2> 1411664 INFO (zkCallback-985-thread-1-processing-n:127.0.0.1:41317_) [n:127.0.0.1:41317_ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1) [junit4] 2> 1411663 INFO (zkCallback-979-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1) [junit4] 2> 1411666 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:41317_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1411667 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:41317_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94427075404890120-127.0.0.1:41317_-n_0000000001 [junit4] 2> 1411667 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:41317_ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/94427075404890116-127.0.0.1:34913_-n_0000000000 to know if I could be the leader [junit4] 2> 1411679 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:41317_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1411679 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:41317_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1411680 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:41317_ ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/shard-1-001/cores [junit4] 2> 1411681 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:41317_ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/shard-1-001/cores/collection1, collection=collection1, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/shard-1-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 1411681 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:41317_ ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/shard-1-001/cores/collection1/ [junit4] 2> 1411681 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:41317_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 1411684 INFO (coreLoadExecutor-2763-thread-1-processing-n:127.0.0.1:41317_) [n:127.0.0.1:41317_ c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1411684 INFO (coreLoadExecutor-2763-thread-1-processing-n:127.0.0.1:41317_) [n:127.0.0.1:41317_ c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1411685 INFO (coreLoadExecutor-2763-thread-1-processing-n:127.0.0.1:41317_) [n:127.0.0.1:41317_ c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1411685 INFO (OverseerStateUpdate-94427075404890116-127.0.0.1:34913_-n_0000000000) [n:127.0.0.1:34913_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:41317";, [junit4] 2> "node_name":"127.0.0.1:41317_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 1411687 INFO (OverseerStateUpdate-94427075404890116-127.0.0.1:34913_-n_0000000000) [n:127.0.0.1:34913_ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:41317";, [junit4] 2> "node_name":"127.0.0.1:41317_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 1411687 INFO (OverseerStateUpdate-94427075404890116-127.0.0.1:34913_-n_0000000000) [n:127.0.0.1:34913_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 1411687 INFO (OverseerStateUpdate-94427075404890116-127.0.0.1:34913_-n_0000000000) [n:127.0.0.1:34913_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 1411691 INFO (zkCallback-981-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 1411691 INFO (zkCallback-979-thread-1-processing-n:127.0.0.1:34913_) [n:127.0.0.1:34913_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 1411692 INFO (zkCallback-985-thread-1-processing-n:127.0.0.1:41317_) [n:127.0.0.1:41317_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 1411704 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:41317_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2 [junit4] 2> 1411704 INFO (TEST-BasicDistributedZkTest.test-seed#[E6CC20B8B641C97A]) [n:127.0.0.1:41317_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1412686 INFO (coreLoadExecutor-2763-thread-1-processing-n:127.0.0.1:41317_) [n:127.0.0.1:41317_ c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1412687 INFO (coreLoadExecutor-2763-thread-1-processing-n:127.0.0.1:41317_) [n:127.0.0.1:41317_ c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1 [junit4] 2> 1412687 INFO (coreLoadExecutor-2763-thread-1-processing-n:127.0.0.1:41317_) [n:127.0.0.1:41317_ c:collection1 x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1412687 INFO (coreLoadExecutor-2763-thread-1-processing-n:127.0.0.1:41317_) [n:127.0.0.1:41317_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/collection1 [junit4] 2> 1412688 INFO (coreLoadExecutor-2763-thread-1-processing-n:127.0.0.1:41317_) [n:127.0.0.1:41317_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1412688 INFO (coreLoadExecutor-2763-thread-1-processing-n:127.0.0.1:41317_) [n:127.0.0.1:41317_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_E6CC20B8B641C97A-001/shard-1-001/cores/collection1/' [junit4] 2> 1412708 INFO (coreLoadExecutor-2763-thread-1-processing-n:127.0.0.1:41317_) [n:127.0.0.1:41317_ c:collection1 x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1412712 INFO (coreLoadExecutor-2763-thread-1-processing-n:127.0.0.1:41317_) [n:127.0.0.1:41317_ c:collection1 x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1412716 INFO (coreLoadExecutor-2763-thread-1-processing-n:127.0.0.1:41317_) [n:127.0.0.1:41317_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1412724 INFO (coreLoadExecutor-2763-thread-1-processing-n:127.0.0.1:41317_) [n:127.0.0.1:41317_ c:collection1 x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 1412725 INFO (coreLoadExecutor-2763-thread-1-processing-n:127.0.0.1:41317_) [n:127.0.0.1:41317_ c:collection1 x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 1412730 INFO (coreLoadExecutor-2763-thread-1-processing-n:127.0.0.1:41317_) [n:127.0.0.1:41317_ c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name [...truncated too long message...] tionTest.testSortJoinDocFreq-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.SolrCore newest commit generation = 7 [junit4] 2> 2361362 INFO (TEST-SortByFunctionTest.testSortJoinDocFreq-seed#[E6CC20B8B641C97A]) [ ] o.a.s.s.SolrIndexSearcher Opening Searcher@67c96639[collection1] main [junit4] 2> 2361363 INFO (searcherExecutor-6765-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@67c96639[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_a(6.0.0):C4)))} [junit4] 2> 2361364 INFO (TEST-SortByFunctionTest.testSortJoinDocFreq-seed#[E6CC20B8B641C97A]) [ ] o.a.s.u.UpdateHandler end_commit_flush [junit4] 2> 2361365 INFO (TEST-SortByFunctionTest.testSortJoinDocFreq-seed#[E6CC20B8B641C97A]) [ ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {commit=} 0 11 [junit4] 2> 2361366 INFO (TEST-SortByFunctionTest.testSortJoinDocFreq-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=links_mfacet:B&fl=id&sort=id+asc&wt=xml} hits=2 status=0 QTime=0 [junit4] 2> 2361367 INFO (TEST-SortByFunctionTest.testSortJoinDocFreq-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=id&sort=joindf(id_s1,+links_mfacet)+desc&wt=xml} hits=4 status=0 QTime=0 [junit4] 2> 2361369 INFO (TEST-SortByFunctionTest.testSortJoinDocFreq-seed#[E6CC20B8B641C97A]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=id&sort=joindf(id_s1,+links_mfacet)+asc&wt=xml} hits=4 status=0 QTime=0 [junit4] 2> 2361369 INFO (TEST-SortByFunctionTest.testSortJoinDocFreq-seed#[E6CC20B8B641C97A]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSortJoinDocFreq [junit4] 2> 2361370 INFO (SUITE-SortByFunctionTest-seed#[E6CC20B8B641C97A]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> 2361370 INFO (SUITE-SortByFunctionTest-seed#[E6CC20B8B641C97A]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=974497431 [junit4] 2> 2361370 INFO (SUITE-SortByFunctionTest-seed#[E6CC20B8B641C97A]-worker) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@57528867 [junit4] 2> 2361371 INFO (SUITE-SortByFunctionTest-seed#[E6CC20B8B641C97A]-worker) [ x:collection1] o.a.s.u.UpdateHandler closing DirectUpdateHandler2{commits=7,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=17,cumulative_deletesById=0,cumulative_deletesByQuery=3,cumulative_errors=0,transaction_logs_total_size=1943,transaction_logs_total_number=7} [junit4] 2> 2361371 INFO (SUITE-SortByFunctionTest-seed#[E6CC20B8B641C97A]-worker) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState [junit4] 2> 2361371 INFO (SUITE-SortByFunctionTest-seed#[E6CC20B8B641C97A]-worker) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter [junit4] 2> 2361371 INFO (SUITE-SortByFunctionTest-seed#[E6CC20B8B641C97A]-worker) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser [junit4] 2> 2361372 INFO (SUITE-SortByFunctionTest-seed#[E6CC20B8B641C97A]-worker) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request. [junit4] 2> 2361387 INFO (SUITE-SortByFunctionTest-seed#[E6CC20B8B641C97A]-worker) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked [junit4] 2> 2361387 INFO (SUITE-SortByFunctionTest-seed#[E6CC20B8B641C97A]-worker) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.function.SortByFunctionTest_E6CC20B8B641C97A-001/init-core-data-001/index [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.function.SortByFunctionTest_E6CC20B8B641C97A-001/init-core-data-001/index;done=false>>] [junit4] 2> 2361387 INFO (SUITE-SortByFunctionTest-seed#[E6CC20B8B641C97A]-worker) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.function.SortByFunctionTest_E6CC20B8B641C97A-001/init-core-data-001/index [junit4] 2> 2361388 INFO (SUITE-SortByFunctionTest-seed#[E6CC20B8B641C97A]-worker) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.function.SortByFunctionTest_E6CC20B8B641C97A-001/init-core-data-001 [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.function.SortByFunctionTest_E6CC20B8B641C97A-001/init-core-data-001;done=false>>] [junit4] 2> 2361388 INFO (SUITE-SortByFunctionTest-seed#[E6CC20B8B641C97A]-worker) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.function.SortByFunctionTest_E6CC20B8B641C97A-001/init-core-data-001 [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.function.SortByFunctionTest_E6CC20B8B641C97A-001 [junit4] 2> NOTE: test params are: codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=19697, maxDocsPerChunk=10, blockSize=3), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=19697, blockSize=3)), sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=hu, timezone=Canada/Pacific [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_45 (64-bit)/cpus=4,threads=1,free=114476208,total=526385152 [junit4] 2> NOTE: All tests run in this JVM: [TestCloudSchemaless, TestQueryTypes, TestDynamicLoading, TestDFRSimilarityFactory, SpellCheckCollatorTest, CleanupOldIndexTest, BitVectorTest, TestCloudPivotFacet, CSVRequestHandlerTest, SolrIndexSplitterTest, TestAuthenticationFramework, TestSolrDeletionPolicy2, AtomicUpdatesTest, TestCustomSort, SpatialHeatmapFacetsTest, ClusterStateUpdateTest, TestSchemaNameResource, TestAnalyzeInfixSuggestions, DOMUtilTest, TestFiltering, SolrCmdDistributorTest, PreAnalyzedUpdateProcessorTest, SSLMigrationTest, TestCopyFieldCollectionResource, TriLevelCompositeIdRoutingTest, TestRebalanceLeaders, DistributedTermsComponentTest, DocValuesTest, TestJettySolrRunner, LoggingHandlerTest, HdfsNNFailoverTest, TermsComponentTest, TestSuggestSpellingConverter, SolrIndexConfigTest, TestRequestStatusCollectionAPI, TestCoreContainer, DistributedVersionInfoTest, HighlighterTest, TestSolrCoreProperties, TestSQLHandler, DistributedFacetPivotLongTailTest, TestTrie, DistributedQueueTest, SolrPluginUtilsTest, SliceStateTest, CircularListTest, SolrCloudExampleTest, TestCodecSupport, TestLMJelinekMercerSimilarityFactory, BJQParserTest, OpenCloseCoreStressTest, SuggesterFSTTest, TestUtils, ShardRoutingTest, BasicFunctionalityTest, SpatialFilterTest, NumericFieldsTest, UniqFieldsUpdateProcessorFactoryTest, DistribJoinFromCollectionTest, SimplePostToolTest, NotRequiredUniqueKeyTest, RulesTest, ChangedSchemaMergeTest, TestCrossCoreJoin, DateRangeFieldTest, TestManagedSchema, QueryResultKeyTest, ChaosMonkeySafeLeaderTest, TestRTGBase, TestDistribDocBasedVersion, MoreLikeThisHandlerTest, AnalysisErrorHandlingTest, SolrInfoMBeanTest, TestQueryUtils, TestJmxMonitoredMap, CollectionsAPIDistributedZkTest, ConvertedLegacyTest, MergeStrategyTest, TestRestoreCore, TestChildDocTransformer, DistribDocExpirationUpdateProcessorTest, TestTolerantSearch, DistanceFunctionTest, TestNRTOpen, DocValuesMissingTest, FastVectorHighlighterTest, DistributedSpellCheckComponentTest, ZkControllerTest, TestRealTimeGet, TestStressVersions, SpellCheckComponentTest, TestFunctionQuery, TestLazyCores, SoftAutoCommitTest, TestStressLucene, SchemaVersionSpecificBehaviorTest, FieldMutatingUpdateProcessorTest, QueryEqualityTest, DefaultValueUpdateProcessorTest, SortByFunctionTest] [junit4] Completed [304/543] on J1 in 0.76s, 3 tests, 1 error <<< FAILURES! [...truncated 782 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:782: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:719: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:514: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1432: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:987: There were test failures: 543 suites, 2145 tests, 2 errors, 1 failure, 29 ignored (17 assumptions) Total time: 176 minutes 22 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts No prior successful build to compare, so performing full copy of artifacts Recording test results Email was triggered for: Failure Sending email for trigger: Failure
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
