Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/843/
1 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Error from server at http://127.0.0.1:37527: Could not load collection from ZK:awholynewstresscollection_collection0_1 Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:37527: Could not load collection from ZK:awholynewstresscollection_collection0_1 at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:575) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.makeRequest(CollectionsAPIDistributedZkTest.java:302) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.deletePartiallyCreatedCollection(CollectionsAPIDistributedZkTest.java:224) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:171) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:497) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1660) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:866) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:902) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:916) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:963) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:875) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:777) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:811) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:822) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 10981 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/init-core-data-001 [junit4] 2> 1930033 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[1BF2BDFCDF031AD4]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 1930033 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[1BF2BDFCDF031AD4]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 1930036 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1930036 INFO (Thread-72068) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1930036 INFO (Thread-72068) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1930136 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.ZkTestServer start zk server on port:38955 [junit4] 2> 1930136 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1930138 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1930140 INFO (zkCallback-1163-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6f601586 name:ZooKeeperConnection Watcher:127.0.0.1:38955 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1930141 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1930141 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1930141 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1930145 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1930145 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1930147 INFO (zkCallback-1164-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@eaecdaf name:ZooKeeperConnection Watcher:127.0.0.1:38955/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1930147 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1930147 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1930147 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 1930150 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 1930152 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 1930154 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 1930156 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] 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> 1930156 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 1930159 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] 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> 1930160 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 1930162 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] 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> 1930163 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1930165 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] 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> 1930165 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 1930167 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] 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> 1930167 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 1930171 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] 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> 1930172 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 1930174 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] 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> 1930174 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 1930176 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] 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> 1930177 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 1930179 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] 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> 1930179 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1930181 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] 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> 1930182 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 1930184 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] 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> 1930184 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 1930187 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1930187 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1930189 INFO (zkCallback-1165-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3cd01eb7 name:ZooKeeperConnection Watcher:127.0.0.1:38955 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1930189 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1930189 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1930189 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1930191 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1930192 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1930193 INFO (zkCallback-1166-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3986c57c name:ZooKeeperConnection Watcher:127.0.0.1:38955/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1930193 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1930193 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1930194 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml [junit4] 2> 1930194 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml [junit4] 2> 1930197 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml [junit4] 2> 1930197 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml [junit4] 2> 1930204 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml [junit4] 2> 1930204 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml [junit4] 2> 1930206 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1930206 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1930208 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt [junit4] 2> 1930208 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt [junit4] 2> 1930210 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt [junit4] 2> 1930210 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt [junit4] 2> 1930213 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml [junit4] 2> 1930213 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml [junit4] 2> 1930215 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json [junit4] 2> 1930215 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json [junit4] 2> 1930217 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 1930217 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 1930219 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt [junit4] 2> 1930219 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt [junit4] 2> 1930222 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt [junit4] 2> 1930222 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt [junit4] 2> 1930224 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml [junit4] 2> 1930225 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml [junit4] 2> 1930587 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/control-001/cores/collection1 [junit4] 2> 1930588 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 1930590 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@73ea4128{/,null,AVAILABLE} [junit4] 2> 1930591 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.e.j.s.ServerConnector Started ServerConnector@29249fbf{HTTP/1.1}{127.0.0.1:35673} [junit4] 2> 1930591 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.e.j.s.Server Started @1933620ms [junit4] 2> 1930591 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/tempDir-001/control/data, hostContext=/, hostPort=35673, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/control-001/cores} [junit4] 2> 1930591 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1930591 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/control-001/' [junit4] 2> 1930591 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1930591 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1930608 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1930608 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1930609 INFO (zkCallback-1167-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@289dc7ca name:ZooKeeperConnection Watcher:127.0.0.1:38955/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1930610 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1930610 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1930611 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1930611 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/control-001/solr.xml [junit4] 2> 1930616 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/control-001/cores [junit4] 2> 1930616 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.CoreContainer New CoreContainer 349906879 [junit4] 2> 1930616 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/control-001/] [junit4] 2> 1930616 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/control-001/lib [junit4] 2> 1930616 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/control-001/lib). [junit4] 2> 1930624 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] 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> 1930625 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1930626 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1930626 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1930626 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38955/solr [junit4] 2> 1930626 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1930626 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1930626 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1930627 INFO (zkCallback-1169-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@8160adb name:ZooKeeperConnection Watcher:127.0.0.1:38955 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1930628 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1930629 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1930630 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1930631 INFO (zkCallback-1170-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3ed1f192 name:ZooKeeperConnection Watcher:127.0.0.1:38955/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1930631 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1930632 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 1930634 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 1930637 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 1930639 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 1930641 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 1930643 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1930644 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1930645 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1930646 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1930647 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35673_ [junit4] 2> 1930648 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:35673_ [junit4] 2> 1930649 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 1930650 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 1930651 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1930652 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94812943434252294-127.0.0.1:35673_-n_0000000000 [junit4] 2> 1930652 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:35673_ [junit4] 2> 1930652 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 1930653 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] o.a.s.c.Overseer Overseer (id=94812943434252294-127.0.0.1:35673_-n_0000000000) starting [junit4] 2> 1930655 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 1930660 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 1930661 INFO (OverseerCollectionConfigSetProcessor-94812943434252294-127.0.0.1:35673_-n_0000000000) [n:127.0.0.1:35673_ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 1930661 INFO (OverseerStateUpdate-94812943434252294-127.0.0.1:35673_-n_0000000000) [n:127.0.0.1:35673_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 1930661 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1930678 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1930678 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1930679 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/control-001/cores [junit4] 2> 1930679 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 1930679 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/control-001/cores/collection1/ [junit4] 2> 1930680 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 1930681 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2 [junit4] 2> 1930681 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:35673_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1930681 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1930681 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1930683 INFO (OverseerStateUpdate-94812943434252294-127.0.0.1:35673_-n_0000000000) [n:127.0.0.1:35673_ ] 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:35673";, [junit4] 2> "node_name":"127.0.0.1:35673_", [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> 1930683 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1930683 INFO (OverseerStateUpdate-94812943434252294-127.0.0.1:35673_-n_0000000000) [n:127.0.0.1:35673_ ] 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:35673";, [junit4] 2> "node_name":"127.0.0.1:35673_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1930683 INFO (OverseerStateUpdate-94812943434252294-127.0.0.1:35673_-n_0000000000) [n:127.0.0.1:35673_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 1930684 INFO (OverseerStateUpdate-94812943434252294-127.0.0.1:35673_-n_0000000000) [n:127.0.0.1:35673_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 1930685 INFO (zkCallback-1170-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ ] 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> 1931683 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1931683 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 1931685 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1931685 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 1931686 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1931686 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/control-001/cores/collection1/' [junit4] 2> 1931686 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1931686 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1931702 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1931707 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1931711 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1931720 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 1931736 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 1931742 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1931834 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1931841 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1931842 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1931854 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1931856 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1931858 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1931859 WARN (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1931859 WARN (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1931859 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1931860 WARN (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1931860 WARN (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1931861 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ 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> 1931861 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 1931861 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1931861 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 1931861 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ 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@7384cbcf [junit4] 2> 1931862 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/control-001/cores/collection1/data [junit4] 2> 1931862 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/control-001/cores/collection1/data/index/ [junit4] 2> 1931862 WARN (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1931862 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/control-001/cores/collection1/data/index [junit4] 2> 1931862 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@4ad5e086 [junit4] 2> 1931863 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@7e83448 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1358f903; maxCacheMB=0.4296875 maxMergeSizeMB=0.9970703125)),segFN=segments_1,generation=1} [junit4] 2> 1931863 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 1931872 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:35673_ 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/control-001/cores/collection1/data/ [junit4] 2> 1931873 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:35673_ 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 1931876 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1931877 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 1931877 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1931877 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1931877 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1931877 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ 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> 1931877 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ 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> 1931877 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ 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> 1931877 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ 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> 1931878 WARN (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}} [junit4] 2> 1931879 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1931880 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1931881 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1931882 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1931885 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/dump,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 1931886 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ 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> 1931886 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ 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> 1931886 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ 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> 1931887 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 1931887 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 1931887 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ 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=44, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=46.5, floorSegmentMB=0.4453125, forceMergeDeletesPctAllowed=20.74294267231492, segmentsPerTier=32.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 1931888 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@7e83448 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1358f903; maxCacheMB=0.4296875 maxMergeSizeMB=0.9970703125)),segFN=segments_1,generation=1} [junit4] 2> 1931888 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 1931888 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@6bb182f9[collection1] main [junit4] 2> 1931888 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ 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> 1931889 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ 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> 1931889 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ 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> 1931890 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1931890 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 1931890 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ 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> 1931890 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ 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> 1931890 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ 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> 1931890 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1931890 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1931891 INFO (searcherExecutor-4181-thread-1-processing-n:127.0.0.1:35673_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6bb182f9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1931891 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ 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> 1931891 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ 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> 1931891 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ 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> 1931891 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ 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 1517007096892096512 [junit4] 2> 1931894 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ 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 1517007096892096512 [junit4] 2> 1931894 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1931894 INFO (coreLoadExecutor-4180-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 1931895 INFO (coreZkRegister-4174-thread-1-processing-n:127.0.0.1:35673_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35673_ 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:35673 collection:control_collection shard:shard1 [junit4] 2> 1931896 INFO (coreZkRegister-4174-thread-1-processing-n:127.0.0.1:35673_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35673_ 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> 1931900 INFO (coreZkRegister-4174-thread-1-processing-n:127.0.0.1:35673_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35673_ 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/94812943434252294-core_node1-n_0000000000 [junit4] 2> 1931900 INFO (coreZkRegister-4174-thread-1-processing-n:127.0.0.1:35673_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 1931902 INFO (coreZkRegister-4174-thread-1-processing-n:127.0.0.1:35673_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1931902 INFO (coreZkRegister-4174-thread-1-processing-n:127.0.0.1:35673_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35673_ 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> 1931902 INFO (coreZkRegister-4174-thread-1-processing-n:127.0.0.1:35673_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:35673/collection1/ [junit4] 2> 1931902 INFO (coreZkRegister-4174-thread-1-processing-n:127.0.0.1:35673_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1931902 INFO (coreZkRegister-4174-thread-1-processing-n:127.0.0.1:35673_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:35673/collection1/ has no replicas [junit4] 2> 1931902 INFO (OverseerStateUpdate-94812943434252294-127.0.0.1:35673_-n_0000000000) [n:127.0.0.1:35673_ ] 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> 1931902 INFO (coreZkRegister-4174-thread-1-processing-n:127.0.0.1:35673_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35673_ 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:35673/collection1/ shard1 [junit4] 2> 1931903 INFO (coreZkRegister-4174-thread-1-processing-n:127.0.0.1:35673_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 1931905 INFO (coreZkRegister-4174-thread-1-processing-n:127.0.0.1:35673_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node [junit4] 2> 1931908 INFO (OverseerStateUpdate-94812943434252294-127.0.0.1:35673_-n_0000000000) [n:127.0.0.1:35673_ ] 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:35673";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 1932011 INFO (zkCallback-1170-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ ] 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> 1932059 INFO (coreZkRegister-4174-thread-1-processing-n:127.0.0.1:35673_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:35673/collection1/ and leader is http://127.0.0.1:35673/collection1/ [junit4] 2> 1932059 INFO (coreZkRegister-4174-thread-1-processing-n:127.0.0.1:35673_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35673_ 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:35673 [junit4] 2> 1932059 INFO (coreZkRegister-4174-thread-1-processing-n:127.0.0.1:35673_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1932059 INFO (coreZkRegister-4174-thread-1-processing-n:127.0.0.1:35673_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35673_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 1932059 INFO (coreZkRegister-4174-thread-1-processing-n:127.0.0.1:35673_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35673_ 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> 1932062 INFO (OverseerStateUpdate-94812943434252294-127.0.0.1:35673_-n_0000000000) [n:127.0.0.1:35673_ ] 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:35673";, [junit4] 2> "node_name":"127.0.0.1:35673_", [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> 1932063 INFO (OverseerStateUpdate-94812943434252294-127.0.0.1:35673_-n_0000000000) [n:127.0.0.1:35673_ ] 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:35673";, [junit4] 2> "node_name":"127.0.0.1:35673_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1932166 INFO (zkCallback-1170-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ ] 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> 1932183 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1932184 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1932185 INFO (zkCallback-1172-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6cd528d4 name:ZooKeeperConnection Watcher:127.0.0.1:38955/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1932185 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1932186 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1932186 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1932188 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 1932189 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 1932189 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1932189 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1932190 INFO (zkCallback-1173-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@788b0c1c name:ZooKeeperConnection Watcher:127.0.0.1:38955/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1932190 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1932191 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1932196 INFO (OverseerStateUpdate-94812943434252294-127.0.0.1:35673_-n_0000000000) [n:127.0.0.1:35673_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "stateFormat":"2"} current state version: 3 [junit4] 2> 1932196 INFO (OverseerStateUpdate-94812943434252294-127.0.0.1:35673_-n_0000000000) [n:127.0.0.1:35673_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 1932299 INFO (OverseerStateUpdate-94812943434252294-127.0.0.1:35673_-n_0000000000) [n:127.0.0.1:35673_ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json [junit4] 2> 1932549 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/shard-1-001/cores/collection1 [junit4] 2> 1932550 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/shard-1-001 [junit4] 2> 1932551 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 1932553 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4fab8f14{/,null,AVAILABLE} [junit4] 2> 1932553 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.e.j.s.ServerConnector Started ServerConnector@4c18ff0{HTTP/1.1}{127.0.0.1:37527} [junit4] 2> 1932553 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.e.j.s.Server Started @1935582ms [junit4] 2> 1932553 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=37527, 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/shard-1-001/cores} [junit4] 2> 1932554 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1932554 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/shard-1-001/' [junit4] 2> 1932554 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1932554 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1932571 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1932571 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1932573 INFO (zkCallback-1174-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1f333eba name:ZooKeeperConnection Watcher:127.0.0.1:38955/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1932573 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1932573 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1932574 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1932574 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/shard-1-001/solr.xml [junit4] 2> 1932579 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/shard-1-001/cores [junit4] 2> 1932579 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.CoreContainer New CoreContainer 1802325905 [junit4] 2> 1932579 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/shard-1-001/] [junit4] 2> 1932580 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/shard-1-001/lib [junit4] 2> 1932580 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/shard-1-001/lib). [junit4] 2> 1932587 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] 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> 1932588 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1932589 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1932589 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1932589 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38955/solr [junit4] 2> 1932589 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1932589 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1932590 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1932591 INFO (zkCallback-1176-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6ed2fe40 name:ZooKeeperConnection Watcher:127.0.0.1:38955 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1932592 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1932592 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1932594 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:37527_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1932595 INFO (zkCallback-1177-thread-1-processing-n:127.0.0.1:37527_) [n:127.0.0.1:37527_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1829b721 name:ZooKeeperConnection Watcher:127.0.0.1:38955/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1932595 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:37527_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1932601 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:37527_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1933609 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:37527_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37527_ [junit4] 2> 1933609 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:37527_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:37527_ [junit4] 2> 1933610 INFO (zkCallback-1177-thread-1-processing-n:127.0.0.1:37527_) [n:127.0.0.1:37527_ ] 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> 1933610 INFO (zkCallback-1172-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> 1933610 INFO (zkCallback-1170-thread-1-processing-n:127.0.0.1:35673_) [n:127.0.0.1:35673_ ] 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> 1933612 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:37527_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1933612 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:37527_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94812943434252299-127.0.0.1:37527_-n_0000000001 [junit4] 2> 1933613 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:37527_ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/94812943434252294-127.0.0.1:35673_-n_0000000000 to know if I could be the leader [junit4] 2> 1933629 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:37527_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1933629 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:37527_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1933630 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:37527_ ] 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/shard-1-001/cores [junit4] 2> 1933631 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:37527_ ] 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/shard-1-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 1933631 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:37527_ ] 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.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001/shard-1-001/cores/collection1/ [junit4] 2> 1933631 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:37527_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 1933632 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:37527_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2 [junit4] 2> 1933632 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1BF2BDFCDF031AD4]) [n:127.0.0.1:37527_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1933633 INFO (coreLoadExecutor-4191-thread-1-processing-n:127.0.0.1:37527_) [n:127.0.0.1:37527_ c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1933633 INFO (coreLoadExecutor-4191-thread-1-processing-n:127.0.0.1:37527_) [n:127.0.0.1:37527_ c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1933634 INFO (coreLoadExecutor-4191-thread-1-processing-n:127.0.0.1:37527_) [n:127.0.0.1:37527_ c:collection1 x:collection1] o.a.s.c.ZkController Registering watch for collection collection1 [j [...truncated too long message...] rotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=78971, name=collection4, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] [junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:35673: collection already exists: awholynewstresscollection_collection4_1 [junit4] > at __randomizedtesting.SeedInfo.seed([1BF2BDFCDF031AD4]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:575) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806) [junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1574) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1595) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:888)Throwable #7: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=78967, name=collection0, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] [junit4] > Caused by: java.lang.RuntimeException: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:45949, http://127.0.0.1:35673, http://127.0.0.1:37527, http://127.0.0.1:50542, http://127.0.0.1:59656] [junit4] > at __randomizedtesting.SeedInfo.seed([1BF2BDFCDF031AD4]:0) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:898) [junit4] > Caused by: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:45949, http://127.0.0.1:35673, http://127.0.0.1:37527, http://127.0.0.1:50542, http://127.0.0.1:59656] [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:352) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806) [junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1574) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1595) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:888) [junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:50542: KeeperErrorCode = Session expired for /overseer/collection-queue-work/qnr- [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:575) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) [junit4] > ... 7 more [junit4] 2> 3031618 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[1BF2BDFCDF031AD4]-worker) [n:127.0.0.1:45949_ c:awholynewstresscollection_collection5_1 s:shard4 r:core_node12 x:awholynewstresscollection_collection5_1_shard4_replica2] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_1BF2BDFCDF031AD4-001 [junit4] 2> Nov 05, 2015 1:56:45 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene60): {other_tl1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), range_facet_l_dv=PostingsFormat(name=LuceneFixedGap), _version_=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), multiDefault=FSTOrd50, a_t=FSTOrd50, intDefault=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), a_i1=FSTOrd50, id=PostingsFormat(name=LuceneFixedGap), range_facet_i_dv=FSTOrd50, text=Lucene50(blocksize=128), range_facet_l=FSTOrd50, timestamp=FSTOrd50}, docValues:{range_facet_l_dv=DocValuesFormat(name=Memory), range_facet_i_dv=DocValuesFormat(name=Direct), timestamp=DocValuesFormat(name=Direct)}, sim=ClassicSimilarity, locale=fr, timezone=Canada/Mountain [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_45 (64-bit)/cpus=4,threads=1,free=189080824,total=501219328 [junit4] 2> NOTE: All tests run in this JVM: [SolrTestCaseJ4Test, StatsComponentTest, TestBulkSchemaConcurrent, OverseerTest, PluginInfoTest, BlockCacheTest, TestStressRecovery, DeleteLastCustomShardedReplicaTest, DeleteShardTest, TestReRankQParserPlugin, HdfsDirectoryTest, TestJmxIntegration, TestManagedStopFilterFactory, RegexBoostProcessorTest, TestStressReorder, TestPerFieldSimilarity, SimpleFacetsTest, TestFieldSortValues, AnalyticsMergeStrategyTest, DebugComponentTest, BaseCdcrDistributedZkTest, TestDynamicFieldCollectionResource, TestElisionMultitermQuery, HdfsNNFailoverTest, TestPostingsSolrHighlighter, DocValuesMultiTest, OverriddenZkACLAndCredentialsProvidersTest, TestSolrCLIRunExample, TestEmbeddedSolrServerConstructors, TestOrdValues, NoCacheHeaderTest, TestWriterPerf, AddBlockUpdateTest, TestReversedWildcardFilterFactory, TriLevelCompositeIdRoutingTest, TestExactSharedStatsCache, TestSolrDeletionPolicy1, DistributedFacetPivotSmallAdvancedTest, DirectUpdateHandlerOptimizeTest, CloudExitableDirectoryReaderTest, CursorMarkTest, TestSha256AuthenticationProvider, TestFuzzyAnalyzedSuggestions, FieldMutatingUpdateProcessorTest, JavabinLoaderTest, TestRecovery, CoreAdminHandlerTest, TestIndexingPerformance, TestSystemIdResolver, TestHashQParserPlugin, TestCustomSort, TestReplicationHandlerBackup, DeleteReplicaTest, StressHdfsTest, MultiThreadedOCPTest, BinaryUpdateRequestHandlerTest, TestMinMaxOnMultiValuedField, TestRandomRequestDistribution, DistributedQueryComponentCustomSortTest, AsyncCallRequestStatusResponseTest, SolrCoreCheckLockOnStartupTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestFastLRUCache, TestSmileRequest, TestShardHandlerFactory, RAMDirectoryFactoryTest, HdfsBasicDistributedZkTest, PrimitiveFieldTypeTest, TestManagedResource, CleanupOldIndexTest, TestSearcherReuse, TestSortingResponseWriter, ShowFileRequestHandlerTest, TestScoreJoinQPScore, SuggesterWFSTTest, TestReload, ZkStateReaderTest, SimpleMLTQParserTest, FullSolrCloudDistribCmdsTest, TestFastWriter, TestRawTransformer, TestUniqueKeyFieldResource, TestFieldTypeResource, TestTrieFacet, TestJsonFacets, TestCursorMarkWithoutUniqueKey, ScriptEngineTest, TestReqParamsAPI, SpatialHeatmapFacetsTest, ClusterStateTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest] [junit4] Completed [362/554] on J2 in 1101.88s, 1 test, 1 error <<< FAILURES! [...truncated 610 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:782: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:719: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:516: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1432: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:987: There were test failures: 554 suites (8 ignored), 2182 tests, 1 error, 31 ignored (18 assumptions) [seed: 1BF2BDFCDF031AD4] Total time: 218 minutes 23 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts No prior successful build to compare, so performing full copy of artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
