Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.x/116/
6 tests failed. FAILED: org.apache.solr.cloud.HttpPartitionTest.test Error Message: Captured an uncaught exception in thread: Thread[id=25190, name=SocketProxy-Response-37204:54204, state=RUNNABLE, group=TGRP-HttpPartitionTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=25190, name=SocketProxy-Response-37204:54204, state=RUNNABLE, group=TGRP-HttpPartitionTest] at __randomizedtesting.SeedInfo.seed([56EEEACA37CCC526:DEBAD5109930A8DE]:0) Caused by: java.lang.RuntimeException: java.net.SocketException: Socket is closed at __randomizedtesting.SeedInfo.seed([56EEEACA37CCC526]:0) at org.apache.solr.cloud.SocketProxy$Bridge$Pump.run(SocketProxy.java:347) Caused by: java.net.SocketException: Socket is closed at java.net.Socket.setSoTimeout(Socket.java:1137) at org.apache.solr.cloud.SocketProxy$Bridge$Pump.run(SocketProxy.java:344) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest Error Message: 1 thread leaked from SUITE scope at org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest: 1) Thread[id=151274, name=searcherExecutor-18176-thread-1, state=WAITING, group=TGRP-HdfsCollectionsAPIDistributedZkTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest: 1) Thread[id=151274, name=searcherExecutor-18176-thread-1, state=WAITING, group=TGRP-HdfsCollectionsAPIDistributedZkTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) at __randomizedtesting.SeedInfo.seed([56EEEACA37CCC526]:0) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest Error Message: There are still zombie threads that couldn't be terminated: 1) Thread[id=151274, name=searcherExecutor-18176-thread-1, state=WAITING, group=TGRP-HdfsCollectionsAPIDistributedZkTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: 1) Thread[id=151274, name=searcherExecutor-18176-thread-1, state=WAITING, group=TGRP-HdfsCollectionsAPIDistributedZkTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) at __randomizedtesting.SeedInfo.seed([56EEEACA37CCC526]:0) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.overseer.ZkStateWriterTest Error Message: 1 thread leaked from SUITE scope at org.apache.solr.cloud.overseer.ZkStateWriterTest: 1) Thread[id=212982, name=watches-5398-thread-1, state=TIMED_WAITING, group=TGRP-ZkStateWriterTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.cloud.overseer.ZkStateWriterTest: 1) Thread[id=212982, name=watches-5398-thread-1, state=TIMED_WAITING, group=TGRP-ZkStateWriterTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) at __randomizedtesting.SeedInfo.seed([56EEEACA37CCC526]:0) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.overseer.ZkStateWriterTest Error Message: There are still zombie threads that couldn't be terminated: 1) Thread[id=212982, name=watches-5398-thread-1, state=TIMED_WAITING, group=TGRP-ZkStateWriterTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: 1) Thread[id=212982, name=watches-5398-thread-1, state=TIMED_WAITING, group=TGRP-ZkStateWriterTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) at __randomizedtesting.SeedInfo.seed([56EEEACA37CCC526]:0) FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Timeout occured while waiting response from server at: http://127.0.0.1:33683/_fj/ar Stack Trace: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:33683/_fj/ar 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:515) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:179) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at java.lang.Thread.run(Thread.java:745) Caused by: java.net.SocketTimeoutException: Read timed out at java.net.SocketInputStream.socketRead0(Native Method) at java.net.SocketInputStream.socketRead(SocketInputStream.java:116) at java.net.SocketInputStream.read(SocketInputStream.java:170) at java.net.SocketInputStream.read(SocketInputStream.java:141) at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160) at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84) at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261) at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251) at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197) at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124) at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:685) at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:487) at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:495) ... 47 more Build Log: [...truncated 12524 lines...] [junit4] Suite: org.apache.solr.cloud.HttpPartitionTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/init-core-data-001 [junit4] 2> 3646183 INFO (SUITE-HttpPartitionTest-seed#[56EEEACA37CCC526]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-5776) [junit4] 2> 3646183 INFO (SUITE-HttpPartitionTest-seed#[56EEEACA37CCC526]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /sno/ [junit4] 2> 3646197 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 3646206 INFO (Thread-10489) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 3646206 INFO (Thread-10489) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 3646306 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.ZkTestServer start zk server on port:42014 [junit4] 2> 3646306 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3646314 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3646328 INFO (zkCallback-4097-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7c3bdcab name:ZooKeeperConnection Watcher:127.0.0.1:42014 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3646328 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3646328 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3646328 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 3646330 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3646338 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3646377 INFO (zkCallback-4098-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@46a3012e name:ZooKeeperConnection Watcher:127.0.0.1:42014/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3646377 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3646377 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3646377 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 3646390 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 3646391 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 3646392 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 3646393 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 3646393 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 3646395 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 3646395 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 3646396 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 3646396 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 3646397 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 3646397 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 3646398 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 3646398 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 3646403 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 3646403 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 3646404 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 3646404 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 3646405 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 3646405 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 3646406 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 3646406 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 3646407 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 3646407 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 3646408 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 3646408 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 3646599 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/control-001/cores/collection1 [junit4] 2> 3646601 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 3646607 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@556bc2b9{/sno,null,AVAILABLE} [junit4] 2> 3646608 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.e.j.s.ServerConnector Started ServerConnector@a3e8d5b{HTTP/1.1,[http/1.1]}{127.0.0.1:43322} [junit4] 2> 3646608 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.e.j.s.Server Started @3650605ms [junit4] 2> 3646608 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/tempDir-001/control/data, hostContext=/sno, hostPort=33828, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/control-001/cores} [junit4] 2> 3646609 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93 [junit4] 2> 3646609 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/control-001' [junit4] 2> 3646609 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3646609 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 3646609 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3646618 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3646630 INFO (zkCallback-4099-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@50c8e771 name:ZooKeeperConnection Watcher:127.0.0.1:42014/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3646634 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3646634 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3646636 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 3646636 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/control-001/solr.xml [junit4] 2> 3646640 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/control-001/cores [junit4] 2> 3646640 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.CoreContainer New CoreContainer 1409477028 [junit4] 2> 3646640 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/control-001] [junit4] 2> 3646641 WARN (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/control-001/lib [junit4] 2> 3646641 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] 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> 3646642 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 3646643 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3646643 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3646643 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42014/solr [junit4] 2> 3646643 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 3646643 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3646655 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3646674 INFO (zkCallback-4102-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@56c02158 name:ZooKeeperConnection Watcher:127.0.0.1:42014 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3646674 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3646675 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3646676 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$34/713325738@5a8e2acb [junit4] 2> 3646690 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3646718 INFO (zkCallback-4103-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1acb7250 name:ZooKeeperConnection Watcher:127.0.0.1:42014/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3646718 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3646719 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 3646720 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 3646722 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 3646723 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 3646725 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 3646725 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 3646726 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 3646727 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 3646728 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 3646729 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 3646729 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 3646729 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 3646731 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 3646732 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 3646732 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 3646733 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96217665882619908-127.0.0.1:33828_sno-n_0000000000 [junit4] 2> 3646733 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:33828_sno [junit4] 2> 3646733 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 3646734 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.Overseer Overseer (id=96217665882619908-127.0.0.1:33828_sno-n_0000000000) starting [junit4] 2> 3646735 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 3646738 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 3646744 INFO (OverseerStateUpdate-96217665882619908-127.0.0.1:33828_sno-n_0000000000) [n:127.0.0.1:33828_sno ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 3646745 INFO (OverseerStateUpdate-96217665882619908-127.0.0.1:33828_sno-n_0000000000) [n:127.0.0.1:33828_sno ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 3646758 INFO (OverseerCollectionConfigSetProcessor-96217665882619908-127.0.0.1:33828_sno-n_0000000000) [n:127.0.0.1:33828_sno ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 3646766 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33828_sno [junit4] 2> 3646766 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:33828_sno [junit4] 2> 3646767 INFO (zkCallback-4103-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno ] 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> 3646768 INFO (zkCallback-4103-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 3646779 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 3646779 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 3646780 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/control-001/cores [junit4] 2> 3646781 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] 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> 3646781 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/control-001/cores/collection1 [junit4] 2> 3646781 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 3646799 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 3646799 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 3646800 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno 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> 3646800 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Deleting data for [control_collection] [junit4] 2> 3646819 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2 [junit4] 2> 3646819 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:33828_sno ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 3646823 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 3646823 INFO (OverseerStateUpdate-96217665882619908-127.0.0.1:33828_sno-n_0000000000) [n:127.0.0.1:33828_sno ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:33828/sno", [junit4] 2> "node_name":"127.0.0.1:33828_sno", [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> 3646823 INFO (OverseerStateUpdate-96217665882619908-127.0.0.1:33828_sno-n_0000000000) [n:127.0.0.1:33828_sno ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:33828/sno", [junit4] 2> "node_name":"127.0.0.1:33828_sno", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 3646824 INFO (OverseerStateUpdate-96217665882619908-127.0.0.1:33828_sno-n_0000000000) [n:127.0.0.1:33828_sno ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 3646824 INFO (OverseerStateUpdate-96217665882619908-127.0.0.1:33828_sno-n_0000000000) [n:127.0.0.1:33828_sno ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 3646825 INFO (zkCallback-4103-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno ] 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> 3647824 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 3647824 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 3647824 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 3647824 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection] [junit4] 2> 3647824 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 3647825 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/control-001/cores/collection1' [junit4] 2> 3647825 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3647825 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 3647826 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 3647830 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 3647833 WARN (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 3647834 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.2.0 [junit4] 2> 3647856 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 3647861 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 3648216 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 3648222 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 3648227 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 3648240 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 3648242 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 3648244 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 3648245 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 3648247 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2 [junit4] 2> 3648247 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 3648247 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 3648247 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/control-001/cores/collection1/data/] [junit4] 2> 3648247 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno 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@6dcf746d [junit4] 2> 3648248 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/control-001/cores/collection1/data [junit4] 2> 3648248 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/control-001/cores/collection1/data/index/ [junit4] 2> 3648248 WARN (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 3648248 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/control-001/cores/collection1/data/index [junit4] 2> 3648248 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=10, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.24721143588410568] [junit4] 2> 3648254 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@619c32e1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3e6f8baa),segFN=segments_1,generation=1} [junit4] 2> 3648254 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 3648266 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 3648266 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 3648266 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 3648266 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 3648266 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 3648266 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno 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> 3648267 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno 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> 3648267 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno 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> 3648267 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno 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> 3648267 WARN (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno 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> 3648269 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 3648269 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 3648270 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 3648271 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 3648273 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno 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,/terms [junit4] 2> 3648273 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno 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> 3648274 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/control-001/cores/collection1/data/ [junit4] 2> 3648274 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 3648288 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno 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> 3648289 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno 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> 3648289 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 3648289 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 3648290 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@1169190 [junit4] 2> 3648290 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@619c32e1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3e6f8baa),segFN=segments_1,generation=1} [junit4] 2> 3648290 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 3648290 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@78f8ae13[collection1] main] [junit4] 2> 3648290 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno 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> 3648291 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno 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> 3648291 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno 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> 3648291 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 3648291 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 3648291 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno 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> 3648292 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno 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> 3648292 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno 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> 3648292 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 3648292 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 3648292 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno 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> 3648292 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno 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> 3648292 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno 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> 3648292 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno 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 1539482656296665088 [junit4] 2> 3648294 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno 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 1539482656296665088 [junit4] 2> 3648294 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 3648294 INFO (coreLoadExecutor-9429-thread-1-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 3648295 INFO (searcherExecutor-9430-thread-1-processing-n:127.0.0.1:33828_sno x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@78f8ae13[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 3648299 INFO (coreZkRegister-9423-thread-1-processing-n:127.0.0.1:33828_sno x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address:http://127.0.0.1:33828/sno collection:control_collection shard:shard1 [junit4] 2> 3648300 INFO (coreZkRegister-9423-thread-1-processing-n:127.0.0.1:33828_sno x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33828_sno 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> 3648302 INFO (coreZkRegister-9423-thread-1-processing-n:127.0.0.1:33828_sno x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33828_sno 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/96217665882619908-core_node1-n_0000000000 [junit4] 2> 3648302 INFO (coreZkRegister-9423-thread-1-processing-n:127.0.0.1:33828_sno x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33828_sno 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=10000 [junit4] 2> 3648304 INFO (OverseerStateUpdate-96217665882619908-127.0.0.1:33828_sno-n_0000000000) [n:127.0.0.1:33828_sno ] 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> 3648305 INFO (coreZkRegister-9423-thread-1-processing-n:127.0.0.1:33828_sno x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 3648305 INFO (coreZkRegister-9423-thread-1-processing-n:127.0.0.1:33828_sno x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33828_sno 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> 3648305 INFO (coreZkRegister-9423-thread-1-processing-n:127.0.0.1:33828_sno x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:33828/sno/collection1/ [junit4] 2> 3648305 INFO (coreZkRegister-9423-thread-1-processing-n:127.0.0.1:33828_sno x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 3648305 INFO (coreZkRegister-9423-thread-1-processing-n:127.0.0.1:33828_sno x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:33828/sno/collection1/ has no replicas [junit4] 2> 3648306 INFO (coreZkRegister-9423-thread-1-processing-n:127.0.0.1:33828_sno x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 3648307 INFO (coreZkRegister-9423-thread-1-processing-n:127.0.0.1:33828_sno x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33828_sno 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/96217665882619908-core_node1-n_0000000000 [junit4] 2> 3648308 INFO (coreZkRegister-9423-thread-1-processing-n:127.0.0.1:33828_sno x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:33828/sno/collection1/ shard1 [junit4] 2> 3648310 INFO (OverseerStateUpdate-96217665882619908-127.0.0.1:33828_sno-n_0000000000) [n:127.0.0.1:33828_sno ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:33828/sno", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 3648323 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3648324 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3648331 INFO (zkCallback-4107-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@44c3604a name:ZooKeeperConnection Watcher:127.0.0.1:42014/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3648331 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3648331 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3648331 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 3648332 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 3648333 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 3648334 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 3648334 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 3648334 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3648335 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3648335 INFO (zkCallback-4108-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@250b1a25 name:ZooKeeperConnection Watcher:127.0.0.1:42014/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3648335 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3648335 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3648342 INFO (OverseerStateUpdate-96217665882619908-127.0.0.1:33828_sno-n_0000000000) [n:127.0.0.1:33828_sno ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "stateFormat":"2"} current state version: 1 [junit4] 2> 3648342 INFO (OverseerStateUpdate-96217665882619908-127.0.0.1:33828_sno-n_0000000000) [n:127.0.0.1:33828_sno ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 3648343 INFO (zkCallback-4103-thread-3-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno ] 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> 3648343 INFO (zkCallback-4107-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1]) [junit4] 2> 3648387 INFO (coreZkRegister-9423-thread-1-processing-n:127.0.0.1:33828_sno x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:33828/sno/collection1/ and leader is http://127.0.0.1:33828/sno/collection1/ [junit4] 2> 3648387 INFO (coreZkRegister-9423-thread-1-processing-n:127.0.0.1:33828_sno x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:33828/sno [junit4] 2> 3648387 INFO (coreZkRegister-9423-thread-1-processing-n:127.0.0.1:33828_sno x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 3648388 INFO (coreZkRegister-9423-thread-1-processing-n:127.0.0.1:33828_sno x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 3648388 INFO (coreZkRegister-9423-thread-1-processing-n:127.0.0.1:33828_sno x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33828_sno 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> 3648389 INFO (coreZkRegister-9423-thread-1-processing-n:127.0.0.1:33828_sno x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33828_sno c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader Checking legacy cluster state for collection control_collection [junit4] 2> 3648391 INFO (OverseerStateUpdate-96217665882619908-127.0.0.1:33828_sno-n_0000000000) [n:127.0.0.1:33828_sno ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:33828/sno", [junit4] 2> "node_name":"127.0.0.1:33828_sno", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 3648391 INFO (OverseerStateUpdate-96217665882619908-127.0.0.1:33828_sno-n_0000000000) [n:127.0.0.1:33828_sno ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:33828/sno", [junit4] 2> "node_name":"127.0.0.1:33828_sno", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 3648391 INFO (OverseerStateUpdate-96217665882619908-127.0.0.1:33828_sno-n_0000000000) [n:127.0.0.1:33828_sno ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json [junit4] 2> 3648497 INFO (zkCallback-4107-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1]) [junit4] 2> 3648497 INFO (zkCallback-4103-thread-3-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno ] 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> 3648529 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/shard-1-001/cores/collection1 [junit4] 2> 3648530 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/shard-1-001 [junit4] 2> 3648530 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 3648598 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3317b57b{/sno,null,AVAILABLE} [junit4] 2> 3648599 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.e.j.s.ServerConnector Started ServerConnector@3f1f98b3{HTTP/1.1,[http/1.1]}{127.0.0.1:57877} [junit4] 2> 3648599 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.e.j.s.Server Started @3652596ms [junit4] 2> 3648599 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/sno, hostPort=32960, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/shard-1-001/cores} [junit4] 2> 3648599 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93 [junit4] 2> 3648599 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/shard-1-001' [junit4] 2> 3648599 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3648599 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 3648600 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3648611 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3648630 INFO (zkCallback-4109-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@895c6e8 name:ZooKeeperConnection Watcher:127.0.0.1:42014/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3648630 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3648631 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3648632 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 3648632 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/shard-1-001/solr.xml [junit4] 2> 3648636 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/shard-1-001/cores [junit4] 2> 3648636 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.CoreContainer New CoreContainer 961673952 [junit4] 2> 3648636 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/shard-1-001] [junit4] 2> 3648636 WARN (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_56EEEACA37CCC526-001/shard-1-001/lib [junit4] 2> 3648637 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] 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> 3648638 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 3648638 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3648638 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3648638 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42014/solr [junit4] 2> 3648638 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 3648638 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3648643 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3648646 INFO (zkCallback-4112-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@456e5aa3 name:ZooKeeperConnection Watcher:127.0.0.1:42014 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3648646 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3648646 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3648648 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:32960_sno ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$34/713325738@59ea33d5 [junit4] 2> 3648651 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:32960_sno ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3648664 INFO (zkCallback-4113-thread-1-processing-n:127.0.0.1:32960_sno) [n:127.0.0.1:32960_sno ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6ae415bf name:ZooKeeperConnection Watcher:127.0.0.1:42014/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3648664 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:32960_sno ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3648676 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:32960_sno ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 3648677 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:32960_sno ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 3648677 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:32960_sno ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 3648679 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:32960_sno ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 3648679 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:32960_sno ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96217665882619913-127.0.0.1:32960_sno-n_0000000001 [junit4] 2> 3648680 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:32960_sno ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/96217665882619908-127.0.0.1:33828_sno-n_0000000000 to know if I could be the leader [junit4] 2> 3648680 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:32960_sno ] o.a.s.c.ZkController Publish node=127.0.0.1:32960_sno as DOWN [junit4] 2> 3648681 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:32960_sno ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:32960_sno [junit4] 2> 3648681 INFO (TEST-HttpPartitionTest.test-seed#[56EEEACA37CCC526]) [n:127.0.0.1:32960_sno ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:32960_sno [junit4] 2> 3648681 INFO (OverseerStateUpdate-96217665882619908-127.0.0.1:33828_sno-n_0000000000) [n:127.0.0.1:33828_sno ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:32960_sno"} current state version: 3 [junit4] 2> 3648682 INFO (OverseerStateUpdate-96217665882619908-127.0.0.1:33828_sno-n_0000000000) [n:127.0.0.1:33828_sno ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:32960_sno [junit4] 2> 3648682 INFO (zkCallback-4107-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1]) [junit4] 2> 3648682 INFO (zkCallback-4103-thread-4-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1]) [junit4] 2> 3648682 INFO (zkCallback-4107-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 3648682 INFO (zkCallback-4103-thread-4-processing-n:127.0.0.1:33828_sno) [n:127.0.0.1:33828_sno ] o.a.s.c.c.ZkStateRe [...truncated too long message...] htlyTests-6.x/solr/core/test-lib/apacheds-interceptors-journal-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-normalization-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-operational-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-referral-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-schema-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-subtree-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-trigger-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-jdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-jdbm1-2.0.0-M2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-kerberos-codec-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-ldif-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-mavibot-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-protocol-kerberos-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-protocol-ldap-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-protocol-shared-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-xdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/api-all-1.0.0-M20.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/bcprov-jdk15-1.45.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/cglib-nodep-2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/commons-collections-3.2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/commons-math3-3.4.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/easymock-3.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/ehcache-core-2.4.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/hadoop-common-2.7.2-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/hadoop-hdfs-2.7.2-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/hadoop-minikdc-2.7.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/htrace-core-3.2.0-incubating.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jackson-annotations-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jackson-core-asl-1.9.13.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jackson-databind-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jackson-mapper-asl-1.9.13.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jersey-core-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jersey-server-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jetty-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jetty-util-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/mina-core-2.0.0-M5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/mockito-core-1.9.5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/netty-all-4.0.36.Final.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/objenesis-1.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/presto-parser-0.122.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/slice-0.10.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/build/analysis/icu/lucene-analyzers-icu-6.2.0-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-analysis-extras/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/build/queryparser/classes/test:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/contrib/analysis-extras/lib/icu4j-56.1.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-launcher.jar:/x1/jenkins/.ant/lib/ivy-2.3.0.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-antlr.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-regexp.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-resolver.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-commons-net.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jmf.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-oro.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-xalan2.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-commons-logging.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jsch.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-junit4.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-javamail.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jai.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jdepend.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-junit.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-bsf.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-swing.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-netrexx.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-bcel.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-testutil.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-log4j.jar:/x1/jenkins/jenkins-slave/tools/hudson.model.JDK/latest1.8/lib/tools.jar:/x1/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.3.4.jar com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/temp/junit4-J1-20160710_143657_515.events @/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/temp/junit4-J1-20160710_143657_515.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.x/build.xml:747: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:684: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/common-build.xml:530: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1443: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:984: At least one slave process threw an exception, first: Quit event not received from the forked process? This may indicate JVM crash or runner bugs. Total time: 316 minutes 47 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts No prior successful build to compare, so performing full copy of artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org