Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.x/76/
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:40070 Stack Trace: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:40070 at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:601) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:259) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.makeRequest(CollectionsAPIDistributedZkTest.java:399) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:457) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:179) 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:495) ... 47 more Build Log: [...truncated 11818 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/init-core-data-001 [junit4] 2> 2921128 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[AFFDD8DE45FE7A36]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 2921134 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[AFFDD8DE45FE7A36]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 2921136 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 2921142 INFO (Thread-21432) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 2921143 INFO (Thread-21432) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 2921391 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.ZkTestServer start zk server on port:43675 [junit4] 2> 2921391 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2921392 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2921418 INFO (zkCallback-1593-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3d8cbd81 name:ZooKeeperConnection Watcher:127.0.0.1:43675 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2921418 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2921419 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2921419 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 2921427 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2921441 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2921450 INFO (zkCallback-1594-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@35a8aed1 name:ZooKeeperConnection Watcher:127.0.0.1:43675/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2921450 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2921451 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2921451 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 2921458 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 2921459 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 2921459 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 2921460 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 2921460 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 2921461 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 2921461 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 2921462 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 2921462 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 2921463 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 2921463 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 2921463 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 2921463 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 2921464 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 2921464 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 2921465 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 2921465 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 2921465 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 2921465 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 2921466 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 2921466 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 2921467 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 2921467 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 2921467 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 2921467 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 2921615 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/cores/collection1 [junit4] 2> 2921627 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 2921661 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@748fa80c{/,null,AVAILABLE} [junit4] 2> 2921662 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.e.j.s.ServerConnector Started ServerConnector@6eecf33f{HTTP/1.1,[http/1.1]}{127.0.0.1:45398} [junit4] 2> 2921662 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.e.j.s.Server Started @2924880ms [junit4] 2> 2921662 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/tempDir-001/control/data, hostContext=/, hostPort=45398, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/cores} [junit4] 2> 2921662 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93 [junit4] 2> 2921662 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001' [junit4] 2> 2921662 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2921662 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 2921663 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2921669 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2921670 INFO (zkCallback-1595-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@35aee0d8 name:ZooKeeperConnection Watcher:127.0.0.1:43675/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2921670 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2921670 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2921671 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 2921671 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/solr.xml [junit4] 2> 2921674 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/cores [junit4] 2> 2921674 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.CoreContainer New CoreContainer 1345964234 [junit4] 2> 2921674 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001] [junit4] 2> 2921674 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/lib [junit4] 2> 2921674 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] 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> 2921675 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 2921675 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 2921676 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 2921676 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43675/solr [junit4] 2> 2921676 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 2921676 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2921679 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2921679 INFO (zkCallback-1598-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@341c2131 name:ZooKeeperConnection Watcher:127.0.0.1:43675 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2921679 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2921679 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2921680 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$38/2075211641@612d4e90 [junit4] 2> 2921682 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2921683 INFO (zkCallback-1599-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@459f57ed name:ZooKeeperConnection Watcher:127.0.0.1:43675/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2921683 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2921683 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 2921684 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 2921685 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 2921685 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 2921686 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 2921686 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 2921687 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 2921687 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 2921688 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 2921688 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 2921688 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 2921689 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 2921689 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 2921690 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 2921690 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 2921691 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95975996557295620-127.0.0.1:45398_-n_0000000000 [junit4] 2> 2921691 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:45398_ [junit4] 2> 2921691 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 2921691 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.Overseer Overseer (id=95975996557295620-127.0.0.1:45398_-n_0000000000) starting [junit4] 2> 2921692 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 2921694 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 2921694 INFO (OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) [n:127.0.0.1:45398_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 2921694 INFO (OverseerCollectionConfigSetProcessor-95975996557295620-127.0.0.1:45398_-n_0000000000) [n:127.0.0.1:45398_ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 2921702 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45398_ [junit4] 2> 2921703 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:45398_ [junit4] 2> 2921703 INFO (OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) [n:127.0.0.1:45398_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 2921703 INFO (zkCallback-1599-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ ] 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> 2921703 INFO (zkCallback-1599-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2921722 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 2921722 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 2921722 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/cores [junit4] 2> 2921723 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] 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> 2921723 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/cores/collection1 [junit4] 2> 2921723 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 2921724 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 2921724 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 2921724 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection x:collection1] o.a.s.c.ZkController Collection control_collection not visible yet, but flagging it so a watch is registered when it becomes visible [junit4] 2> 2921725 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Deleting data for [control_collection] [junit4] 2> 2921725 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1 [junit4] 2> 2921725 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:45398_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 2921726 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 2921726 INFO (OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) [n:127.0.0.1:45398_ ] 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:45398", [junit4] 2> "node_name":"127.0.0.1:45398_", [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> 2921726 INFO (OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) [n:127.0.0.1:45398_ ] 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:45398", [junit4] 2> "node_name":"127.0.0.1:45398_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 2921726 INFO (OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) [n:127.0.0.1:45398_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 2921726 INFO (OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) [n:127.0.0.1:45398_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 2921727 INFO (zkCallback-1599-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ ] 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> 2922726 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 2922726 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 2922727 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 2922727 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection] [junit4] 2> 2922727 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 2922727 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/cores/collection1' [junit4] 2> 2922727 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2922727 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 2922728 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 2922730 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 2922732 WARN (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 2922737 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.1.0 [junit4] 2> 2922743 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 2922961 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 2923130 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 2923149 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 2923150 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 2923154 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 2923156 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 2923157 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 2923157 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 2923158 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1 [junit4] 2> 2923158 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 2923158 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 2923158 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/cores/collection1/data/] [junit4] 2> 2923158 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ 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@5a3da9f9 [junit4] 2> 2923158 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/cores/collection1/data [junit4] 2> 2923166 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/cores/collection1/data/index/ [junit4] 2> 2923166 WARN (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 2923166 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/cores/collection1/data/index [junit4] 2> 2923166 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=9, maxMergeAtOnceExplicit=4, maxMergedSegmentMB=88.5, floorSegmentMB=0.9404296875, forceMergeDeletesPctAllowed=6.753991068492562, segmentsPerTier=27.0, maxCFSSegmentSizeMB=1.3935546875, noCFSRatio=0.0 [junit4] 2> 2923166 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4289e8f3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11fd0ee5),segFN=segments_1,generation=1} [junit4] 2> 2923166 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 2923168 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 2923168 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 2923168 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 2923168 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 2923168 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 2923168 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ 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> 2923168 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ 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> 2923169 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ 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> 2923169 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ 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> 2923169 WARN (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ 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> 2923170 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 2923170 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 2923171 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 2923171 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 2923173 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/dump,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file [junit4] 2> 2923173 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ 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> 2923173 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/cores/collection1/data/ [junit4] 2> 2923173 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 2923173 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ 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> 2923173 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ 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> 2923174 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2923174 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2923174 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ 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=33, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=67.6943359375, floorSegmentMB=0.3505859375, forceMergeDeletesPctAllowed=12.317429438353683, segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 2923174 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4289e8f3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11fd0ee5),segFN=segments_1,generation=1} [junit4] 2> 2923175 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 2923175 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3798da33[collection1] main] [junit4] 2> 2923175 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ 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> 2923175 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ 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> 2923175 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ 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> 2923175 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 2923175 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 2923175 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ 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> 2923175 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ 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> 2923176 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ 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> 2923176 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 2923176 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 2923176 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ 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> 2923176 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ 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> 2923176 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ 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> 2923176 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ 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 1535615947036950528 [junit4] 2> 2923178 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ 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 1535615947036950528 [junit4] 2> 2923178 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 2923178 INFO (coreLoadExecutor-4487-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 2923178 INFO (searcherExecutor-4488-thread-1-processing-n:127.0.0.1:45398_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3798da33[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2923179 INFO (coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ 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:45398 collection:control_collection shard:shard1 [junit4] 2> 2923179 INFO (coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ 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> 2923180 INFO (coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ 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/95975996557295620-core_node1-n_0000000000 [junit4] 2> 2923180 INFO (coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ 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> 2923181 INFO (OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) [n:127.0.0.1:45398_ ] 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> 2923182 INFO (coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 2923182 INFO (coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ 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> 2923182 INFO (coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:45398/collection1/ [junit4] 2> 2923182 INFO (coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 2923182 INFO (coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:45398/collection1/ has no replicas [junit4] 2> 2923182 INFO (coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 2923183 INFO (coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ 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/95975996557295620-core_node1-n_0000000000 [junit4] 2> 2923184 INFO (OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) [n:127.0.0.1:45398_ ] 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:45398", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 2923185 INFO (coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ 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:45398/collection1/ shard1 [junit4] 2> 2923226 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.s.i.CloudSolrClient Final constructed zkHost string: 127.0.0.1:43675/solr [junit4] 2> 2923226 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2923243 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2923244 INFO (zkCallback-1603-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@33980ed9 name:ZooKeeperConnection Watcher:127.0.0.1:43675/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2923245 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2923245 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2923245 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 2923245 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 2923245 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2923246 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 2923285 INFO (zkCallback-1603-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> 2923285 INFO (zkCallback-1599-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ ] 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> 2923335 INFO (coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:45398/collection1/ and leader is http://127.0.0.1:45398/collection1/ [junit4] 2> 2923335 INFO (coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ 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:45398 [junit4] 2> 2923335 INFO (coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 2923335 INFO (coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 2923336 INFO (coreZkRegister-4481-thread-1-processing-n:127.0.0.1:45398_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45398_ 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> 2923336 INFO (OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) [n:127.0.0.1:45398_ ] 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:45398", [junit4] 2> "node_name":"127.0.0.1:45398_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 2923337 INFO (OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) [n:127.0.0.1:45398_ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 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:45398", [junit4] 2> "node_name":"127.0.0.1:45398_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 2923362 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/shard-1-001/cores/collection1 [junit4] 2> 2923363 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/shard-1-001 [junit4] 2> 2923363 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 2923386 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@44cd5e15{/,null,AVAILABLE} [junit4] 2> 2923387 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.e.j.s.ServerConnector Started ServerConnector@7496adb1{HTTP/1.1,[http/1.1]}{127.0.0.1:40070} [junit4] 2> 2923387 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.e.j.s.Server Started @2926605ms [junit4] 2> 2923387 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=40070, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/shard-1-001/cores} [junit4] 2> 2923387 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93 [junit4] 2> 2923387 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/shard-1-001' [junit4] 2> 2923387 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2923387 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 2923387 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2923410 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2923422 INFO (zkCallback-1604-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@37eedd07 name:ZooKeeperConnection Watcher:127.0.0.1:43675/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2923422 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2923423 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2923423 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 2923423 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/shard-1-001/solr.xml [junit4] 2> 2923427 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/shard-1-001/cores [junit4] 2> 2923427 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.CoreContainer New CoreContainer 622539282 [junit4] 2> 2923427 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/shard-1-001] [junit4] 2> 2923427 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/shard-1-001/lib [junit4] 2> 2923427 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] 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> 2923428 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 2923428 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 2923428 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 2923428 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43675/solr [junit4] 2> 2923428 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 2923428 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2923438 INFO (zkCallback-1603-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> 2923438 INFO (zkCallback-1599-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ ] 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> 2923443 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2923450 INFO (zkCallback-1607-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@42b8f654 name:ZooKeeperConnection Watcher:127.0.0.1:43675 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2923450 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2923451 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2923451 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:40070_ ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$38/2075211641@5312ba4f [junit4] 2> 2923463 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:40070_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2923464 INFO (zkCallback-1608-thread-1-processing-n:127.0.0.1:40070_) [n:127.0.0.1:40070_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4f88c0cb name:ZooKeeperConnection Watcher:127.0.0.1:43675/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2923464 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:40070_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2923466 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:40070_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 2923467 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:40070_ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 2923467 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:40070_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2923468 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:40070_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 2923468 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:40070_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95975996557295624-127.0.0.1:40070_-n_0000000001 [junit4] 2> 2923468 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:40070_ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95975996557295620-127.0.0.1:45398_-n_0000000000 to know if I could be the leader [junit4] 2> 2923468 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:40070_ ] o.a.s.c.ZkController Publish node=127.0.0.1:40070_ as DOWN [junit4] 2> 2923469 INFO (OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) [n:127.0.0.1:45398_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:40070_"} current state version: 3 [junit4] 2> 2923469 INFO (OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) [n:127.0.0.1:45398_ ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:40070_ [junit4] 2> 2923570 INFO (zkCallback-1599-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ ] 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> 2923570 INFO (zkCallback-1608-thread-1-processing-n:127.0.0.1:40070_) [n:127.0.0.1:40070_ ] 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> 2923570 INFO (zkCallback-1603-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> 2924469 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:40070_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40070_ [junit4] 2> 2924469 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:40070_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:40070_ [junit4] 2> 2924470 INFO (zkCallback-1599-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ ] 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> 2924470 INFO (zkCallback-1603-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> 2924470 INFO (zkCallback-1603-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 2924470 INFO (zkCallback-1599-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 2924470 INFO (zkCallback-1608-thread-1-processing-n:127.0.0.1:40070_) [n:127.0.0.1:40070_ ] 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> 2924471 INFO (zkCallback-1608-thread-1-processing-n:127.0.0.1:40070_) [n:127.0.0.1:40070_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 2924487 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:40070_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 2924487 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:40070_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 2924488 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:40070_ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/shard-1-001/cores [junit4] 2> 2924488 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:40070_ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=collection1, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 2924488 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:40070_ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AFFDD8DE45FE7A36-001/shard-1-001/cores/collection1 [junit4] 2> 2924488 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:40070_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 2924489 INFO (coreLoadExecutor-4498-thread-1-processing-n:127.0.0.1:40070_) [n:127.0.0.1:40070_ c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 2924489 INFO (coreLoadExecutor-4498-thread-1-processing-n:127.0.0.1:40070_) [n:127.0.0.1:40070_ c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 2924490 INFO (OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) [n:127.0.0.1:45398_ ] 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:40070", [junit4] 2> "node_name":"127.0.0.1:40070_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 4 [junit4] 2> 2924490 INFO (OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) [n:127.0.0.1:45398_ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:40070", [junit4] 2> "node_name":"127.0.0.1:40070_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 2924490 INFO (OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) [n:127.0.0.1:45398_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 2924490 INFO (OverseerStateUpdate-95975996557295620-127.0.0.1:45398_-n_0000000000) [n:127.0.0.1:45398_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 2924491 INFO (coreLoadExecutor-4498-thread-1-processing-n:127.0.0.1:40070_) [n:127.0.0.1:40070_ c:collection1 x:collection1] o.a.s.c.ZkController Collection collection1 not visible yet, but flagging it so a watch is registered when it becomes visible [junit4] 2> 2924491 INFO (coreLoadExecutor-4498-thread-1-processing-n:127.0.0.1:40070_) [n:127.0.0.1:40070_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Deleting data for [collection1] [junit4] 2> 2924492 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:40070_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1 [junit4] 2> 2924492 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AFFDD8DE45FE7A36]) [n:127.0.0.1:40070_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 2924492 INFO (coreLoadExecutor-4498-thread-1-processing-n:127.0.0.1:40070_) [n:127.0.0.1:40070_ c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 2924591 INFO (zkCallback-1608-thread-1-processing-n:127.0.0.1:40070_) [n:127.0.0.1:40070_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2]) [junit4] 2> 2924591 INFO (zkCallback-1603-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2]) [junit4] 2> 2924591 INFO (zkCallback-1599-thread-1-processing-n:127.0.0.1:45398_) [n:127.0.0.1:45398_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2]) [junit4] 2> 2925492 INFO (coreLoadExecutor-4498-thread-1-processing-n:127.0.0.1:40070_) [n:127.0.0.1:40070_ c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 2925492 INFO (coreLoadExecutor-4498-thread-1-processing-n:127.0.0.1:40070_) [n:127.0.0.1:40070_ c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1 [junit4] 2> 2925493 INFO (coreLoadExecutor-4498-thread-1-pr [...truncated too long message...] g-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-collective-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-event-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-exception-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-journal-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-normalization-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-operational-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-referral-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-schema-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-subtree-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-trigger-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-jdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-jdbm1-2.0.0-M2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-kerberos-codec-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-ldif-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-mavibot-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-protocol-kerberos-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-protocol-ldap-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-protocol-shared-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-xdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/api-all-1.0.0-M20.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/bcprov-jdk15-1.45.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/cglib-nodep-2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/commons-collections-3.2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/commons-math3-3.4.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/easymock-3.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/ehcache-core-2.4.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/hadoop-common-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/hadoop-hdfs-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/hadoop-minikdc-2.6.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jackson-annotations-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jackson-databind-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jersey-core-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jersey-server-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jetty-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jetty-util-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/mina-core-2.0.0-M5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/mockito-core-1.9.5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/objenesis-1.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/presto-parser-0.122.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/slice-0.10.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/build/analysis/icu/lucene-analyzers-icu-6.1.0-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-analysis-extras/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/build/queryparser/classes/test:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/contrib/analysis-extras/lib/icu4j-56.1.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-launcher.jar:/x1/jenkins/.ant/lib/ivy-2.3.0.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-antlr.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-regexp.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-resolver.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-commons-net.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jmf.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-oro.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-xalan2.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-commons-logging.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jsch.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-junit4.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-javamail.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jai.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jdepend.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-junit.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-bsf.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-swing.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-netrexx.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-bcel.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-testutil.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-log4j.jar:/x1/jenkins/jenkins-slave/tools/hudson.model.JDK/latest1.8/lib/tools.jar:/x1/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.3.4.jar com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/temp/junit4-J0-20160528_222922_117.events @/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/temp/junit4-J0-20160528_222922_117.suites -stdin [junit4] ERROR: JVM J0 ended with an exception: Quit event not received from the forked process? This may indicate JVM crash or runner bugs. [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1501) [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:121) [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:955) [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:952) [junit4] at java.util.concurrent.FutureTask.run(FutureTask.java:266) [junit4] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [junit4] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] at java.lang.Thread.run(Thread.java:745) BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:747: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:684: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/common-build.xml:531: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1427: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:984: At least one slave process threw an exception, first: Quit event not received from the forked process? This may indicate JVM crash or runner bugs. Total time: 305 minutes 55 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