Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.1/2/
1 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Timeout occured while waiting response from server at: https://127.0.0.1:37061 Stack Trace: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: https://127.0.0.1:37061 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 sun.security.ssl.InputRecord.readFully(InputRecord.java:465) at sun.security.ssl.InputRecord.read(InputRecord.java:503) at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:973) at sun.security.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:930) at sun.security.ssl.AppInputStream.read(AppInputStream.java:105) 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 11499 lines...] [junit4] JVM J1: stdout was not empty, see: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/temp/junit4-J1-20160611_065118_924.sysout [junit4] HEARTBEAT J0 PID(24281@lucene1-us-west): 2016-06-11T07:14:19, stalled for 67.3s at: BasicDistributedZkTest.test [junit4] >>> JVM J1 emitted unexpected output (verbatim) ---- [junit4] java.lang.OutOfMemoryError: GC overhead limit exceeded [junit4] Dumping heap to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/heapdumps/java_pid24282.hprof ... [junit4] Heap dump file created [668928882 bytes in 40.617 secs] [junit4] <<< JVM J1: EOF ---- [junit4] JVM J1: stderr was not empty, see: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/temp/junit4-J1-20160611_065118_924.syserr [junit4] >>> JVM J1 emitted unexpected output (verbatim) ---- [junit4] WARN: Unhandled exception in event serialization. -> java.lang.OutOfMemoryError: GC overhead limit exceeded [junit4] <<< JVM J1: EOF ---- [...truncated 392 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/init-core-data-001 [junit4] 2> 2529069 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[F16EC5A43AC01EBA]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 2529070 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[F16EC5A43AC01EBA]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 2529071 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 2529072 INFO (Thread-18947) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 2529072 INFO (Thread-18947) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 2529172 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.ZkTestServer start zk server on port:37272 [junit4] 2> 2529172 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2529172 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2529177 INFO (zkCallback-28429-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4a810afc name:ZooKeeperConnection Watcher:127.0.0.1:37272 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2529177 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2529178 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2529178 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 2529180 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2529180 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2529181 INFO (zkCallback-28430-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@57c41e7a name:ZooKeeperConnection Watcher:127.0.0.1:37272/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2529181 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2529181 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2529181 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 2529183 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 2529184 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 2529184 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 2529185 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 2529185 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 2529186 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 2529187 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 2529188 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 2529188 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 2529189 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 2529189 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 2529190 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 2529190 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 2529190 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 2529191 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 2529191 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 2529191 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 2529192 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 2529192 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 2529193 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 2529193 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 2529194 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 2529194 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 2529195 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 2529195 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 2529196 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2529197 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2529197 INFO (zkCallback-28432-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4b237808 name:ZooKeeperConnection Watcher:127.0.0.1:37272/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2529197 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2529197 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2529198 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2529198 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2529199 INFO (zkCallback-28433-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@558be987 name:ZooKeeperConnection Watcher:127.0.0.1:37272 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2529199 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2529199 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2529199 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 2529200 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2529200 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2529201 INFO (zkCallback-28434-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@25e6bb68 name:ZooKeeperConnection Watcher:127.0.0.1:37272/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2529201 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2529201 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2529201 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml [junit4] 2> 2529201 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml [junit4] 2> 2529202 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml [junit4] 2> 2529202 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml [junit4] 2> 2529203 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml [junit4] 2> 2529204 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml [junit4] 2> 2529204 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 2529205 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 2529205 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt [junit4] 2> 2529205 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt [junit4] 2> 2529206 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt [junit4] 2> 2529206 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt [junit4] 2> 2529207 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml [junit4] 2> 2529207 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml [junit4] 2> 2529208 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json [junit4] 2> 2529208 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json [junit4] 2> 2529209 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 2529209 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 2529210 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt [junit4] 2> 2529210 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt [junit4] 2> 2529211 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt [junit4] 2> 2529211 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt [junit4] 2> 2529212 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml [junit4] 2> 2529212 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml [junit4] 2> 2529282 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/control-001/cores/collection1 [junit4] 2> 2529284 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 2529286 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@70f9707d{/,null,AVAILABLE} [junit4] 2> 2529290 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.e.j.s.ServerConnector Started ServerConnector@6d28c0f8{SSL,[ssl, http/1.1]}{127.0.0.1:36330} [junit4] 2> 2529290 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.e.j.s.Server Started @2532745ms [junit4] 2> 2529290 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/tempDir-001/control/data, hostContext=/, hostPort=36330, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/control-001/cores} [junit4] 2> 2529291 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93 [junit4] 2> 2529291 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/control-001' [junit4] 2> 2529291 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2529291 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 2529291 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2529292 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2529292 INFO (zkCallback-28435-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@23fd1692 name:ZooKeeperConnection Watcher:127.0.0.1:37272/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2529292 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2529293 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2529293 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 2529294 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/control-001/solr.xml [junit4] 2> 2529302 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/control-001/cores [junit4] 2> 2529303 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.CoreContainer New CoreContainer 1265022026 [junit4] 2> 2529303 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/control-001] [junit4] 2> 2529303 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/control-001/lib [junit4] 2> 2529303 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] 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> 2529304 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 2529304 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 2529304 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 2529304 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37272/solr [junit4] 2> 2529304 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 2529304 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2529305 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2529305 INFO (zkCallback-28438-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@54dcd91d name:ZooKeeperConnection Watcher:127.0.0.1:37272 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2529306 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2529306 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2529307 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$12/192102634@d3f896b [junit4] 2> 2529308 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2529308 INFO (zkCallback-28439-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@41383347 name:ZooKeeperConnection Watcher:127.0.0.1:37272/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2529308 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2529309 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 2529311 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 2529312 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 2529313 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 2529314 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 2529315 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 2529316 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 2529317 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 2529317 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 2529318 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 2529318 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {urlScheme=https} [junit4] 2> 2529318 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 2529320 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 2529320 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 2529321 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 2529321 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96051554647539719-127.0.0.1:36330_-n_0000000000 [junit4] 2> 2529321 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:36330_ [junit4] 2> 2529322 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 2529322 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.Overseer Overseer (id=96051554647539719-127.0.0.1:36330_-n_0000000000) starting [junit4] 2> 2529323 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 2529326 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 2529327 INFO (OverseerCollectionConfigSetProcessor-96051554647539719-127.0.0.1:36330_-n_0000000000) [n:127.0.0.1:36330_ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 2529327 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36330_ [junit4] 2> 2529327 INFO (OverseerStateUpdate-96051554647539719-127.0.0.1:36330_-n_0000000000) [n:127.0.0.1:36330_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 2529328 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:36330_ [junit4] 2> 2529329 INFO (zkCallback-28439-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ ] 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> 2529329 INFO (OverseerStateUpdate-96051554647539719-127.0.0.1:36330_-n_0000000000) [n:127.0.0.1:36330_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2529333 INFO (zkCallback-28439-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (1) [junit4] 2> 2529345 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 2529345 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 2529345 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/control-001/cores [junit4] 2> 2529346 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] 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> 2529346 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/control-001/cores/collection1 [junit4] 2> 2529346 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 2529347 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0 [junit4] 2> 2529347 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 2529347 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:36330_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 2529347 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 2529347 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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> 2529348 INFO (OverseerStateUpdate-96051554647539719-127.0.0.1:36330_-n_0000000000) [n:127.0.0.1:36330_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:36330";, [junit4] 2> "node_name":"127.0.0.1:36330_", [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> 2529348 INFO (OverseerStateUpdate-96051554647539719-127.0.0.1:36330_-n_0000000000) [n:127.0.0.1:36330_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:36330";, [junit4] 2> "node_name":"127.0.0.1:36330_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 2529348 INFO (OverseerStateUpdate-96051554647539719-127.0.0.1:36330_-n_0000000000) [n:127.0.0.1:36330_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 2529348 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Deleting data for [control_collection] [junit4] 2> 2529348 INFO (OverseerStateUpdate-96051554647539719-127.0.0.1:36330_-n_0000000000) [n:127.0.0.1:36330_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 2529349 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 2529349 INFO (zkCallback-28439-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ ] 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> 2530349 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 2530350 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 2530350 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 2530350 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection] [junit4] 2> 2530350 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 2530350 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/control-001/cores/collection1' [junit4] 2> 2530351 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2530351 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 2530352 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 2530355 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 2530357 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.1.0 [junit4] 2> 2530364 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 2530367 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 2530432 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 2530436 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 2530437 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 2530441 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 2530442 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 2530443 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 2530444 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 2530445 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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.1/solr/build/solr-core/test/J0 [junit4] 2> 2530445 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 2530445 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 2530445 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/control-001/cores/collection1/data/] [junit4] 2> 2530445 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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@54a06abe [junit4] 2> 2530446 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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.1/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/control-001/cores/collection1/data [junit4] 2> 2530446 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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.1/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/control-001/cores/collection1/data/index/ [junit4] 2> 2530446 WARN (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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.1/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 2530446 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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.1/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/control-001/cores/collection1/data/index [junit4] 2> 2530446 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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=23, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=15.69921875, floorSegmentMB=0.935546875, forceMergeDeletesPctAllowed=18.480631433658125, segmentsPerTier=11.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6072057377217137 [junit4] 2> 2530448 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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(SimpleFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/index-SimpleFSDirectory-001 lockFactory=org.apache.lucene.store.NativeFSLockFactory@45d6e709),segFN=segments_1,generation=1} [junit4] 2> 2530448 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 2530448 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:36330_ 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.1/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/control-001/cores/collection1/data/ [junit4] 2> 2530449 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 2530451 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 2530451 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 2530451 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 2530451 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 2530451 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 2530451 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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> 2530451 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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> 2530451 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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> 2530451 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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> 2530451 WARN (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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> 2530452 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 2530453 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 2530453 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 2530454 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 2530455 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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> 2530456 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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> 2530456 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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> 2530456 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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> 2530456 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2530457 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2530457 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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=49, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=1.328125, floorSegmentMB=1.322265625, forceMergeDeletesPctAllowed=25.20516728775109, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.427808228958814 [junit4] 2> 2530457 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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(SimpleFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/index-SimpleFSDirectory-001 lockFactory=org.apache.lucene.store.NativeFSLockFactory@45d6e709),segFN=segments_1,generation=1} [junit4] 2> 2530458 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 2530458 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@486f6318[collection1] main] [junit4] 2> 2530458 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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> 2530458 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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> 2530458 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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> 2530458 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 2530458 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 2530458 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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> 2530459 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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> 2530459 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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> 2530459 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 2530459 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 2530459 INFO (searcherExecutor-10753-thread-1-processing-n:127.0.0.1:36330_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@486f6318[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2530459 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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> 2530459 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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> 2530459 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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> 2530459 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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 1536824875815010304 [junit4] 2> 2530461 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ 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 1536824875815010304 [junit4] 2> 2530461 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 2530461 INFO (coreLoadExecutor-10752-thread-1-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 2530462 INFO (coreZkRegister-10746-thread-1-processing-n:127.0.0.1:36330_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address:https://127.0.0.1:36330 collection:control_collection shard:shard1 [junit4] 2> 2530462 INFO (coreZkRegister-10746-thread-1-processing-n:127.0.0.1:36330_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36330_ 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> 2530464 INFO (coreZkRegister-10746-thread-1-processing-n:127.0.0.1:36330_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36330_ 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/96051554647539719-core_node1-n_0000000000 [junit4] 2> 2530464 INFO (coreZkRegister-10746-thread-1-processing-n:127.0.0.1:36330_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36330_ 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> 2530465 INFO (coreZkRegister-10746-thread-1-processing-n:127.0.0.1:36330_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 2530465 INFO (OverseerStateUpdate-96051554647539719-127.0.0.1:36330_-n_0000000000) [n:127.0.0.1:36330_ ] 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> 2530465 INFO (coreZkRegister-10746-thread-1-processing-n:127.0.0.1:36330_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36330_ 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> 2530465 INFO (coreZkRegister-10746-thread-1-processing-n:127.0.0.1:36330_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:36330/collection1/ [junit4] 2> 2530465 INFO (coreZkRegister-10746-thread-1-processing-n:127.0.0.1:36330_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 2530465 INFO (coreZkRegister-10746-thread-1-processing-n:127.0.0.1:36330_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy https://127.0.0.1:36330/collection1/ has no replicas [junit4] 2> 2530465 INFO (coreZkRegister-10746-thread-1-processing-n:127.0.0.1:36330_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 2530466 INFO (coreZkRegister-10746-thread-1-processing-n:127.0.0.1:36330_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36330_ 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/96051554647539719-core_node1-n_0000000000 [junit4] 2> 2530467 INFO (coreZkRegister-10746-thread-1-processing-n:127.0.0.1:36330_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:36330/collection1/ shard1 [junit4] 2> 2530468 INFO (OverseerStateUpdate-96051554647539719-127.0.0.1:36330_-n_0000000000) [n:127.0.0.1:36330_ ] 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":"https://127.0.0.1:36330";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 2530569 INFO (zkCallback-28439-thread-3-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ ] 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> 2530618 INFO (coreZkRegister-10746-thread-1-processing-n:127.0.0.1:36330_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are https://127.0.0.1:36330/collection1/ and leader is https://127.0.0.1:36330/collection1/ [junit4] 2> 2530618 INFO (coreZkRegister-10746-thread-1-processing-n:127.0.0.1:36330_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:36330 [junit4] 2> 2530618 INFO (coreZkRegister-10746-thread-1-processing-n:127.0.0.1:36330_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 2530618 INFO (coreZkRegister-10746-thread-1-processing-n:127.0.0.1:36330_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36330_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 2530619 INFO (coreZkRegister-10746-thread-1-processing-n:127.0.0.1:36330_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36330_ 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> 2530619 INFO (OverseerStateUpdate-96051554647539719-127.0.0.1:36330_-n_0000000000) [n:127.0.0.1:36330_ ] 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":"https://127.0.0.1:36330";, [junit4] 2> "node_name":"127.0.0.1:36330_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 2530619 INFO (OverseerStateUpdate-96051554647539719-127.0.0.1:36330_-n_0000000000) [n:127.0.0.1:36330_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:36330";, [junit4] 2> "node_name":"127.0.0.1:36330_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 2530721 INFO (zkCallback-28439-thread-3-processing-n:127.0.0.1:36330_) [n:127.0.0.1:36330_ ] 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> 2530848 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.s.i.CloudSolrClient Final constructed zkHost string: 127.0.0.1:37272/solr [junit4] 2> 2530848 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2530853 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2530857 INFO (zkCallback-28443-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@c890357 name:ZooKeeperConnection Watcher:127.0.0.1:37272/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2530857 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2530857 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2530857 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 2530861 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {urlScheme=https} [junit4] 2> 2530865 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2530865 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 2530952 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/shard-1-001/cores/collection1 [junit4] 2> 2530952 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/shard-1-001 [junit4] 2> 2530957 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 2530958 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4a03c209{/,null,AVAILABLE} [junit4] 2> 2530959 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.e.j.s.ServerConnector Started ServerConnector@3b58c830{SSL,[ssl, http/1.1]}{127.0.0.1:37061} [junit4] 2> 2530959 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.e.j.s.Server Started @2534414ms [junit4] 2> 2530959 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=37061, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/shard-1-001/cores} [junit4] 2> 2530960 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93 [junit4] 2> 2530960 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/shard-1-001' [junit4] 2> 2530960 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2530960 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 2530960 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2530960 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2530965 INFO (zkCallback-28444-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3b08e15d name:ZooKeeperConnection Watcher:127.0.0.1:37272/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2530965 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2530965 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2530966 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 2530966 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/shard-1-001/solr.xml [junit4] 2> 2530970 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/shard-1-001/cores [junit4] 2> 2530971 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.CoreContainer New CoreContainer 90843200 [junit4] 2> 2530971 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/shard-1-001] [junit4] 2> 2530971 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_F16EC5A43AC01EBA-001/shard-1-001/lib [junit4] 2> 2530971 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] 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> 2530972 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 2530972 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 2530972 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 2530973 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37272/solr [junit4] 2> 2530973 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 2530973 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2530973 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2530974 INFO (zkCallback-28447-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@70ce513a name:ZooKeeperConnection Watcher:127.0.0.1:37272 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2530974 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2530975 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2530975 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:37061_ ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$12/192102634@606a3119 [junit4] 2> 2530976 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:37061_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2530976 INFO (zkCallback-28448-thread-1-processing-n:127.0.0.1:37061_) [n:127.0.0.1:37061_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@d20a1ac name:ZooKeeperConnection Watcher:127.0.0.1:37272/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2530977 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:37061_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2530980 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:37061_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 2530981 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:37061_ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {urlScheme=https} [junit4] 2> 2530981 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:37061_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2530982 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F16EC5A43AC01EBA]) [n:127.0.0.1:37061_ ] o.a.s.c.Overseer Overseer (id=nul [...truncated too long message...] g-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/apacheds-interceptors-collective-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/apacheds-interceptors-event-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/apacheds-interceptors-exception-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/apacheds-interceptors-journal-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/apacheds-interceptors-normalization-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/apacheds-interceptors-operational-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/apacheds-interceptors-referral-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/apacheds-interceptors-schema-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/apacheds-interceptors-subtree-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/apacheds-interceptors-trigger-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/apacheds-jdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/apacheds-jdbm1-2.0.0-M2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/apacheds-kerberos-codec-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/apacheds-ldif-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/apacheds-mavibot-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/apacheds-protocol-kerberos-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/apacheds-protocol-ldap-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/apacheds-protocol-shared-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/apacheds-xdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/api-all-1.0.0-M20.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/bcprov-jdk15-1.45.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/cglib-nodep-2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/commons-collections-3.2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/commons-math3-3.4.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/easymock-3.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/ehcache-core-2.4.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/hadoop-common-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/hadoop-hdfs-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/hadoop-minikdc-2.6.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/jackson-annotations-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/jackson-databind-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/jersey-core-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/jersey-server-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/jetty-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/jetty-util-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/mina-core-2.0.0-M5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/mockito-core-1.9.5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/objenesis-1.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/presto-parser-0.122.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/test-lib/slice-0.10.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/lucene/build/analysis/icu/lucene-analyzers-icu-6.1.0-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/contrib/solr-analysis-extras/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/lucene/build/queryparser/classes/test:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/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.1/solr/build/solr-core/test/temp/junit4-J1-20160611_065118_924.events @/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/temp/junit4-J1-20160611_065118_924.suites -stdin [junit4] ERROR: JVM J1 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.1/build.xml:747: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/build.xml:684: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/common-build.xml:531: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/lucene/common-build.xml:1427: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/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: 289 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: [email protected] For additional commands, e-mail: [email protected]
