Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.0/12/
1 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Timeout occured while waiting response from server at: http://127.0.0.1:50480/_/w Stack Trace: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:50480/_/w at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:588) 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:1219) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.makeRequest(CollectionsAPIDistributedZkTest.java:382) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:440) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:169) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at java.lang.Thread.run(Thread.java:745) Caused by: java.net.SocketTimeoutException: Read timed out at java.net.SocketInputStream.socketRead0(Native Method) at java.net.SocketInputStream.socketRead(SocketInputStream.java:116) at java.net.SocketInputStream.read(SocketInputStream.java:170) at java.net.SocketInputStream.read(SocketInputStream.java:141) at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160) at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84) at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261) at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251) at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197) at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124) at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:685) at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:487) at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:482) ... 47 more Build Log: [...truncated 11764 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/init-core-data-001 [junit4] 2> 1767529 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[F255A4056D2F1DF]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 1767529 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[F255A4056D2F1DF]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_/w [junit4] 2> 1767531 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1767532 INFO (Thread-51147) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1767532 INFO (Thread-51147) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1767632 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.ZkTestServer start zk server on port:49105 [junit4] 2> 1767632 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1767632 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1767634 INFO (zkCallback-1583-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5664daa9 name:ZooKeeperConnection Watcher:127.0.0.1:49105 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1767635 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1767635 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1767635 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1767637 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1767637 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1767638 INFO (zkCallback-1584-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5ed3f318 name:ZooKeeperConnection Watcher:127.0.0.1:49105/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1767638 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1767638 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1767638 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 1767639 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 1767639 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 1767640 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 1767641 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 1767641 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 1767642 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 1767642 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 1767643 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1767643 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1767644 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 1767644 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 1767644 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 1767644 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 1767645 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 1767645 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 1767646 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1767646 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 1767647 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 1767647 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 1767647 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1767648 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1767648 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1767648 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 1767649 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 1767649 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 1767650 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1767650 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1767651 INFO (zkCallback-1585-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1f35d324 name:ZooKeeperConnection Watcher:127.0.0.1:49105 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1767651 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1767651 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1767651 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1767652 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1767652 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1767653 INFO (zkCallback-1586-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3d8affe3 name:ZooKeeperConnection Watcher:127.0.0.1:49105/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1767653 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1767653 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1767653 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml [junit4] 2> 1767653 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml [junit4] 2> 1767654 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml [junit4] 2> 1767654 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml [junit4] 2> 1767655 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml [junit4] 2> 1767655 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml [junit4] 2> 1767656 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1767656 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1767657 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt [junit4] 2> 1767657 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt [junit4] 2> 1767658 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt [junit4] 2> 1767658 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt [junit4] 2> 1767658 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml [junit4] 2> 1767658 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml [junit4] 2> 1767659 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json [junit4] 2> 1767659 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json [junit4] 2> 1767660 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 1767660 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 1767661 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt [junit4] 2> 1767661 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt [junit4] 2> 1767661 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt [junit4] 2> 1767662 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt [junit4] 2> 1767662 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml [junit4] 2> 1767662 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml [junit4] 2> 1767719 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/control-001/cores/collection1 [junit4] 2> 1767721 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 1767721 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2fcfa503{/_/w,null,AVAILABLE} [junit4] 2> 1767724 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.e.j.s.ServerConnector Started ServerConnector@34db6976{HTTP/1.1,[http/1.1]}{127.0.0.1:56161} [junit4] 2> 1767724 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.e.j.s.Server Started @1770316ms [junit4] 2> 1767724 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/tempDir-001/control/data, hostContext=/_/w, hostPort=56161, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/control-001/cores} [junit4] 2> 1767724 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93 [junit4] 2> 1767724 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/control-001' [junit4] 2> 1767725 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1767725 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1767725 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1767728 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1767729 INFO (zkCallback-1587-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3f23fcb2 name:ZooKeeperConnection Watcher:127.0.0.1:49105/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1767729 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1767729 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1767730 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1767730 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/control-001/solr.xml [junit4] 2> 1767733 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/control-001/cores [junit4] 2> 1767733 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.CoreContainer New CoreContainer 1916264700 [junit4] 2> 1767733 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/control-001] [junit4] 2> 1767733 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/control-001/lib [junit4] 2> 1767733 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] 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> 1767734 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1767734 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1767734 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1767735 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49105/solr [junit4] 2> 1767735 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1767735 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1767740 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1767740 INFO (zkCallback-1590-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4b362bbc name:ZooKeeperConnection Watcher:127.0.0.1:49105 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1767741 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1767741 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1767742 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1767742 INFO (zkCallback-1591-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5f74ca59 name:ZooKeeperConnection Watcher:127.0.0.1:49105/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1767742 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1767743 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 1767744 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 1767745 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 1767745 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 1767746 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 1767747 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1767747 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1767748 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1767748 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1767749 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1767749 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1767750 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 1767750 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 1767751 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1767751 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95914627731816454-127.0.0.1:56161__%2Fw-n_0000000000 [junit4] 2> 1767751 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:56161__%2Fw [junit4] 2> 1767751 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 1767752 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.Overseer Overseer (id=95914627731816454-127.0.0.1:56161__%2Fw-n_0000000000) starting [junit4] 2> 1767753 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 1767755 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 1767755 INFO (OverseerStateUpdate-95914627731816454-127.0.0.1:56161__%2Fw-n_0000000000) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 1767756 INFO (OverseerStateUpdate-95914627731816454-127.0.0.1:56161__%2Fw-n_0000000000) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1767756 INFO (OverseerCollectionConfigSetProcessor-95914627731816454-127.0.0.1:56161__%2Fw-n_0000000000) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 1767757 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56161__%2Fw [junit4] 2> 1767757 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:56161__%2Fw [junit4] 2> 1767758 INFO (zkCallback-1591-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0]) [junit4] 2> 1767760 INFO (zkCallback-1591-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1767771 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1767771 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1767771 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/control-001/cores [junit4] 2> 1767772 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=control_collection, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 1767772 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/control-001/cores/collection1 [junit4] 2> 1767772 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 1767773 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1767773 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2 [junit4] 2> 1767773 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1767773 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:56161__%2Fw ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1767773 INFO (OverseerStateUpdate-95914627731816454-127.0.0.1:56161__%2Fw-n_0000000000) [n:127.0.0.1:56161__%2Fw ] 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:56161/_/w";, [junit4] 2> "node_name":"127.0.0.1:56161__%2Fw", [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> 1767773 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1767774 INFO (OverseerStateUpdate-95914627731816454-127.0.0.1:56161__%2Fw-n_0000000000) [n:127.0.0.1:56161__%2Fw ] 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:56161/_/w";, [junit4] 2> "node_name":"127.0.0.1:56161__%2Fw", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1767774 INFO (OverseerStateUpdate-95914627731816454-127.0.0.1:56161__%2Fw-n_0000000000) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 1767774 INFO (OverseerStateUpdate-95914627731816454-127.0.0.1:56161__%2Fw-n_0000000000) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 1767775 INFO (zkCallback-1591-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw ] 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> 1768774 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1768774 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 1768774 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1768774 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection] [junit4] 2> 1768775 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 1768775 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/control-001/cores/collection1' [junit4] 2> 1768775 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1768775 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1768776 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1768779 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1768782 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.1 [junit4] 2> 1768797 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 1768800 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1768853 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1768856 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1768863 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1768867 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1768868 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1768869 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1768870 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1768871 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2 [junit4] 2> 1768871 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 1768871 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1768871 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/control-001/cores/collection1/data/] [junit4] 2> 1768871 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw 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@a4a7c26 [junit4] 2> 1768871 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/control-001/cores/collection1/data [junit4] 2> 1768871 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/control-001/cores/collection1/data/index/ [junit4] 2> 1768872 WARN (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1768872 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/control-001/cores/collection1/data/index [junit4] 2> 1768872 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw 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=6, maxMergeAtOnceExplicit=6, maxMergedSegmentMB=93.32421875, floorSegmentMB=1.5478515625, forceMergeDeletesPctAllowed=5.098846478352163, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7081603462781312 [junit4] 2> 1768872 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@61acd239 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@315cd7af; maxCacheMB=0.154296875 maxMergeSizeMB=0.0400390625)),segFN=segments_1,generation=1} [junit4] 2> 1768872 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 1768873 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/control-001/cores/collection1/data/ [junit4] 2> 1768873 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 1768875 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1768875 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 1768875 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1768875 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1768875 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1768875 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw 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> 1768875 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw 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> 1768875 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw 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> 1768875 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw 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> 1768875 WARN (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw 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> 1768876 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1768877 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1768877 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1768878 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1768879 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/dump,/update/csv,/sql,/update/json/docs,/admin/luke,/export,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins,/admin/logging,/update/json,/admin/ping,/admin/threads,/update,/admin/file [junit4] 2> 1768879 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw 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> 1768880 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw 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> 1768880 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw 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> 1768880 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1768881 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1768881 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=22, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=49.322265625, floorSegmentMB=1.5009765625, forceMergeDeletesPctAllowed=28.653156873313705, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3041072926180546 [junit4] 2> 1768881 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@61acd239 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@315cd7af; maxCacheMB=0.154296875 maxMergeSizeMB=0.0400390625)),segFN=segments_1,generation=1} [junit4] 2> 1768881 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 1768881 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@678119b4[collection1] main] [junit4] 2> 1768881 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw 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> 1768881 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw 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> 1768881 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw 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> 1768882 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1768882 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 1768882 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw 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> 1768882 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw 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> 1768882 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw 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> 1768882 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1768882 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1768882 INFO (searcherExecutor-4755-thread-1-processing-n:127.0.0.1:56161__%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@678119b4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1768883 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw 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> 1768883 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw 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> 1768883 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw 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> 1768883 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw 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 1534634045124640768 [junit4] 2> 1768884 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1534634045124640768 [junit4] 2> 1768884 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1768885 INFO (coreLoadExecutor-4754-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 1768885 INFO (coreZkRegister-4748-thread-1-processing-n:127.0.0.1:56161__%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56161__%2Fw 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:56161/_/w collection:control_collection shard:shard1 [junit4] 2> 1768885 INFO (coreZkRegister-4748-thread-1-processing-n:127.0.0.1:56161__%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56161__%2Fw 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> 1768887 INFO (coreZkRegister-4748-thread-1-processing-n:127.0.0.1:56161__%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56161__%2Fw 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/95914627731816454-core_node1-n_0000000000 [junit4] 2> 1768887 INFO (coreZkRegister-4748-thread-1-processing-n:127.0.0.1:56161__%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard1 and weAreReplacement=false and leaderVoteWait=180000 [junit4] 2> 1768888 INFO (coreZkRegister-4748-thread-1-processing-n:127.0.0.1:56161__%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1768888 INFO (coreZkRegister-4748-thread-1-processing-n:127.0.0.1:56161__%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56161__%2Fw 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> 1768888 INFO (coreZkRegister-4748-thread-1-processing-n:127.0.0.1:56161__%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:56161/_/w/collection1/ [junit4] 2> 1768888 INFO (coreZkRegister-4748-thread-1-processing-n:127.0.0.1:56161__%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1768888 INFO (coreZkRegister-4748-thread-1-processing-n:127.0.0.1:56161__%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:56161/_/w/collection1/ has no replicas [junit4] 2> 1768888 INFO (OverseerStateUpdate-95914627731816454-127.0.0.1:56161__%2Fw-n_0000000000) [n:127.0.0.1:56161__%2Fw ] 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> 1768889 INFO (coreZkRegister-4748-thread-1-processing-n:127.0.0.1:56161__%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 1768890 INFO (coreZkRegister-4748-thread-1-processing-n:127.0.0.1:56161__%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/95914627731816454-core_node1-n_0000000000 [junit4] 2> 1768891 INFO (coreZkRegister-4748-thread-1-processing-n:127.0.0.1:56161__%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56161__%2Fw 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:56161/_/w/collection1/ shard1 [junit4] 2> 1768891 INFO (OverseerStateUpdate-95914627731816454-127.0.0.1:56161__%2Fw-n_0000000000) [n:127.0.0.1:56161__%2Fw ] 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:56161/_/w";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 1768992 INFO (zkCallback-1591-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw ] 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> 1769042 INFO (coreZkRegister-4748-thread-1-processing-n:127.0.0.1:56161__%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:56161/_/w/collection1/ and leader is http://127.0.0.1:56161/_/w/collection1/ [junit4] 2> 1769042 INFO (coreZkRegister-4748-thread-1-processing-n:127.0.0.1:56161__%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56161__%2Fw 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:56161/_/w [junit4] 2> 1769042 INFO (coreZkRegister-4748-thread-1-processing-n:127.0.0.1:56161__%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1769042 INFO (coreZkRegister-4748-thread-1-processing-n:127.0.0.1:56161__%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 1769042 INFO (coreZkRegister-4748-thread-1-processing-n:127.0.0.1:56161__%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56161__%2Fw 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> 1769043 INFO (OverseerStateUpdate-95914627731816454-127.0.0.1:56161__%2Fw-n_0000000000) [n:127.0.0.1:56161__%2Fw ] 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:56161/_/w";, [junit4] 2> "node_name":"127.0.0.1:56161__%2Fw", [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> 1769043 INFO (coreZkRegister-4748-thread-1-processing-n:127.0.0.1:56161__%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56161__%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (1) [junit4] 2> 1769043 INFO (OverseerStateUpdate-95914627731816454-127.0.0.1:56161__%2Fw-n_0000000000) [n:127.0.0.1:56161__%2Fw ] 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:56161/_/w";, [junit4] 2> "node_name":"127.0.0.1:56161__%2Fw", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1769144 INFO (zkCallback-1591-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw ] 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> 1769274 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1769274 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1769275 INFO (zkCallback-1593-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1bae1d4e name:ZooKeeperConnection Watcher:127.0.0.1:49105/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1769275 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1769275 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1769275 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1769276 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1769276 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 1769331 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/shard-1-001/cores/collection1 [junit4] 2> 1769331 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/shard-1-001 [junit4] 2> 1769333 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 1769334 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6b41db77{/_/w,null,AVAILABLE} [junit4] 2> 1769334 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1be4ecee{HTTP/1.1,[http/1.1]}{127.0.0.1:50480} [junit4] 2> 1769335 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.e.j.s.Server Started @1771926ms [junit4] 2> 1769335 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/_/w, hostPort=50480, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/shard-1-001/cores} [junit4] 2> 1769335 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93 [junit4] 2> 1769335 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/shard-1-001' [junit4] 2> 1769335 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1769335 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1769335 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1769335 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1769336 INFO (zkCallback-1594-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@40176121 name:ZooKeeperConnection Watcher:127.0.0.1:49105/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1769336 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1769336 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1769337 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1769337 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/shard-1-001/solr.xml [junit4] 2> 1769340 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/shard-1-001/cores [junit4] 2> 1769340 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.CoreContainer New CoreContainer 309222982 [junit4] 2> 1769340 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/shard-1-001] [junit4] 2> 1769340 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001/shard-1-001/lib [junit4] 2> 1769340 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] 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> 1769341 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1769341 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1769341 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1769342 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49105/solr [junit4] 2> 1769342 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1769342 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1769342 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1769342 INFO (zkCallback-1597-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4c407dd2 name:ZooKeeperConnection Watcher:127.0.0.1:49105 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1769342 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1769343 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1769343 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:50480__%2Fw ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1769344 INFO (zkCallback-1598-thread-1-processing-n:127.0.0.1:50480__%2Fw) [n:127.0.0.1:50480__%2Fw ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4648664a name:ZooKeeperConnection Watcher:127.0.0.1:49105/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1769344 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:50480__%2Fw ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1769347 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:50480__%2Fw ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1769348 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:50480__%2Fw ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1769349 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:50480__%2Fw ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1769349 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:50480__%2Fw ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95914627731816458-127.0.0.1:50480__%2Fw-n_0000000001 [junit4] 2> 1769349 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:50480__%2Fw ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95914627731816454-127.0.0.1:56161__%2Fw-n_0000000000 to know if I could be the leader [junit4] 2> 1769349 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:50480__%2Fw ] o.a.s.c.ZkController Publish node=127.0.0.1:50480__%2Fw as DOWN [junit4] 2> 1769350 INFO (OverseerStateUpdate-95914627731816454-127.0.0.1:56161__%2Fw-n_0000000000) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:50480__%2Fw"} current state version: 3 [junit4] 2> 1769350 INFO (OverseerStateUpdate-95914627731816454-127.0.0.1:56161__%2Fw-n_0000000000) [n:127.0.0.1:56161__%2Fw ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:50480__%2Fw [junit4] 2> 1769452 INFO (zkCallback-1591-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw ] 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> 1769452 INFO (zkCallback-1598-thread-1-processing-n:127.0.0.1:50480__%2Fw) [n:127.0.0.1:50480__%2Fw ] 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> 1769452 INFO (zkCallback-1593-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1]) [junit4] 2> 1770350 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:50480__%2Fw ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50480__%2Fw [junit4] 2> 1770351 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F255A4056D2F1DF]) [n:127.0.0.1:50480__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:50480__%2Fw [junit4] 2> 1770354 INFO (zkCallback-1593-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> 1770354 INFO (zkCallback-1591-thread-1-processing-n:127.0.0.1:56161__%2Fw) [n:127.0.0.1:56161__%2Fw ] 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> 1770354 INFO (zkCallback-1598-thread-1-processing-n:127.0.0.1:50480__%2Fw) [n:127.0.0.1:50480__%2Fw ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:S [...truncated too long message...] l.CloudSolrClient.sendRequest(CloudSolrClient.java:1100) [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:1219) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1593) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1614) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:973) [junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:41772/_/w: KeeperErrorCode = Session expired for /overseer/collection-queue-work/qnr- [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:577) [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 moreThrowable #6: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=58898, name=collection2, 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:56161/_/w: collection already exists: awholynewstresscollection_collection2_7 [junit4] > at __randomizedtesting.SeedInfo.seed([F255A4056D2F1DF]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:577) [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:1100) [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:1219) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1593) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1614) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:973)Throwable #7: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=58901, name=collection5, 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:41772/_/w: Could not find collection : awholynewstresscollection_collection5_7 [junit4] > at __randomizedtesting.SeedInfo.seed([F255A4056D2F1DF]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:577) [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:1100) [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:1219) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:980) [junit4] 2> 2567353 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[F255A4056D2F1DF]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F255A4056D2F1DF-001 [junit4] 2> May 18, 2016 3:24:32 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=FastCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST, chunkSize=5060, maxDocsPerChunk=636, blockSize=3), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST, chunkSize=5060, blockSize=3)), sim=RandomSimilarity(queryNorm=true,coord=no): {}, locale=uk-UA, timezone=Pacific/Wake [junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_74 (64-bit)/cpus=4,threads=1,free=172435992,total=480247808 [junit4] 2> NOTE: All tests run in this JVM: [CurrencyFieldXmlFileTest, CoreAdminCreateDiscoverTest, TestSearchPerf, DistributedSuggestComponentTest, ParsingFieldUpdateProcessorsTest, TestSort, TestXIncludeConfig, PluginInfoTest, TestCustomDocTransformer, TestPHPSerializedResponseWriter, TestJmxIntegration, TestDynamicFieldCollectionResource, QueryParsingTest, TriLevelCompositeIdRoutingTest, DistributedFacetPivotLongTailTest, ShowFileRequestHandlerTest, BasicDistributedZk2Test, LukeRequestHandlerTest, TestRandomCollapseQParserPlugin, CleanupOldIndexTest, TestSchemaNameResource, TestNRTOpen, TestTolerantSearch, ConjunctionSolrSpellCheckerTest, TestReversedWildcardFilterFactory, DistribJoinFromCollectionTest, TestSQLHandler, CoreAdminHandlerTest, SharedFSAutoReplicaFailoverTest, TestFieldSortValues, DebugComponentTest, ConnectionManagerTest, TestExpandComponent, AnalysisErrorHandlingTest, InfoHandlerTest, TestImplicitCoreProperties, LeaderInitiatedRecoveryOnCommitTest, TestZkChroot, RegexBoostProcessorTest, CircularListTest, TestStandardQParsers, DeleteStatusTest, HdfsThreadLeakTest, TestDistributedMissingSort, StatsComponentTest, FieldAnalysisRequestHandlerTest, VersionInfoTest, SSLMigrationTest, TestDocBasedVersionConstraints, BigEndianAscendingWordDeserializerTest, TestRuleBasedAuthorizationPlugin, HdfsCollectionsAPIDistributedZkTest, TestDownShardTolerantSearch, XmlUpdateRequestHandlerTest, TestUseDocValuesAsStored2, CollectionsAPISolrJTest, TestCollectionAPI, TestStressReorder, HdfsBasicDistributedZkTest, BadComponentTest, DocValuesMultiTest, BaseCdcrDistributedZkTest, TestExactStatsCache, BlockCacheTest, TestPostingsSolrHighlighter, DateRangeFieldTest, ReplicationFactorTest, TestGroupingSearch, DateMathParserTest, TestRecovery, TestStressRecovery, QueryResultKeyTest, OverseerTest, TestManagedSynonymFilterFactory, SolrCloudExampleTest, DirectUpdateHandlerTest, TestJmxMonitoredMap, TestIntervalFaceting, TestSweetSpotSimilarityFactory, HdfsDirectoryTest, JavabinLoaderTest, TestNoOpRegenerator, TestExtendedDismaxParser, DirectoryFactoryTest, TestSolrQueryResponse, SolrCoreCheckLockOnStartupTest, TestIndexSearcher, DirectUpdateHandlerOptimizeTest, TestCharFilters, TestUtils, HardAutoCommitTest, PKIAuthenticationIntegrationTest, CloneFieldUpdateProcessorFactoryTest, TestRebalanceLeaders, ChaosMonkeySafeLeaderTest, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest] [junit4] Completed [259/580 (1!)] on J2 in 800.60s, 1 test, 1 error <<< FAILURES! [...truncated 1021 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/build.xml:747: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/build.xml:684: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/common-build.xml:527: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/lucene/common-build.xml:1457: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/lucene/common-build.xml:1014: There were test failures: 580 suites (10 ignored), 2318 tests, 1 error, 31 ignored (16 assumptions) [seed: F255A4056D2F1DF] Total time: 185 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: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org
