Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/7669/ Java: 32bit/jdk1.8.0_172 -server -XX:+UseConcMarkSweepGC
3 tests failed. FAILED: junit.framework.TestSuite.org.apache.solr.cloud.autoscaling.AutoAddReplicasIntegrationTest Error Message: ObjectTracker found 2 object(s) that were not released!!! [SolrZkClient, ZkStateReader] org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.solr.common.cloud.SolrZkClient at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) at org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:203) at org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:126) at org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:116) at org.apache.solr.common.cloud.ZkStateReader.<init>(ZkStateReader.java:306) at org.apache.solr.client.solrj.impl.ZkClientClusterStateProvider.connect(ZkClientClusterStateProvider.java:160) at org.apache.solr.client.solrj.impl.CloudSolrClient.connect(CloudSolrClient.java:399) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:827) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:817) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:224) at org.apache.solr.client.solrj.impl.SolrClientCloudManager.request(SolrClientCloudManager.java:115) at org.apache.solr.cloud.autoscaling.ExecutePlanAction.waitForTaskToFinish(ExecutePlanAction.java:132) at org.apache.solr.cloud.autoscaling.ExecutePlanAction.process(ExecutePlanAction.java:85) at org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:311) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.solr.common.cloud.ZkStateReader at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) at org.apache.solr.common.cloud.ZkStateReader.<init>(ZkStateReader.java:328) at org.apache.solr.client.solrj.impl.ZkClientClusterStateProvider.connect(ZkClientClusterStateProvider.java:160) at org.apache.solr.client.solrj.impl.CloudSolrClient.connect(CloudSolrClient.java:399) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:827) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:817) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:224) at org.apache.solr.client.solrj.impl.SolrClientCloudManager.request(SolrClientCloudManager.java:115) at org.apache.solr.cloud.autoscaling.ExecutePlanAction.waitForTaskToFinish(ExecutePlanAction.java:132) at org.apache.solr.cloud.autoscaling.ExecutePlanAction.process(ExecutePlanAction.java:85) at org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:311) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) expected null, but was:<ObjectTracker found 2 object(s) that were not released!!! [SolrZkClient, ZkStateReader] org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.solr.common.cloud.SolrZkClient at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) at org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:203) at org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:126) at org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:116) at org.apache.solr.common.cloud.ZkStateReader.<init>(ZkStateReader.java:306) at org.apache.solr.client.solrj.impl.ZkClientClusterStateProvider.connect(ZkClientClusterStateProvider.java:160) at org.apache.solr.client.solrj.impl.CloudSolrClient.connect(CloudSolrClient.java:399) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:827) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:817) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:224) at org.apache.solr.client.solrj.impl.SolrClientCloudManager.request(SolrClientCloudManager.java:115) at org.apache.solr.cloud.autoscaling.ExecutePlanAction.waitForTaskToFinish(ExecutePlanAction.java:132) at org.apache.solr.cloud.autoscaling.ExecutePlanAction.process(ExecutePlanAction.java:85) at org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:311) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.solr.common.cloud.ZkStateReader at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) at org.apache.solr.common.cloud.ZkStateReader.<init>(ZkStateReader.java:328) at org.apache.solr.client.solrj.impl.ZkClientClusterStateProvider.connect(ZkClientClusterStateProvider.java:160) at org.apache.solr.client.solrj.impl.CloudSolrClient.connect(CloudSolrClient.java:399) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:827) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:817) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:224) at org.apache.solr.client.solrj.impl.SolrClientCloudManager.request(SolrClientCloudManager.java:115) at org.apache.solr.cloud.autoscaling.ExecutePlanAction.waitForTaskToFinish(ExecutePlanAction.java:132) at org.apache.solr.cloud.autoscaling.ExecutePlanAction.process(ExecutePlanAction.java:85) at org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:311) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) > Stack Trace: java.lang.AssertionError: ObjectTracker found 2 object(s) that were not released!!! [SolrZkClient, ZkStateReader] org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.solr.common.cloud.SolrZkClient at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) at org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:203) at org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:126) at org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:116) at org.apache.solr.common.cloud.ZkStateReader.<init>(ZkStateReader.java:306) at org.apache.solr.client.solrj.impl.ZkClientClusterStateProvider.connect(ZkClientClusterStateProvider.java:160) at org.apache.solr.client.solrj.impl.CloudSolrClient.connect(CloudSolrClient.java:399) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:827) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:817) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:224) at org.apache.solr.client.solrj.impl.SolrClientCloudManager.request(SolrClientCloudManager.java:115) at org.apache.solr.cloud.autoscaling.ExecutePlanAction.waitForTaskToFinish(ExecutePlanAction.java:132) at org.apache.solr.cloud.autoscaling.ExecutePlanAction.process(ExecutePlanAction.java:85) at org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:311) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.solr.common.cloud.ZkStateReader at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) at org.apache.solr.common.cloud.ZkStateReader.<init>(ZkStateReader.java:328) at org.apache.solr.client.solrj.impl.ZkClientClusterStateProvider.connect(ZkClientClusterStateProvider.java:160) at org.apache.solr.client.solrj.impl.CloudSolrClient.connect(CloudSolrClient.java:399) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:827) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:817) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:224) at org.apache.solr.client.solrj.impl.SolrClientCloudManager.request(SolrClientCloudManager.java:115) at org.apache.solr.cloud.autoscaling.ExecutePlanAction.waitForTaskToFinish(ExecutePlanAction.java:132) at org.apache.solr.cloud.autoscaling.ExecutePlanAction.process(ExecutePlanAction.java:85) at org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:311) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) expected null, but was:<ObjectTracker found 2 object(s) that were not released!!! [SolrZkClient, ZkStateReader] org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.solr.common.cloud.SolrZkClient at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) at org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:203) at org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:126) at org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:116) at org.apache.solr.common.cloud.ZkStateReader.<init>(ZkStateReader.java:306) at org.apache.solr.client.solrj.impl.ZkClientClusterStateProvider.connect(ZkClientClusterStateProvider.java:160) at org.apache.solr.client.solrj.impl.CloudSolrClient.connect(CloudSolrClient.java:399) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:827) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:817) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:224) at org.apache.solr.client.solrj.impl.SolrClientCloudManager.request(SolrClientCloudManager.java:115) at org.apache.solr.cloud.autoscaling.ExecutePlanAction.waitForTaskToFinish(ExecutePlanAction.java:132) at org.apache.solr.cloud.autoscaling.ExecutePlanAction.process(ExecutePlanAction.java:85) at org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:311) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.solr.common.cloud.ZkStateReader at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) at org.apache.solr.common.cloud.ZkStateReader.<init>(ZkStateReader.java:328) at org.apache.solr.client.solrj.impl.ZkClientClusterStateProvider.connect(ZkClientClusterStateProvider.java:160) at org.apache.solr.client.solrj.impl.CloudSolrClient.connect(CloudSolrClient.java:399) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:827) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:817) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:224) at org.apache.solr.client.solrj.impl.SolrClientCloudManager.request(SolrClientCloudManager.java:115) at org.apache.solr.cloud.autoscaling.ExecutePlanAction.waitForTaskToFinish(ExecutePlanAction.java:132) at org.apache.solr.cloud.autoscaling.ExecutePlanAction.process(ExecutePlanAction.java:85) at org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:311) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) > at __randomizedtesting.SeedInfo.seed([FB50140163370A04]:0) at org.junit.Assert.fail(Assert.java:88) at org.junit.Assert.failNotNull(Assert.java:755) at org.junit.Assert.assertNull(Assert.java:737) at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:319) at sun.reflect.GeneratedMethodAccessor39.invoke(Unknown Source) 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:1750) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:901) 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:368) at java.lang.Thread.run(Thread.java:748) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.autoscaling.AutoAddReplicasIntegrationTest Error Message: 3 threads leaked from SUITE scope at org.apache.solr.cloud.autoscaling.AutoAddReplicasIntegrationTest: 1) Thread[id=34643, name=AutoscalingActionExecutor-10892-thread-1-EventThread, state=WAITING, group=TGRP-AutoAddReplicasIntegrationTest] 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 org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:504) 2) Thread[id=34642, name=AutoscalingActionExecutor-10892-thread-1-SendThread(127.0.0.1:51683), state=TIMED_WAITING, group=TGRP-AutoAddReplicasIntegrationTest] at java.lang.Thread.sleep(Native Method) at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1049) 3) Thread[id=34644, name=zkConnectionManagerCallback-10496-thread-1, state=WAITING, group=TGRP-AutoAddReplicasIntegrationTest] 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:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 3 threads leaked from SUITE scope at org.apache.solr.cloud.autoscaling.AutoAddReplicasIntegrationTest: 1) Thread[id=34643, name=AutoscalingActionExecutor-10892-thread-1-EventThread, state=WAITING, group=TGRP-AutoAddReplicasIntegrationTest] 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 org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:504) 2) Thread[id=34642, name=AutoscalingActionExecutor-10892-thread-1-SendThread(127.0.0.1:51683), state=TIMED_WAITING, group=TGRP-AutoAddReplicasIntegrationTest] at java.lang.Thread.sleep(Native Method) at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1049) 3) Thread[id=34644, name=zkConnectionManagerCallback-10496-thread-1, state=WAITING, group=TGRP-AutoAddReplicasIntegrationTest] 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:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) at __randomizedtesting.SeedInfo.seed([FB50140163370A04]:0) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.autoscaling.AutoAddReplicasIntegrationTest Error Message: There are still zombie threads that couldn't be terminated: 1) Thread[id=34642, name=AutoscalingActionExecutor-10892-thread-1-SendThread(127.0.0.1:51683), state=RUNNABLE, group=TGRP-AutoAddReplicasIntegrationTest] at sun.nio.ch.WindowsSelectorImpl$SubSelector.poll0(Native Method) at sun.nio.ch.WindowsSelectorImpl$SubSelector.poll(WindowsSelectorImpl.java:296) at sun.nio.ch.WindowsSelectorImpl$SubSelector.access$400(WindowsSelectorImpl.java:278) at sun.nio.ch.WindowsSelectorImpl.doSelect(WindowsSelectorImpl.java:159) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:349) at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1145) 2) Thread[id=34644, name=zkConnectionManagerCallback-10496-thread-1, state=WAITING, group=TGRP-AutoAddReplicasIntegrationTest] 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:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: 1) Thread[id=34642, name=AutoscalingActionExecutor-10892-thread-1-SendThread(127.0.0.1:51683), state=RUNNABLE, group=TGRP-AutoAddReplicasIntegrationTest] at sun.nio.ch.WindowsSelectorImpl$SubSelector.poll0(Native Method) at sun.nio.ch.WindowsSelectorImpl$SubSelector.poll(WindowsSelectorImpl.java:296) at sun.nio.ch.WindowsSelectorImpl$SubSelector.access$400(WindowsSelectorImpl.java:278) at sun.nio.ch.WindowsSelectorImpl.doSelect(WindowsSelectorImpl.java:159) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:349) at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1145) 2) Thread[id=34644, name=zkConnectionManagerCallback-10496-thread-1, state=WAITING, group=TGRP-AutoAddReplicasIntegrationTest] 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:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) at __randomizedtesting.SeedInfo.seed([FB50140163370A04]:0) Build Log: [...truncated 14237 lines...] [junit4] Suite: org.apache.solr.cloud.autoscaling.AutoAddReplicasIntegrationTest [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.AutoAddReplicasIntegrationTest_FB50140163370A04-001\init-core-data-001 [junit4] 2> 1811939 INFO (TEST-AutoAddReplicasIntegrationTest.testSimple-seed#[FB50140163370A04]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.AutoAddReplicasIntegrationTest_FB50140163370A04-001\tempDir-001 [junit4] 2> 1811940 INFO (TEST-AutoAddReplicasIntegrationTest.testSimple-seed#[FB50140163370A04]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1811941 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1811941 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1812048 INFO (TEST-AutoAddReplicasIntegrationTest.testSimple-seed#[FB50140163370A04]) [ ] o.a.s.c.ZkTestServer start zk server on port:51683 [junit4] 2> 1812048 INFO (TEST-AutoAddReplicasIntegrationTest.testSimple-seed#[FB50140163370A04]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:51683 [junit4] 2> 1812048 INFO (TEST-AutoAddReplicasIntegrationTest.testSimple-seed#[FB50140163370A04]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 51683 [junit4] 2> 1812063 INFO (zkConnectionManagerCallback-10366-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1812070 INFO (zkConnectionManagerCallback-10368-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1812075 INFO (zkConnectionManagerCallback-10370-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1812078 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x100340b13940002, likely client has closed socket [junit4] 2> 1812084 WARN (jetty-launcher-10371-thread-3) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 1812084 INFO (jetty-launcher-10371-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 1812084 INFO (jetty-launcher-10371-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 1812084 INFO (jetty-launcher-10371-thread-3) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_172-b11 [junit4] 2> 1812085 INFO (jetty-launcher-10371-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1812085 INFO (jetty-launcher-10371-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1812085 INFO (jetty-launcher-10371-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 1812085 INFO (jetty-launcher-10371-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1b54048{/solr,null,AVAILABLE} [junit4] 2> 1812086 WARN (jetty-launcher-10371-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@153b6d7[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1812088 WARN (jetty-launcher-10371-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 1812088 INFO (jetty-launcher-10371-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 1812088 INFO (jetty-launcher-10371-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 1812088 INFO (jetty-launcher-10371-thread-1) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_172-b11 [junit4] 2> 1812090 INFO (jetty-launcher-10371-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1812090 INFO (jetty-launcher-10371-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1812090 INFO (jetty-launcher-10371-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 1812091 INFO (jetty-launcher-10371-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@87251f{/solr,null,AVAILABLE} [junit4] 2> 1812092 WARN (jetty-launcher-10371-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@7634c9[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1812106 INFO (jetty-launcher-10371-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@13b35{SSL,[ssl, http/1.1]}{127.0.0.1:51694} [junit4] 2> 1812106 INFO (jetty-launcher-10371-thread-3) [ ] o.e.j.s.Server Started @1791772ms [junit4] 2> 1812106 INFO (jetty-launcher-10371-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=51694} [junit4] 2> 1812108 ERROR (jetty-launcher-10371-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1812108 INFO (jetty-launcher-10371-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1812108 INFO (jetty-launcher-10371-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 1812108 INFO (jetty-launcher-10371-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1812108 INFO (jetty-launcher-10371-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1812108 INFO (jetty-launcher-10371-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-12-31T09:08:35.405Z [junit4] 2> 1812117 WARN (jetty-launcher-10371-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 1812121 INFO (jetty-launcher-10371-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 1812121 INFO (jetty-launcher-10371-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 1812122 INFO (jetty-launcher-10371-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1fe901d{SSL,[ssl, http/1.1]}{127.0.0.1:51697} [junit4] 2> 1812122 INFO (jetty-launcher-10371-thread-1) [ ] o.e.j.s.Server Started @1791785ms [junit4] 2> 1812122 INFO (jetty-launcher-10371-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=51697} [junit4] 2> 1812123 ERROR (jetty-launcher-10371-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1812123 INFO (jetty-launcher-10371-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1812123 INFO (jetty-launcher-10371-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 1812123 INFO (jetty-launcher-10371-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1812123 INFO (jetty-launcher-10371-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1812124 INFO (jetty-launcher-10371-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-12-31T09:08:35.420Z [junit4] 2> 1812127 INFO (jetty-launcher-10371-thread-2) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_172-b11 [junit4] 2> 1812129 INFO (jetty-launcher-10371-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1812129 INFO (jetty-launcher-10371-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1812129 INFO (jetty-launcher-10371-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 1812130 INFO (jetty-launcher-10371-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@16c5938{/solr,null,AVAILABLE} [junit4] 2> 1812131 WARN (jetty-launcher-10371-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1b03f80[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1812137 INFO (jetty-launcher-10371-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2877ed{SSL,[ssl, http/1.1]}{127.0.0.1:51704} [junit4] 2> 1812137 INFO (jetty-launcher-10371-thread-2) [ ] o.e.j.s.Server Started @1791800ms [junit4] 2> 1812137 INFO (jetty-launcher-10371-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=51704} [junit4] 2> 1812138 ERROR (jetty-launcher-10371-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1812138 INFO (jetty-launcher-10371-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1812138 INFO (jetty-launcher-10371-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 1812138 INFO (jetty-launcher-10371-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1812138 INFO (jetty-launcher-10371-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1812138 INFO (jetty-launcher-10371-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-12-31T09:08:35.435Z [junit4] 2> 1812148 INFO (zkConnectionManagerCallback-10373-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1812152 INFO (jetty-launcher-10371-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1812154 INFO (zkConnectionManagerCallback-10375-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1812156 INFO (jetty-launcher-10371-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1812162 INFO (jetty-launcher-10371-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 1812162 INFO (jetty-launcher-10371-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 1812162 INFO (jetty-launcher-10371-thread-3) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@ca704c, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 1812190 INFO (jetty-launcher-10371-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 1812190 INFO (jetty-launcher-10371-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 1812191 INFO (jetty-launcher-10371-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@ca704c, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 1812382 INFO (zkConnectionManagerCallback-10377-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1812384 INFO (jetty-launcher-10371-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1812398 INFO (jetty-launcher-10371-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 1812398 INFO (jetty-launcher-10371-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 1812404 INFO (jetty-launcher-10371-thread-2) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@ca704c, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 1812998 WARN (jetty-launcher-10371-thread-3) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 1813003 WARN (jetty-launcher-10371-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@99cd3[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1813008 WARN (jetty-launcher-10371-thread-3) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 1813010 WARN (jetty-launcher-10371-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1afc31f[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1813010 INFO (jetty-launcher-10371-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51683/solr [junit4] 2> 1813022 INFO (zkConnectionManagerCallback-10383-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1813023 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x100340b13940006, likely client has closed socket [junit4] 2> 1813023 DEBUG (jetty-launcher-10371-thread-3) [n:127.0.0.1:51694_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$284/21730350@bb4287 [junit4] 2> 1813027 INFO (zkConnectionManagerCallback-10385-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1813062 WARN (jetty-launcher-10371-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 1813064 WARN (jetty-launcher-10371-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@18c13a4[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1813066 WARN (jetty-launcher-10371-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 1813068 WARN (jetty-launcher-10371-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1d3b8d5[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1813069 INFO (jetty-launcher-10371-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51683/solr [junit4] 2> 1813071 INFO (zkConnectionManagerCallback-10393-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1813072 DEBUG (jetty-launcher-10371-thread-1) [n:127.0.0.1:51697_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$284/21730350@1e7a6d9 [junit4] 2> 1813075 INFO (zkConnectionManagerCallback-10395-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1813079 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 1813079 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1813081 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 1813081 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 1813081 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 1813082 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/security.json [junit4] 2> 1813083 DEBUG (jetty-launcher-10371-thread-1) [n:127.0.0.1:51697_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/72114816215678985-127.0.0.1:51697_solr-n_0000000000 [junit4] 2> 1813083 INFO (jetty-launcher-10371-thread-1) [n:127.0.0.1:51697_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:51697_solr [junit4] 2> 1813084 INFO (jetty-launcher-10371-thread-1) [n:127.0.0.1:51697_solr ] o.a.s.c.Overseer Overseer (id=72114816215678985-127.0.0.1:51697_solr-n_0000000000) starting [junit4] 2> 1813092 INFO (zkConnectionManagerCallback-10402-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1813093 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 1813093 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1813094 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 1813094 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 1813094 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 1813094 INFO (jetty-launcher-10371-thread-1) [n:127.0.0.1:51697_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:51683/solr ready [junit4] 2> 1813095 INFO (jetty-launcher-10371-thread-1) [n:127.0.0.1:51697_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51697_solr [junit4] 2> 1813095 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 1813095 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 1813108 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 1813108 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1813108 INFO (jetty-launcher-10371-thread-3) [n:127.0.0.1:51694_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1813108 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 1813108 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 1813108 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 1813108 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/security.json [junit4] 2> 1813110 DEBUG (jetty-launcher-10371-thread-3) [n:127.0.0.1:51694_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/72114816215678983-127.0.0.1:51694_solr-n_0000000001 [junit4] 2> 1813110 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/overseer_elect/election/72114816215678985-127.0.0.1:51697_solr-n_0000000000 [junit4] 2> 1813110 DEBUG (jetty-launcher-10371-thread-3) [n:127.0.0.1:51694_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/72114816215678985-127.0.0.1:51697_solr-n_0000000000 to know if I could be the leader [junit4] 2> 1813110 INFO (jetty-launcher-10371-thread-3) [n:127.0.0.1:51694_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:51694_solr as DOWN [junit4] 2> 1813111 INFO (jetty-launcher-10371-thread-3) [n:127.0.0.1:51694_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 1813111 INFO (jetty-launcher-10371-thread-3) [n:127.0.0.1:51694_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51694_solr [junit4] 2> 1813111 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 1813133 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1813135 INFO (zkCallback-10384-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1813135 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1813135 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1813136 DEBUG (OverseerCollectionConfigSetProcessor-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 1813136 INFO (zkCallback-10401-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1813136 INFO (zkCallback-10394-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1813151 INFO (jetty-launcher-10371-thread-1) [n:127.0.0.1:51697_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1813156 DEBUG (OverseerAutoScalingTriggerThread-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers [junit4] 2> 1813162 INFO (OverseerStateUpdate-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:51697_solr [junit4] 2> 1813163 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/autoscaling.json [junit4] 2> 1813163 DEBUG (OverseerAutoScalingTriggerThread-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1 [junit4] 2> 1813163 DEBUG (OverseerAutoScalingTriggerThread-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0 [junit4] 2> 1813163 DEBUG (OverseerAutoScalingTriggerThread-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1 [junit4] 2> 1813164 DEBUG (OverseerCollectionConfigSetProcessor-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 0 #Completed tasks: 0 [junit4] 2> 1813164 DEBUG (OverseerCollectionConfigSetProcessor-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.OverseerTaskProcessor RunningTasks: [] [junit4] 2> 1813164 DEBUG (OverseerCollectionConfigSetProcessor-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 1813164 DEBUG (OverseerCollectionConfigSetProcessor-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [] [junit4] 2> 1813164 INFO (OverseerCollectionConfigSetProcessor-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [] [junit4] 2> 1813164 DEBUG (OverseerCollectionConfigSetProcessor-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.OverseerTaskQueue Peeking for top 100 elements. ExcludeSet: [] [junit4] 2> 1813165 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/collection-queue-work [junit4] 2> 1813166 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue-work [junit4] 2> 1813166 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 1813166 DEBUG (OverseerStateUpdate-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:51694_solr"} current state version: 0 [junit4] 2> 1813166 DEBUG (OverseerStateUpdate-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:51694_solr [junit4] 2> 1813167 DEBUG (OverseerAutoScalingTriggerThread-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:51697_solr, 127.0.0.1:51694_solr] [junit4] 2> 1813168 DEBUG (OverseerAutoScalingTriggerThread-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- cleaning old nodeLost / nodeAdded markers [junit4] 2> 1813168 DEBUG (OverseerAutoScalingTriggerThread-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1 [junit4] 2> 1813201 INFO (jetty-launcher-10371-thread-1) [n:127.0.0.1:51697_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ca704c [junit4] 2> 1813206 INFO (jetty-launcher-10371-thread-1) [n:127.0.0.1:51697_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ca704c [junit4] 2> 1813206 INFO (jetty-launcher-10371-thread-1) [n:127.0.0.1:51697_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ca704c [junit4] 2> 1813207 INFO (jetty-launcher-10371-thread-1) [n:127.0.0.1:51697_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.AutoAddReplicasIntegrationTest_FB50140163370A04-001\tempDir-001\node2\. [junit4] 2> 1813222 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/autoscaling/events/.scheduled_maintenance [junit4] 2> 1813222 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/autoscaling/events/.auto_add_replicas [junit4] 2> 1813236 DEBUG (ScheduledTrigger-10744-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2 [junit4] 2> 1813238 INFO (zkConnectionManagerCallback-10408-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1813238 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 1813239 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1813239 INFO (jetty-launcher-10371-thread-3) [n:127.0.0.1:51694_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1813239 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 1813239 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 1813239 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 1813240 INFO (jetty-launcher-10371-thread-3) [n:127.0.0.1:51694_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:51683/solr ready [junit4] 2> 1813240 INFO (jetty-launcher-10371-thread-3) [n:127.0.0.1:51694_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1813254 INFO (jetty-launcher-10371-thread-3) [n:127.0.0.1:51694_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ca704c [junit4] 2> 1813260 INFO (jetty-launcher-10371-thread-3) [n:127.0.0.1:51694_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ca704c [junit4] 2> 1813260 INFO (jetty-launcher-10371-thread-3) [n:127.0.0.1:51694_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ca704c [junit4] 2> 1813262 INFO (jetty-launcher-10371-thread-3) [n:127.0.0.1:51694_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.AutoAddReplicasIntegrationTest_FB50140163370A04-001\tempDir-001\node3\. [junit4] 2> 1813288 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 1813289 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 1813427 WARN (jetty-launcher-10371-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 1813428 WARN (jetty-launcher-10371-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@6f472[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1813433 WARN (jetty-launcher-10371-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 1813434 WARN (jetty-launcher-10371-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@8e10a6[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1813435 INFO (jetty-launcher-10371-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51683/solr [junit4] 2> 1813437 INFO (zkConnectionManagerCallback-10415-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1813439 DEBUG (jetty-launcher-10371-thread-2) [n:127.0.0.1:51704_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$284/21730350@16cc965 [junit4] 2> 1813439 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x100340b1394000c, likely client has closed socket [junit4] 2> 1813441 INFO (zkConnectionManagerCallback-10417-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1813446 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 1813446 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1813446 INFO (jetty-launcher-10371-thread-2) [n:127.0.0.1:51704_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1813446 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 1813446 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 1813447 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 1813447 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/security.json [junit4] 2> 1813448 DEBUG (jetty-launcher-10371-thread-2) [n:127.0.0.1:51704_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/72114816215678989-127.0.0.1:51704_solr-n_0000000002 [junit4] 2> 1813449 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/overseer_elect/election/72114816215678983-127.0.0.1:51694_solr-n_0000000001 [junit4] 2> 1813449 DEBUG (jetty-launcher-10371-thread-2) [n:127.0.0.1:51704_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/72114816215678983-127.0.0.1:51694_solr-n_0000000001 to know if I could be the leader [junit4] 2> 1813449 INFO (jetty-launcher-10371-thread-2) [n:127.0.0.1:51704_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:51704_solr as DOWN [junit4] 2> 1813449 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 1813449 INFO (jetty-launcher-10371-thread-2) [n:127.0.0.1:51704_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 1813449 INFO (jetty-launcher-10371-thread-2) [n:127.0.0.1:51704_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51704_solr [junit4] 2> 1813450 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 1813450 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 1813450 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 1813450 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 1813450 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 1813450 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 1813450 DEBUG (OverseerStateUpdate-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:51704_solr"} current state version: 0 [junit4] 2> 1813450 DEBUG (OverseerStateUpdate-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:51704_solr [junit4] 2> 1813450 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1813452 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1813452 INFO (zkCallback-10384-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1813452 INFO (zkCallback-10394-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1813452 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1813452 INFO (zkCallback-10401-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1813452 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1813452 INFO (zkCallback-10407-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1813452 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1813453 INFO (zkCallback-10416-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1813504 INFO (zkConnectionManagerCallback-10424-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1813505 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 1813505 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1813505 INFO (jetty-launcher-10371-thread-2) [n:127.0.0.1:51704_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 1813505 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 1813506 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 1813506 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 1813506 INFO (jetty-launcher-10371-thread-2) [n:127.0.0.1:51704_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:51683/solr ready [junit4] 2> 1813506 INFO (jetty-launcher-10371-thread-2) [n:127.0.0.1:51704_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1813531 INFO (jetty-launcher-10371-thread-2) [n:127.0.0.1:51704_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ca704c [junit4] 2> 1813537 INFO (jetty-launcher-10371-thread-2) [n:127.0.0.1:51704_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ca704c [junit4] 2> 1813537 INFO (jetty-launcher-10371-thread-2) [n:127.0.0.1:51704_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ca704c [junit4] 2> 1813539 INFO (jetty-launcher-10371-thread-2) [n:127.0.0.1:51704_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.AutoAddReplicasIntegrationTest_FB50140163370A04-001\tempDir-001\node1\. [junit4] 2> 1813559 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 1813560 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 1813586 INFO (zkConnectionManagerCallback-10430-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1813587 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 1813587 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1813587 INFO (TEST-AutoAddReplicasIntegrationTest.testSimple-seed#[FB50140163370A04]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 1813587 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 1813587 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 1813588 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 1813588 INFO (TEST-AutoAddReplicasIntegrationTest.testSimple-seed#[FB50140163370A04]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:51683/solr ready [junit4] 2> 1813641 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: data: /solr/clusterprops.json [junit4] 2> 1813642 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: data: /solr/clusterprops.json [junit4] 2> 1813642 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: data: /solr/clusterprops.json [junit4] 2> 1813642 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: data: /solr/clusterprops.json [junit4] 2> 1813642 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: data: /solr/clusterprops.json [junit4] 2> 1813642 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: data: /solr/clusterprops.json [junit4] 2> 1813642 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: data: /solr/clusterprops.json [junit4] 2> 1813642 INFO (qtp17595912-34071) [n:127.0.0.1:51697_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster params={wt=javabin&version=2} status=0 QTime=5 [junit4] 2> 1813643 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 1813643 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 1813643 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 1813643 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 1813644 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 1813645 INFO (qtp17595912-34072) [n:127.0.0.1:51697_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&maxShardsPerNode=2&autoAddReplicas=true&name=testSimple1&nrtReplicas=2&action=CREATE&numShards=2&createNodeSet=127.0.0.1:51697_solr,127.0.0.1:51694_solr&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1813645 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 1813645 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 1813646 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/collection-queue-work [junit4] 2> 1813648 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/overseer/collection-queue-work/qnr-0000000000 [junit4] 2> 1813648 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/collection-queue-work [junit4] 2> 1813648 DEBUG (OverseerCollectionConfigSetProcessor-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.ZkDistributedQueue Found child node with improper name: qnr-0000000000 [junit4] 2> 1813648 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/collection-queue-work [junit4] 2> 1813648 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/collection-queue-work [junit4] 2> 1813648 DEBUG (OverseerCollectionConfigSetProcessor-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.ZkDistributedQueue Found child node with improper name: qnr-0000000000 [junit4] 2> 1813654 DEBUG (OverseerCollectionConfigSetProcessor-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.OverseerTaskQueue Returning topN elements: [/overseer/collection-queue-work/qn-0000000000, ] [junit4] 2> 1813654 DEBUG (OverseerCollectionConfigSetProcessor-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.OverseerTaskProcessor Got 1 tasks from work-queue : [[org.apache.solr.cloud.OverseerTaskQueue$QueueEvent@2e684ecf]] [junit4] 2> 1813654 DEBUG (OverseerCollectionConfigSetProcessor-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.OverseerTaskProcessor Marked task [/overseer/collection-queue-work/qn-0000000000] as running [junit4] 2> 1813654 DEBUG (OverseerCollectionConfigSetProcessor-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.OverseerTaskProcessor Overseer Collection Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000000 message:{ [junit4] 2> "name":"testSimple1", [junit4] 2> "fromApi":"true", [junit4] 2> "collection.configName":"conf", [junit4] 2> "numShards":"2", [junit4] 2> "maxShardsPerNode":"2", [junit4] 2> "createNodeSet":"127.0.0.1:51697_solr,127.0.0.1:51694_solr", [junit4] 2> "autoAddReplicas":"true", [junit4] 2> "nrtReplicas":"2", [junit4] 2> "stateFormat":"2", [junit4] 2> "replicationFactor":"2", [junit4] 2> "operation":"create"} [junit4] 2> 1813654 DEBUG (OverseerThreadFactory-10750-thread-1-processing-n:127.0.0.1:51697_solr) [n:127.0.0.1:51697_solr ] o.a.s.c.OverseerTaskProcessor Runner processing /overseer/collection-queue-work/qn-0000000000 [junit4] 2> 1813654 DEBUG (OverseerThreadFactory-10750-thread-1-processing-n:127.0.0.1:51697_solr) [n:127.0.0.1:51697_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler OverseerCollectionMessageHandler.processMessage : create , { [junit4] 2> "name":"testSimple1", [junit4] 2> "fromApi":"true", [junit4] 2> "collection.configName":"conf", [junit4] 2> "numShards":"2", [junit4] 2> "maxShardsPerNode":"2", [junit4] 2> "createNodeSet":"127.0.0.1:51697_solr,127.0.0.1:51694_solr", [junit4] 2> "autoAddReplicas":"true", [junit4] 2> "nrtReplicas":"2", [junit4] 2> "stateFormat":"2", [junit4] 2> "replicationFactor":"2", [junit4] 2> "operation":"create"} [junit4] 2> 1813654 INFO (OverseerThreadFactory-10750-thread-1-processing-n:127.0.0.1:51697_solr) [n:127.0.0.1:51697_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testSimple1 [junit4] 2> 1813654 DEBUG (OverseerCollectionConfigSetProcessor-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 1 #Completed tasks: 0 [junit4] 2> 1813654 DEBUG (OverseerCollectionConfigSetProcessor-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.OverseerTaskProcessor RunningTasks: [/overseer/collection-queue-work/qn-0000000000] [junit4] 2> 1813654 DEBUG (OverseerCollectionConfigSetProcessor-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 1813654 DEBUG (OverseerCollectionConfigSetProcessor-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [] [junit4] 2> 1813654 INFO (OverseerCollectionConfigSetProcessor-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [/overseer/collection-queue-work/qn-0000000000] [junit4] 2> 1813654 DEBUG (OverseerCollectionConfigSetProcessor-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.OverseerTaskQueue Peeking for top 99 elements. ExcludeSet: [/overseer/collection-queue-work/qn-0000000000],[] [junit4] 2> 1813655 DEBUG (OverseerThreadFactory-10750-thread-1-processing-n:127.0.0.1:51697_solr) [n:127.0.0.1:51697_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler creating collections conf node /collections/testSimple1 [junit4] 2> 1813659 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/collections [junit4] 2> 1813659 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/collections [junit4] 2> 1813659 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/collections [junit4] 2> 1813659 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/collections [junit4] 2> 1813659 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/collections [junit4] 2> 1813659 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/collections [junit4] 2> 1813659 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/collections [junit4] 2> 1813660 DEBUG (OverseerThreadFactory-10750-thread-1-processing-n:127.0.0.1:51697_solr) [n:127.0.0.1:51697_solr ] o.a.s.c.a.c.CreateCollectionCmd Check for collection zkNode:testSimple1 [junit4] 2> 1813660 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 1813660 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 1813661 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 1813661 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 1813661 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 1813661 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 1813662 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 1813662 DEBUG (OverseerThreadFactory-10750-thread-1-processing-n:127.0.0.1:51697_solr) [n:127.0.0.1:51697_solr ] o.a.s.c.a.c.CreateCollectionCmd Collection zkNode exists [junit4] 2> 1813664 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 1813667 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 1813669 DEBUG (OverseerStateUpdate-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "name":"testSimple1", [junit4] 2> "fromApi":"true", [junit4] 2> "collection.configName":"conf", [junit4] 2> "numShards":"2", [junit4] 2> "maxShardsPerNode":"2", [junit4] 2> "createNodeSet":"127.0.0.1:51697_solr,127.0.0.1:51694_solr", [junit4] 2> "autoAddReplicas":"true", [junit4] 2> "nrtReplicas":"2", [junit4] 2> "stateFormat":"2", [junit4] 2> "replicationFactor":"2", [junit4] 2> "operation":"create"} current state version: 0 [junit4] 2> 1813669 DEBUG (OverseerStateUpdate-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: testSimple1 [junit4] 2> 1813670 DEBUG (OverseerStateUpdate-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/testSimple1/state.json [junit4] 2> 1813671 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 1813672 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 1813774 DEBUG (OverseerThreadFactory-10750-thread-1-processing-n:127.0.0.1:51697_solr) [n:127.0.0.1:51697_solr ] o.a.s.c.a.c.CreateCollectionCmd Creating SolrCores for new collection testSimple1, shardNames [shard1, shard2] , message : { [junit4] 2> "name":"testSimple1", [junit4] 2> "fromApi":"true", [junit4] 2> "collection.configName":"conf", [junit4] 2> "numShards":"2", [junit4] 2> "maxShardsPerNode":"2", [junit4] 2> "createNodeSet":"127.0.0.1:51697_solr,127.0.0.1:51694_solr", [junit4] 2> "autoAddReplicas":"true", [junit4] 2> "nrtReplicas":"2", [junit4] 2> "stateFormat":"2", [junit4] 2> "replicationFactor":"2", [junit4] 2> "operation":"create"} [junit4] 2> 1813776 DEBUG (OverseerThreadFactory-10750-thread-1-processing-n:127.0.0.1:51697_solr) [n:127.0.0.1:51697_solr ] o.a.s.c.a.c.CreateCollectionCmd Creating core testSimple1_shard1_replica_n1 as part of shard shard1 of collection testSimple1 on 127.0.0.1:51697_solr [junit4] 2> 1813777 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 1813777 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 1813777 DEBUG (OverseerStateUpdate-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"testSimple1", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"testSimple1_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:51697/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} current state version: 0 [junit4] 2> 1813777 INFO (OverseerStateUpdate-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"testSimple1", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"testSimple1_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:51697/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1813778 DEBUG (OverseerThreadFactory-10750-thread-1-processing-n:127.0.0.1:51697_solr) [n:127.0.0.1:51697_solr ] o.a.s.c.a.c.CreateCollectionCmd Creating core testSimple1_shard1_replica_n2 as part of shard shard1 of collection testSimple1 on 127.0.0.1:51694_solr [junit4] 2> 1813778 DEBUG (OverseerStateUpdate-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{ [junit4] 2> "range":"80000000-ffffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{}} [junit4] 2> 1813778 DEBUG (OverseerStateUpdate-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{ [junit4] 2> "range":"80000000-ffffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{"core_node3":{ [junit4] 2> "core":"testSimple1_shard1_replica_n1", [junit4] 2> "base_url":"https://127.0.0.1:51697/solr", [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}}} [junit4] 2> 1813778 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 1813778 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 1813779 DEBUG (OverseerStateUpdate-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"testSimple1", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"testSimple1_shard1_replica_n2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:51694/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} current state version: 0 [junit4] 2> 1813779 INFO (OverseerStateUpdate-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"testSimple1", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"testSimple1_shard1_replica_n2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:51694/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1813779 DEBUG (OverseerThreadFactory-10750-thread-1-processing-n:127.0.0.1:51697_solr) [n:127.0.0.1:51697_solr ] o.a.s.c.a.c.CreateCollectionCmd Creating core testSimple1_shard2_replica_n4 as part of shard shard2 of collection testSimple1 on 127.0.0.1:51697_solr [junit4] 2> 1813780 DEBUG (OverseerStateUpdate-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{ [junit4] 2> "range":"80000000-ffffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{"core_node3":{ [junit4] 2> "core":"testSimple1_shard1_replica_n1", [junit4] 2> "base_url":"https://127.0.0.1:51697/solr", [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}}} [junit4] 2> 1813780 DEBUG (OverseerStateUpdate-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{ [junit4] 2> "range":"80000000-ffffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{ [junit4] 2> "core_node3":{ [junit4] 2> "core":"testSimple1_shard1_replica_n1", [junit4] 2> "base_url":"https://127.0.0.1:51697/solr", [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}, [junit4] 2> "core_node5":{ [junit4] 2> "core":"testSimple1_shard1_replica_n2", [junit4] 2> "base_url":"https://127.0.0.1:51694/solr", [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}}} [junit4] 2> 1813780 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 1813780 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 1813782 DEBUG (OverseerStateUpdate-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"testSimple1", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"testSimple1_shard2_replica_n4", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:51697/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} current state version: 0 [junit4] 2> 1813782 INFO (OverseerStateUpdate-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"testSimple1", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"testSimple1_shard2_replica_n4", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:51697/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1813782 DEBUG (OverseerThreadFactory-10750-thread-1-processing-n:127.0.0.1:51697_solr) [n:127.0.0.1:51697_solr ] o.a.s.c.a.c.CreateCollectionCmd Creating core testSimple1_shard2_replica_n6 as part of shard shard2 of collection testSimple1 on 127.0.0.1:51694_solr [junit4] 2> 1813782 DEBUG (OverseerStateUpdate-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.o.SliceMutator Old Slice: shard2:{ [junit4] 2> "range":"0-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{}} [junit4] 2> 1813782 DEBUG (OverseerStateUpdate-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.o.SliceMutator New Slice: shard2:{ [junit4] 2> "range":"0-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{"core_node7":{ [junit4] 2> "core":"testSimple1_shard2_replica_n4", [junit4] 2> "base_url":"https://127.0.0.1:51697/solr", [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}}} [junit4] 2> 1813782 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 1813783 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 1813783 DEBUG (OverseerThreadFactory-10750-thread-1-processing-n:127.0.0.1:51697_solr) [n:127.0.0.1:51697_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Expecting [testSimple1_shard1_replica_n1, testSimple1_shard1_replica_n2, testSimple1_shard2_replica_n4, testSimple1_shard2_replica_n6] cores but found {} [junit4] 2> 1813783 DEBUG (OverseerStateUpdate-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.Overseer processMessage: queueSize: 4, message = { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"testSimple1", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"testSimple1_shard2_replica_n6", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:51694/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} current state version: 0 [junit4] 2> 1813783 INFO (OverseerStateUpdate-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"testSimple1", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"testSimple1_shard2_replica_n6", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:51694/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1813784 DEBUG (OverseerStateUpdate-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.o.SliceMutator Old Slice: shard2:{ [junit4] 2> "range":"0-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{"core_node7":{ [junit4] 2> "core":"testSimple1_shard2_replica_n4", [junit4] 2> "base_url":"https://127.0.0.1:51697/solr", [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}}} [junit4] 2> 1813784 DEBUG (OverseerStateUpdate-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.o.SliceMutator New Slice: shard2:{ [junit4] 2> "range":"0-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{ [junit4] 2> "core_node7":{ [junit4] 2> "core":"testSimple1_shard2_replica_n4", [junit4] 2> "base_url":"https://127.0.0.1:51697/solr", [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}, [junit4] 2> "core_node8":{ [junit4] 2> "core":"testSimple1_shard2_replica_n6", [junit4] 2> "base_url":"https://127.0.0.1:51694/solr", [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}}} [junit4] 2> 1813890 DEBUG (OverseerThreadFactory-10750-thread-1-processing-n:127.0.0.1:51697_solr) [n:127.0.0.1:51697_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Expecting [testSimple1_shard1_replica_n1, testSimple1_shard1_replica_n2, testSimple1_shard2_replica_n4, testSimple1_shard2_replica_n6] cores but found {} [junit4] 2> 1813892 DEBUG (OverseerStateUpdate-72114816215678985-127.0.0.1:51697_solr-n_0000000000) [n:127.0.0.1:51697_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testSimple1/state.json version: 0 [junit4] 2> 1813893 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 1813895 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 1814006 INFO (qtp17595912-34069) [n:127.0.0.1:51697_solr x:testSimple1_shard2_replica_n4] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=testSimple1_shard2_replica_n4&action=CREATE&numShards=2&collection=testSimple1&shard=shard2&wt=javabin&version=2&replicaType=NRT [junit4] 2> 1814009 INFO (qtp17595912-34069) [n:127.0.0.1:51697_solr x:testSimple1_shard2_replica_n4] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 1814014 INFO (qtp17595912-34073) [n:127.0.0.1:51697_solr x:testSimple1_shard1_replica_n1] o.a.s.h.a.Core [...truncated too long message...] r] [junit4] > org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.solr.common.cloud.SolrZkClient [junit4] > at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) [junit4] > at org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:203) [junit4] > at org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:126) [junit4] > at org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:116) [junit4] > at org.apache.solr.common.cloud.ZkStateReader.<init>(ZkStateReader.java:306) [junit4] > at org.apache.solr.client.solrj.impl.ZkClientClusterStateProvider.connect(ZkClientClusterStateProvider.java:160) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.connect(CloudSolrClient.java:399) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:827) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:817) [junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207) [junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:224) [junit4] > at org.apache.solr.client.solrj.impl.SolrClientCloudManager.request(SolrClientCloudManager.java:115) [junit4] > at org.apache.solr.cloud.autoscaling.ExecutePlanAction.waitForTaskToFinish(ExecutePlanAction.java:132) [junit4] > at org.apache.solr.cloud.autoscaling.ExecutePlanAction.process(ExecutePlanAction.java:85) [junit4] > at org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:311) [junit4] > at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [junit4] > at java.util.concurrent.FutureTask.run(FutureTask.java:266) [junit4] > at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] > org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.solr.common.cloud.ZkStateReader [junit4] > at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) [junit4] > at org.apache.solr.common.cloud.ZkStateReader.<init>(ZkStateReader.java:328) [junit4] > at org.apache.solr.client.solrj.impl.ZkClientClusterStateProvider.connect(ZkClientClusterStateProvider.java:160) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.connect(CloudSolrClient.java:399) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:827) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:817) [junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207) [junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:224) [junit4] > at org.apache.solr.client.solrj.impl.SolrClientCloudManager.request(SolrClientCloudManager.java:115) [junit4] > at org.apache.solr.cloud.autoscaling.ExecutePlanAction.waitForTaskToFinish(ExecutePlanAction.java:132) [junit4] > at org.apache.solr.cloud.autoscaling.ExecutePlanAction.process(ExecutePlanAction.java:85) [junit4] > at org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:311) [junit4] > at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [junit4] > at java.util.concurrent.FutureTask.run(FutureTask.java:266) [junit4] > at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] > > [junit4] > at __randomizedtesting.SeedInfo.seed([FB50140163370A04]:0) [junit4] > at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:319) [junit4] > at java.lang.Thread.run(Thread.java:748)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: 3 threads leaked from SUITE scope at org.apache.solr.cloud.autoscaling.AutoAddReplicasIntegrationTest: [junit4] > 1) Thread[id=34643, name=AutoscalingActionExecutor-10892-thread-1-EventThread, state=WAITING, group=TGRP-AutoAddReplicasIntegrationTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) [junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) [junit4] > at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:504) [junit4] > 2) Thread[id=34642, name=AutoscalingActionExecutor-10892-thread-1-SendThread(127.0.0.1:51683), state=TIMED_WAITING, group=TGRP-AutoAddReplicasIntegrationTest] [junit4] > at java.lang.Thread.sleep(Native Method) [junit4] > at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1049) [junit4] > 3) Thread[id=34644, name=zkConnectionManagerCallback-10496-thread-1, state=WAITING, group=TGRP-AutoAddReplicasIntegrationTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) [junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] > at __randomizedtesting.SeedInfo.seed([FB50140163370A04]:0)Throwable #3: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: [junit4] > 1) Thread[id=34642, name=AutoscalingActionExecutor-10892-thread-1-SendThread(127.0.0.1:51683), state=RUNNABLE, group=TGRP-AutoAddReplicasIntegrationTest] [junit4] > at sun.nio.ch.WindowsSelectorImpl$SubSelector.poll0(Native Method) [junit4] > at sun.nio.ch.WindowsSelectorImpl$SubSelector.poll(WindowsSelectorImpl.java:296) [junit4] > at sun.nio.ch.WindowsSelectorImpl$SubSelector.access$400(WindowsSelectorImpl.java:278) [junit4] > at sun.nio.ch.WindowsSelectorImpl.doSelect(WindowsSelectorImpl.java:159) [junit4] > at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) [junit4] > at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) [junit4] > at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:349) [junit4] > at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1145) [junit4] > 2) Thread[id=34644, name=zkConnectionManagerCallback-10496-thread-1, state=WAITING, group=TGRP-AutoAddReplicasIntegrationTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) [junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] > at __randomizedtesting.SeedInfo.seed([FB50140163370A04]:0) [junit4] Completed [464/838 (1!)] on J0 in 147.50s, 1 test, 1 failure, 2 errors <<< FAILURES! [...truncated 46237 lines...] [repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/7669/consoleText [repro] Revision: 345a655f216258c406c384ada9aa6d5f14e254f9 [repro] Ant options: "-Dargs=-server -XX:+UseConcMarkSweepGC" [repro] ant clean [...truncated 6 lines...] [repro] Test suites by module: [repro] solr\core [repro] AutoAddReplicasIntegrationTest [repro] ant compile-test [...truncated 3614 lines...] [repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.AutoAddReplicasIntegrationTest" -Dtests.showOutput=onerror "-Dargs=-server -XX:+UseConcMarkSweepGC" -Dtests.seed=FB50140163370A04 -Dtests.slow=true -Dtests.locale=ar -Dtests.timezone=Pacific/Majuro -Dtests.asserts=true -Dtests.file.encoding=Cp1252 [...truncated 73 lines...] [repro] Failures: [repro] 0/5 failed: org.apache.solr.cloud.autoscaling.AutoAddReplicasIntegrationTest [repro] Exiting with code 0 [...truncated 75 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org