Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/21366/ Java: 64bit/jdk-10-ea+41 -XX:-UseCompressedOops -XX:+UseG1GC
7 tests failed. FAILED: junit.framework.TestSuite.org.apache.solr.handler.dataimport.TestSolrEntityProcessorEndToEnd Error Message: 1 thread leaked from SUITE scope at org.apache.solr.handler.dataimport.TestSolrEntityProcessorEndToEnd: 1) Thread[id=257, name=qtp1878999664-257, state=TIMED_WAITING, group=TGRP-TestSolrEntityProcessorEndToEnd] at java.base@10-ea/jdk.internal.misc.Unsafe.park(Native Method) at java.base@10-ea/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234) at java.base@10-ea/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2205) at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.base@10-ea/java.lang.Thread.run(Thread.java:844) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.handler.dataimport.TestSolrEntityProcessorEndToEnd: 1) Thread[id=257, name=qtp1878999664-257, state=TIMED_WAITING, group=TGRP-TestSolrEntityProcessorEndToEnd] at java.base@10-ea/jdk.internal.misc.Unsafe.park(Native Method) at java.base@10-ea/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234) at java.base@10-ea/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2205) at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.base@10-ea/java.lang.Thread.run(Thread.java:844) at __randomizedtesting.SeedInfo.seed([109044116134926]:0) FAILED: junit.framework.TestSuite.org.apache.solr.handler.dataimport.TestSolrEntityProcessorEndToEnd Error Message: There are still zombie threads that couldn't be terminated: 1) Thread[id=257, name=qtp1878999664-257, state=TIMED_WAITING, group=TGRP-TestSolrEntityProcessorEndToEnd] at java.base@10-ea/jdk.internal.misc.Unsafe.park(Native Method) at java.base@10-ea/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234) at java.base@10-ea/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2205) at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.base@10-ea/java.lang.Thread.run(Thread.java:844) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: 1) Thread[id=257, name=qtp1878999664-257, state=TIMED_WAITING, group=TGRP-TestSolrEntityProcessorEndToEnd] at java.base@10-ea/jdk.internal.misc.Unsafe.park(Native Method) at java.base@10-ea/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234) at java.base@10-ea/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2205) at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.base@10-ea/java.lang.Thread.run(Thread.java:844) at __randomizedtesting.SeedInfo.seed([109044116134926]:0) FAILED: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testMetricTrigger Error Message: Stack Trace: java.lang.AssertionError at __randomizedtesting.SeedInfo.seed([F2BDFE94D0B2F3E4:48B1C91B8F5A25AB]:0) at org.junit.Assert.fail(Assert.java:92) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertNull(Assert.java:551) at org.junit.Assert.assertNull(Assert.java:562) at org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testMetricTrigger(TriggerIntegrationTest.java:1575) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) 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 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.base/java.lang.Thread.run(Thread.java:844) FAILED: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testTriggerThrottling Error Message: Both triggers should have fired by now Stack Trace: java.lang.AssertionError: Both triggers should have fired by now at __randomizedtesting.SeedInfo.seed([F2BDFE94D0B2F3E4:99F56B102181076]:0) at org.junit.Assert.fail(Assert.java:93) at org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testTriggerThrottling(TriggerIntegrationTest.java:213) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) 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 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.base/java.lang.Thread.run(Thread.java:844) FAILED: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testCooldown Error Message: The trigger did not fire at all Stack Trace: java.lang.AssertionError: The trigger did not fire at all at __randomizedtesting.SeedInfo.seed([F2BDFE94D0B2F3E4:C3039370AE188616]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testCooldown(TriggerIntegrationTest.java:1176) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) 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 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.base/java.lang.Thread.run(Thread.java:844) FAILED: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testNodeLostTriggerRestoreState Error Message: The trigger did not fire at all Stack Trace: java.lang.AssertionError: The trigger did not fire at all at __randomizedtesting.SeedInfo.seed([F2BDFE94D0B2F3E4:D9422BCF4ACAE634]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testNodeLostTriggerRestoreState(TriggerIntegrationTest.java:368) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) 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 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.base/java.lang.Thread.run(Thread.java:844) FAILED: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testListeners Error Message: The trigger did not fire at all Stack Trace: java.lang.AssertionError: The trigger did not fire at all at __randomizedtesting.SeedInfo.seed([F2BDFE94D0B2F3E4:1F7D69846DDE6E3D]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testListeners(TriggerIntegrationTest.java:1052) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) 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 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.base/java.lang.Thread.run(Thread.java:844) Build Log: [...truncated 11920 lines...] [junit4] Suite: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.TriggerIntegrationTest_F2BDFE94D0B2F3E4-001/init-core-data-001 [junit4] 2> 123800 INFO (SUITE-TriggerIntegrationTest-seed#[F2BDFE94D0B2F3E4]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 123801 INFO (SUITE-TriggerIntegrationTest-seed#[F2BDFE94D0B2F3E4]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0) [junit4] 2> 123802 INFO (SUITE-TriggerIntegrationTest-seed#[F2BDFE94D0B2F3E4]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 123803 INFO (SUITE-TriggerIntegrationTest-seed#[F2BDFE94D0B2F3E4]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.TriggerIntegrationTest_F2BDFE94D0B2F3E4-001/tempDir-001 [junit4] 2> 123803 INFO (SUITE-TriggerIntegrationTest-seed#[F2BDFE94D0B2F3E4]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 123811 INFO (Thread-188) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 123811 INFO (Thread-188) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 123853 ERROR (Thread-188) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 123911 INFO (SUITE-TriggerIntegrationTest-seed#[F2BDFE94D0B2F3E4]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:34455 [junit4] 2> 123961 INFO (zkConnectionManagerCallback-176-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 123968 INFO (jetty-launcher-173-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T05:27:37+08:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 123970 INFO (jetty-launcher-173-thread-2) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T05:27:37+08:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 123986 INFO (jetty-launcher-173-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 123986 INFO (jetty-launcher-173-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 123986 INFO (jetty-launcher-173-thread-1) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 123987 INFO (jetty-launcher-173-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@13609d1b{/solr,null,AVAILABLE} [junit4] 2> 123988 INFO (jetty-launcher-173-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2341f157{SSL,[ssl, http/1.1]}{127.0.0.1:35245} [junit4] 2> 123989 INFO (jetty-launcher-173-thread-1) [ ] o.e.j.s.Server Started @125933ms [junit4] 2> 123989 INFO (jetty-launcher-173-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=35245} [junit4] 2> 123991 ERROR (jetty-launcher-173-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 123991 INFO (jetty-launcher-173-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 123991 INFO (jetty-launcher-173-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 123991 INFO (jetty-launcher-173-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 123991 INFO (jetty-launcher-173-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-29T22:58:25.240449Z [junit4] 2> 123994 INFO (jetty-launcher-173-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 123994 INFO (jetty-launcher-173-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 123994 INFO (jetty-launcher-173-thread-2) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 123995 INFO (jetty-launcher-173-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@834c12e{/solr,null,AVAILABLE} [junit4] 2> 123996 INFO (jetty-launcher-173-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3b03a94c{SSL,[ssl, http/1.1]}{127.0.0.1:41825} [junit4] 2> 123996 INFO (jetty-launcher-173-thread-2) [ ] o.e.j.s.Server Started @125941ms [junit4] 2> 123996 INFO (jetty-launcher-173-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=41825} [junit4] 2> 123997 ERROR (jetty-launcher-173-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 123997 INFO (jetty-launcher-173-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 123997 INFO (jetty-launcher-173-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 123997 INFO (jetty-launcher-173-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 123997 INFO (jetty-launcher-173-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-29T22:58:25.246492Z [junit4] 2> 124011 INFO (zkConnectionManagerCallback-178-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 124011 INFO (jetty-launcher-173-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 124022 INFO (zkConnectionManagerCallback-180-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 124030 INFO (jetty-launcher-173-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34455/solr [junit4] 2> 124035 INFO (jetty-launcher-173-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 124055 INFO (zkConnectionManagerCallback-184-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 124072 INFO (jetty-launcher-173-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34455/solr [junit4] 2> 124125 INFO (zkConnectionManagerCallback-190-thread-1-processing-n:127.0.0.1:35245_solr) [n:127.0.0.1:35245_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 124150 INFO (zkConnectionManagerCallback-188-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 124221 INFO (zkConnectionManagerCallback-193-thread-1-processing-n:127.0.0.1:41825_solr) [n:127.0.0.1:41825_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 124228 INFO (jetty-launcher-173-thread-2) [n:127.0.0.1:41825_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 124228 INFO (jetty-launcher-173-thread-2) [n:127.0.0.1:41825_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:41825_solr [junit4] 2> 124229 INFO (jetty-launcher-173-thread-2) [n:127.0.0.1:41825_solr ] o.a.s.c.Overseer Overseer (id=72059551304384518-127.0.0.1:41825_solr-n_0000000000) starting [junit4] 2> 124260 INFO (jetty-launcher-173-thread-2) [n:127.0.0.1:41825_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41825_solr [junit4] 2> 124276 DEBUG (OverseerAutoScalingTriggerThread-72059551304384518-127.0.0.1:41825_solr-n_0000000000) [n:127.0.0.1:41825_solr ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger [junit4] 2> 124290 INFO (zkCallback-192-thread-1-processing-n:127.0.0.1:41825_solr) [n:127.0.0.1:41825_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 124290 DEBUG (OverseerAutoScalingTriggerThread-72059551304384518-127.0.0.1:41825_solr-n_0000000000) [n:127.0.0.1:41825_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1 [junit4] 2> 124291 DEBUG (OverseerAutoScalingTriggerThread-72059551304384518-127.0.0.1:41825_solr-n_0000000000) [n:127.0.0.1:41825_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:41825_solr] [junit4] 2> 124292 DEBUG (OverseerAutoScalingTriggerThread-72059551304384518-127.0.0.1:41825_solr-n_0000000000) [n:127.0.0.1:41825_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1 [junit4] 2> 124292 DEBUG (OverseerAutoScalingTriggerThread-72059551304384518-127.0.0.1:41825_solr-n_0000000000) [n:127.0.0.1:41825_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1 [junit4] 2> 124299 DEBUG (OverseerAutoScalingTriggerThread-72059551304384518-127.0.0.1:41825_solr-n_0000000000) [n:127.0.0.1:41825_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 124299 DEBUG (OverseerAutoScalingTriggerThread-72059551304384518-127.0.0.1:41825_solr-n_0000000000) [n:127.0.0.1:41825_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1 [junit4] 2> 124300 DEBUG (ScheduledTrigger-303-thread-1) [n:127.0.0.1:41825_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 [junit4] 2> 124304 INFO (jetty-launcher-173-thread-1) [n:127.0.0.1:35245_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 124305 INFO (jetty-launcher-173-thread-1) [n:127.0.0.1:35245_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 124307 INFO (jetty-launcher-173-thread-1) [n:127.0.0.1:35245_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35245_solr [junit4] 2> 124308 INFO (zkCallback-192-thread-1-processing-n:127.0.0.1:41825_solr) [n:127.0.0.1:41825_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 124318 INFO (zkCallback-189-thread-1-processing-n:127.0.0.1:35245_solr) [n:127.0.0.1:35245_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 124394 INFO (jetty-launcher-173-thread-2) [n:127.0.0.1:41825_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41825.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5eca12fd [junit4] 2> 124407 INFO (jetty-launcher-173-thread-1) [n:127.0.0.1:35245_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35245.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5eca12fd [junit4] 2> 124414 INFO (jetty-launcher-173-thread-2) [n:127.0.0.1:41825_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41825.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5eca12fd [junit4] 2> 124422 INFO (jetty-launcher-173-thread-2) [n:127.0.0.1:41825_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41825.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5eca12fd [junit4] 2> 124423 INFO (jetty-launcher-173-thread-2) [n:127.0.0.1:41825_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.TriggerIntegrationTest_F2BDFE94D0B2F3E4-001/tempDir-001/node2/. [junit4] 2> 124446 INFO (jetty-launcher-173-thread-1) [n:127.0.0.1:35245_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35245.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5eca12fd [junit4] 2> 124447 INFO (jetty-launcher-173-thread-1) [n:127.0.0.1:35245_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35245.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5eca12fd [junit4] 2> 124448 INFO (jetty-launcher-173-thread-1) [n:127.0.0.1:35245_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.TriggerIntegrationTest_F2BDFE94D0B2F3E4-001/tempDir-001/node1/. [junit4] 2> 124513 INFO (zkConnectionManagerCallback-198-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 124581 INFO (zkConnectionManagerCallback-202-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 124586 INFO (SUITE-TriggerIntegrationTest-seed#[F2BDFE94D0B2F3E4]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 124586 INFO (SUITE-TriggerIntegrationTest-seed#[F2BDFE94D0B2F3E4]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34455/solr ready [junit4] 2> 124598 INFO (TEST-TriggerIntegrationTest.testMetricTrigger-seed#[F2BDFE94D0B2F3E4]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMetricTrigger [junit4] 2> 124604 INFO (TEST-TriggerIntegrationTest.testMetricTrigger-seed#[F2BDFE94D0B2F3E4]) [ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 2 [junit4] 2> 124604 DEBUG (zkCallback-192-thread-1-processing-n:127.0.0.1:41825_solr) [n:127.0.0.1:41825_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2 [junit4] 2> 124604 DEBUG (OverseerAutoScalingTriggerThread-72059551304384518-127.0.0.1:41825_solr-n_0000000000) [n:127.0.0.1:41825_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2 [junit4] 2> 124613 DEBUG (OverseerAutoScalingTriggerThread-72059551304384518-127.0.0.1:41825_solr-n_0000000000) [n:127.0.0.1:41825_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 124614 DEBUG (OverseerAutoScalingTriggerThread-72059551304384518-127.0.0.1:41825_solr-n_0000000000) [n:127.0.0.1:41825_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 124614 DEBUG (OverseerAutoScalingTriggerThread-72059551304384518-127.0.0.1:41825_solr-n_0000000000) [n:127.0.0.1:41825_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2 [junit4] 2> 124617 INFO (TEST-TriggerIntegrationTest.testMetricTrigger-seed#[F2BDFE94D0B2F3E4]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T05:27:37+08:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 124653 INFO (TEST-TriggerIntegrationTest.testMetricTrigger-seed#[F2BDFE94D0B2F3E4]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 124653 INFO (TEST-TriggerIntegrationTest.testMetricTrigger-seed#[F2BDFE94D0B2F3E4]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 124653 INFO (TEST-TriggerIntegrationTest.testMetricTrigger-seed#[F2BDFE94D0B2F3E4]) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 124657 INFO (TEST-TriggerIntegrationTest.testMetricTrigger-seed#[F2BDFE94D0B2F3E4]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@69a4817d{/solr,null,AVAILABLE} [junit4] 2> 124658 INFO (TEST-TriggerIntegrationTest.testMetricTrigger-seed#[F2BDFE94D0B2F3E4]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2cb17b13{SSL,[ssl, http/1.1]}{127.0.0.1:40623} [junit4] 2> 124658 INFO (TEST-TriggerIntegrationTest.testMetricTrigger-seed#[F2BDFE94D0B2F3E4]) [ ] o.e.j.s.Server Started @126602ms [junit4] 2> 124658 INFO (TEST-TriggerIntegrationTest.testMetricTrigger-seed#[F2BDFE94D0B2F3E4]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40623} [junit4] 2> 124658 ERROR (TEST-TriggerIntegrationTest.testMetricTrigger-seed#[F2BDFE94D0B2F3E4]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 124658 INFO (TEST-TriggerIntegrationTest.testMetricTrigger-seed#[F2BDFE94D0B2F3E4]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 124658 INFO (TEST-TriggerIntegrationTest.testMetricTrigger-seed#[F2BDFE94D0B2F3E4]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 124658 INFO (TEST-TriggerIntegrationTest.testMetricTrigger-seed#[F2BDFE94D0B2F3E4]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 124658 INFO (TEST-TriggerIntegrationTest.testMetricTrigger-seed#[F2BDFE94D0B2F3E4]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-29T22:58:25.907833Z [junit4] 2> 124706 INFO (zkConnectionManagerCallback-204-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 124719 INFO (TEST-TriggerIntegrationTest.testMetricTrigger-seed#[F2BDFE94D0B2F3E4]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 124738 INFO (TEST-TriggerIntegrationTest.testMetricTrigger-seed#[F2BDFE94D0B2F3E4]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34455/solr [junit4] 2> 124753 INFO (zkConnectionManagerCallback-208-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 124761 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 0x10001c7b62c000a, likely client has closed socket [junit4] 2> 124802 INFO (zkConnectionManagerCallback-210-thread-1-processing-n:127.0.0.1:40623_solr) [n:127.0.0.1:40623_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 124820 INFO (TEST-TriggerIntegrationTest.testMetricTrigger-seed#[F2BDFE94D0B2F3E4]) [n:127.0.0.1:40623_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 124821 INFO (TEST-TriggerIntegrationTest.testMetricTrigger-seed#[F2BDFE94D0B2F3E4]) [n:127.0.0.1:40623_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 124824 INFO (TEST-TriggerIntegrationTest.testMetricTrigger-seed#[F2BDFE94D0B2F3E4]) [n:127.0.0.1:40623_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40623_solr [junit4] 2> 124825 INFO (zkCallback-189-thread-1-processing-n:127.0.0.1:35245_solr) [n:127.0.0.1:35245_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 124825 INFO (zkCallback-192-thread-1-processing-n:127.0.0.1:41825_solr) [n:127.0.0.1:41825_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 124853 INFO (zkCallback-201-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 124854 INFO (zkCallback-209-thread-1-processing-n:127.0.0.1:40623_solr) [n:127.0.0.1:40623_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 124886 INFO (TEST-TriggerIntegrationTest.testMetricTrigger-seed#[F2BDFE94D0B2F3E4]) [n:127.0.0.1:40623_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40623.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5eca12fd [junit4] 2> 124893 INFO (TEST-TriggerIntegrationTest.testMetricTrigger-seed#[F2BDFE94D0B2F3E4]) [n:127.0.0.1:40623_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40623.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5eca12fd [junit4] 2> 124893 INFO (TEST-TriggerIntegrationTest.testMetricTrigger-seed#[F2BDFE94D0B2F3E4]) [n:127.0.0.1:40623_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40623.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5eca12fd [junit4] 2> 124894 INFO (TEST-TriggerIntegrationTest.testMetricTrigger-seed#[F2BDFE94D0B2F3E4]) [n:127.0.0.1:40623_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.TriggerIntegrationTest_F2BDFE94D0B2F3E4-001/tempDir-001/node3/. [junit4] 2> 125037 INFO (zkConnectionManagerCallback-214-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 125102 INFO (qtp1261620044-802) [n:127.0.0.1:35245_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf&name=testMetricTrigger&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 125142 INFO (OverseerThreadFactory-305-thread-1-processing-n:127.0.0.1:41825_solr) [n:127.0.0.1:41825_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testMetricTrigger [junit4] 2> 125271 INFO (OverseerStateUpdate-72059551304384518-127.0.0.1:41825_solr-n_0000000000) [n:127.0.0.1:41825_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"testMetricTrigger", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"testMetricTrigger_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:35245/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 125274 INFO (OverseerStateUpdate-72059551304384518-127.0.0.1:41825_solr-n_0000000000) [n:127.0.0.1:41825_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"testMetricTrigger", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"testMetricTrigger_shard2_replica_n3", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:40623/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 125423 INFO (qtp1261620044-798) [n:127.0.0.1:35245_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=testMetricTrigger_shard1_replica_n1&action=CREATE&numShards=2&collection=testMetricTrigger&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 125423 INFO (qtp1261620044-798) [n:127.0.0.1:35245_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 125496 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=testMetricTrigger_shard2_replica_n3&action=CREATE&numShards=2&collection=testMetricTrigger&shard=shard2&wt=javabin&version=2&replicaType=NRT [junit4] 2> 125496 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 125628 INFO (zkCallback-189-thread-1-processing-n:127.0.0.1:35245_solr) [n:127.0.0.1:35245_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testMetricTrigger/state.json] for collection [testMetricTrigger] has occurred - updating... (live nodes size: [3]) [junit4] 2> 125628 INFO (zkCallback-209-thread-1-processing-n:127.0.0.1:40623_solr) [n:127.0.0.1:40623_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testMetricTrigger/state.json] for collection [testMetricTrigger] has occurred - updating... (live nodes size: [3]) [junit4] 2> 125630 INFO (zkCallback-189-thread-2-processing-n:127.0.0.1:35245_solr) [n:127.0.0.1:35245_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testMetricTrigger/state.json] for collection [testMetricTrigger] has occurred - updating... (live nodes size: [3]) [junit4] 2> 125630 INFO (zkCallback-209-thread-2-processing-n:127.0.0.1:40623_solr) [n:127.0.0.1:40623_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testMetricTrigger/state.json] for collection [testMetricTrigger] has occurred - updating... (live nodes size: [3]) [junit4] 2> 126490 INFO (qtp1261620044-798) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 126504 INFO (qtp1261620044-798) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.s.IndexSchema [testMetricTrigger_shard1_replica_n1] Schema name=minimal [junit4] 2> 126506 INFO (qtp1261620044-798) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 126506 INFO (qtp1261620044-798) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'testMetricTrigger_shard1_replica_n1' using configuration from collection testMetricTrigger, trusted=true [junit4] 2> 126506 INFO (qtp1261620044-798) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35245.solr.core.testMetricTrigger.shard1.replica_n1' (registry 'solr.core.testMetricTrigger.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5eca12fd [junit4] 2> 126506 INFO (qtp1261620044-798) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 126506 INFO (qtp1261620044-798) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.c.SolrCore [[testMetricTrigger_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.TriggerIntegrationTest_F2BDFE94D0B2F3E4-001/tempDir-001/node1/testMetricTrigger_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.TriggerIntegrationTest_F2BDFE94D0B2F3E4-001/tempDir-001/node1/./testMetricTrigger_shard1_replica_n1/data/] [junit4] 2> 126596 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 126603 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.s.IndexSchema [testMetricTrigger_shard2_replica_n3] Schema name=minimal [junit4] 2> 126605 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 126605 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.c.CoreContainer Creating SolrCore 'testMetricTrigger_shard2_replica_n3' using configuration from collection testMetricTrigger, trusted=true [junit4] 2> 126606 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40623.solr.core.testMetricTrigger.shard2.replica_n3' (registry 'solr.core.testMetricTrigger.shard2.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5eca12fd [junit4] 2> 126606 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 126606 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.c.SolrCore [[testMetricTrigger_shard2_replica_n3] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.TriggerIntegrationTest_F2BDFE94D0B2F3E4-001/tempDir-001/node3/testMetricTrigger_shard2_replica_n3], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.TriggerIntegrationTest_F2BDFE94D0B2F3E4-001/tempDir-001/node3/./testMetricTrigger_shard2_replica_n3/data/] [junit4] 2> 126645 INFO (qtp1261620044-798) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 126645 INFO (qtp1261620044-798) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 126646 INFO (qtp1261620044-798) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 126646 INFO (qtp1261620044-798) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 126650 INFO (qtp1261620044-798) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5ec82d45[testMetricTrigger_shard1_replica_n1] main] [junit4] 2> 126651 INFO (qtp1261620044-798) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 126651 INFO (qtp1261620044-798) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 126652 INFO (qtp1261620044-798) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 126652 INFO (qtp1261620044-798) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1590969455503998976 [junit4] 2> 126655 INFO (searcherExecutor-316-thread-1-processing-n:127.0.0.1:35245_solr x:testMetricTrigger_shard1_replica_n1 s:shard1 c:testMetricTrigger r:core_node2) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.c.SolrCore [testMetricTrigger_shard1_replica_n1] Registered new searcher Searcher@5ec82d45[testMetricTrigger_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 126658 INFO (qtp1261620044-798) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 126658 INFO (qtp1261620044-798) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 126658 INFO (qtp1261620044-798) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:35245/solr/testMetricTrigger_shard1_replica_n1/ [junit4] 2> 126658 INFO (qtp1261620044-798) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 126658 INFO (qtp1261620044-798) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.c.SyncStrategy https://127.0.0.1:35245/solr/testMetricTrigger_shard1_replica_n1/ has no replicas [junit4] 2> 126658 INFO (qtp1261620044-798) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 126661 INFO (qtp1261620044-798) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:35245/solr/testMetricTrigger_shard1_replica_n1/ shard1 [junit4] 2> 126677 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 126677 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 126679 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 126679 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 126680 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.s.SolrIndexSearcher Opening [Searcher@20475237[testMetricTrigger_shard2_replica_n3] main] [junit4] 2> 126681 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 126682 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 126683 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 126685 INFO (searcherExecutor-317-thread-1-processing-n:127.0.0.1:40623_solr x:testMetricTrigger_shard2_replica_n3 s:shard2 c:testMetricTrigger r:core_node4) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.c.SolrCore [testMetricTrigger_shard2_replica_n3] Registered new searcher Searcher@20475237[testMetricTrigger_shard2_replica_n3] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 126686 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1590969455539650560 [junit4] 2> 126692 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 126692 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 126692 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:40623/solr/testMetricTrigger_shard2_replica_n3/ [junit4] 2> 126692 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 126692 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.c.SyncStrategy https://127.0.0.1:40623/solr/testMetricTrigger_shard2_replica_n3/ has no replicas [junit4] 2> 126692 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 126695 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:40623/solr/testMetricTrigger_shard2_replica_n3/ shard2 [junit4] 2> 126795 INFO (zkCallback-209-thread-2-processing-n:127.0.0.1:40623_solr) [n:127.0.0.1:40623_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testMetricTrigger/state.json] for collection [testMetricTrigger] has occurred - updating... (live nodes size: [3]) [junit4] 2> 126796 INFO (zkCallback-209-thread-1-processing-n:127.0.0.1:40623_solr) [n:127.0.0.1:40623_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testMetricTrigger/state.json] for collection [testMetricTrigger] has occurred - updating... (live nodes size: [3]) [junit4] 2> 126796 INFO (zkCallback-189-thread-1-processing-n:127.0.0.1:35245_solr) [n:127.0.0.1:35245_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testMetricTrigger/state.json] for collection [testMetricTrigger] has occurred - updating... (live nodes size: [3]) [junit4] 2> 126795 INFO (zkCallback-189-thread-2-processing-n:127.0.0.1:35245_solr) [n:127.0.0.1:35245_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testMetricTrigger/state.json] for collection [testMetricTrigger] has occurred - updating... (live nodes size: [3]) [junit4] 2> 126811 INFO (qtp1261620044-798) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 126819 INFO (qtp1261620044-798) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=testMetricTrigger_shard1_replica_n1&action=CREATE&numShards=2&collection=testMetricTrigger&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1396 [junit4] 2> 126845 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 126852 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=testMetricTrigger_shard2_replica_n3&action=CREATE&numShards=2&collection=testMetricTrigger&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1355 [junit4] 2> 126856 INFO (qtp1261620044-802) [n:127.0.0.1:35245_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas [junit4] 2> 126949 INFO (zkCallback-209-thread-2-processing-n:127.0.0.1:40623_solr) [n:127.0.0.1:40623_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testMetricTrigger/state.json] for collection [testMetricTrigger] has occurred - updating... (live nodes size: [3]) [junit4] 2> 126949 INFO (zkCallback-209-thread-1-processing-n:127.0.0.1:40623_solr) [n:127.0.0.1:40623_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testMetricTrigger/state.json] for collection [testMetricTrigger] has occurred - updating... (live nodes size: [3]) [junit4] 2> 126949 INFO (zkCallback-189-thread-1-processing-n:127.0.0.1:35245_solr) [n:127.0.0.1:35245_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testMetricTrigger/state.json] for collection [testMetricTrigger] has occurred - updating... (live nodes size: [3]) [junit4] 2> 126949 INFO (zkCallback-189-thread-2-processing-n:127.0.0.1:35245_solr) [n:127.0.0.1:35245_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testMetricTrigger/state.json] for collection [testMetricTrigger] has occurred - updating... (live nodes size: [3]) [junit4] 2> 127144 INFO (OverseerCollectionConfigSetProcessor-72059551304384518-127.0.0.1:41825_solr-n_0000000000) [n:127.0.0.1:41825_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 127856 INFO (qtp1261620044-802) [n:127.0.0.1:35245_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf&name=testMetricTrigger&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=2754 [junit4] 2> 127939 INFO (qtp1261620044-798) [n:127.0.0.1:35245_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.testMetricTrigger.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0 [junit4] 2> 127941 INFO (qtp1261620044-802) [n:127.0.0.1:35245_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 127955 INFO (qtp1095492985-862) [n:127.0.0.1:40623_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.testMetricTrigger.shard2.replica_n3:INDEX.sizeInBytes} status=0 QTime=0 [junit4] 2> 127956 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 127996 INFO (qtp1565532527-809) [n:127.0.0.1:41825_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 127997 DEBUG (qtp1565532527-814) [n:127.0.0.1:41825_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 127998 DEBUG (zkCallback-192-thread-1-processing-n:127.0.0.1:41825_solr) [n:127.0.0.1:41825_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3 [junit4] 2> 128000 DEBUG (OverseerAutoScalingTriggerThread-72059551304384518-127.0.0.1:41825_solr-n_0000000000) [n:127.0.0.1:41825_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3 [junit4] 2> 128003 INFO (qtp1565532527-814) [n:127.0.0.1:41825_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=92 [junit4] 2> 128009 DEBUG (OverseerAutoScalingTriggerThread-72059551304384518-127.0.0.1:41825_solr-n_0000000000) [n:127.0.0.1:41825_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 128019 INFO (qtp1261620044-798) [n:127.0.0.1:35245_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.testMetricTrigger.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0 [junit4] 2> 128040 DEBUG (OverseerAutoScalingTriggerThread-72059551304384518-127.0.0.1:41825_solr-n_0000000000) [n:127.0.0.1:41825_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 128041 INFO (qtp1261620044-796) [n:127.0.0.1:35245_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.testMetricTrigger.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0 [junit4] 2> 128042 INFO (qtp1261620044-886) [n:127.0.0.1:35245_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 128046 DEBUG (OverseerAutoScalingTriggerThread-72059551304384518-127.0.0.1:41825_solr-n_0000000000) [n:127.0.0.1:41825_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3 [junit4] 2> 128053 INFO (qtp1095492985-857) [n:127.0.0.1:40623_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.testMetricTrigger.shard2.replica_n3:INDEX.sizeInBytes} status=0 QTime=0 [junit4] 2> 128055 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 128069 INFO (qtp1565532527-809) [n:127.0.0.1:41825_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 128069 DEBUG (qtp1261620044-804) [n:127.0.0.1:35245_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 128070 DEBUG (zkCallback-192-thread-1-processing-n:127.0.0.1:41825_solr) [n:127.0.0.1:41825_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 4 [junit4] 2> 128070 DEBUG (OverseerAutoScalingTriggerThread-72059551304384518-127.0.0.1:41825_solr-n_0000000000) [n:127.0.0.1:41825_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4 [junit4] 2> 128070 DEBUG (OverseerAutoScalingTriggerThread-72059551304384518-127.0.0.1:41825_solr-n_0000000000) [n:127.0.0.1:41825_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 128071 DEBUG (OverseerAutoScalingTriggerThread-72059551304384518-127.0.0.1:41825_solr-n_0000000000) [n:127.0.0.1:41825_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 128071 DEBUG (OverseerAutoScalingTriggerThread-72059551304384518-127.0.0.1:41825_solr-n_0000000000) [n:127.0.0.1:41825_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion 4 [junit4] 2> 128071 INFO (qtp1261620044-804) [n:127.0.0.1:35245_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=66 [junit4] 2> 128095 INFO (qtp1261620044-802) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0 (1590969457013948416)]} 0 4 [junit4] 2> 128099 INFO (qtp1261620044-804) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1 (1590969457020239872)]} 0 1 [junit4] 2> 128143 INFO (qtp1095492985-893) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2 (1590969457057988608)]} 0 9 [junit4] 2> 128158 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3 (1590969457081057280)]} 0 1 [junit4] 2> 128168 INFO (qtp1261620044-802) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4 (1590969457093640192)]} 0 0 [junit4] 2> 128171 INFO (qtp1095492985-893) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[5 (1590969457095737344)]} 0 0 [junit4] 2> 128173 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[6 (1590969457097834496)]} 0 0 [junit4] 2> 128175 INFO (qtp1095492985-893) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[7 (1590969457099931648)]} 0 0 [junit4] 2> 128176 INFO (qtp1261620044-804) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[8 (1590969457102028800)]} 0 0 [junit4] 2> 128183 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[9 (1590969457108320256)]} 0 0 [junit4] 2> 128185 INFO (qtp1261620044-802) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[10 (1590969457110417408)]} 0 0 [junit4] 2> 128189 INFO (qtp1261620044-804) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[11 (1590969457114611712)]} 0 0 [junit4] 2> 128191 INFO (qtp1261620044-802) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[12 (1590969457116708864)]} 0 0 [junit4] 2> 128193 INFO (qtp1261620044-804) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[13 (1590969457119854592)]} 0 0 [junit4] 2> 128194 INFO (qtp1261620044-802) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[14 (1590969457120903168)]} 0 0 [junit4] 2> 128197 INFO (qtp1261620044-804) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[15 (1590969457124048896)]} 0 0 [junit4] 2> 128199 INFO (qtp1261620044-802) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[16 (1590969457126146048)]} 0 0 [junit4] 2> 128201 INFO (qtp1095492985-893) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[17 (1590969457128243200)]} 0 1 [junit4] 2> 128205 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[18 (1590969457131388928)]} 0 0 [junit4] 2> 128207 INFO (qtp1095492985-893) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[19 (1590969457133486080)]} 0 0 [junit4] 2> 128210 INFO (qtp1261620044-804) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[20 (1590969457135583232)]} 0 1 [junit4] 2> 128212 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[21 (1590969457138728960)]} 0 0 [junit4] 2> 128213 INFO (qtp1261620044-802) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[22 (1590969457140826112)]} 0 0 [junit4] 2> 128215 INFO (qtp1095492985-893) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[23 (1590969457142923264)]} 0 0 [junit4] 2> 128216 INFO (qtp1261620044-804) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[24 (1590969457143971840)]} 0 0 [junit4] 2> 128218 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[25 (1590969457145020416)]} 0 0 [junit4] 2> 128220 INFO (qtp1095492985-893) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[26 (1590969457147117568)]} 0 0 [junit4] 2> 128221 INFO (qtp1261620044-802) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[27 (1590969457149214720)]} 0 0 [junit4] 2> 128223 INFO (qtp1261620044-804) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[28 (1590969457150263296)]} 0 0 [junit4] 2> 128224 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[29 (1590969457152360448)]} 0 0 [junit4] 2> 128227 INFO (qtp1095492985-893) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[30 (1590969457154457600)]} 0 0 [junit4] 2> 128228 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[31 (1590969457156554752)]} 0 0 [junit4] 2> 128230 INFO (qtp1261620044-802) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[32 (1590969457157603328)]} 0 0 [junit4] 2> 128232 INFO (qtp1095492985-893) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[33 (1590969457159700480)]} 0 0 [junit4] 2> 128233 INFO (qtp1261620044-804) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[34 (1590969457161797632)]} 0 0 [junit4] 2> 128235 INFO (qtp1261620044-802) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[35 (1590969457163894784)]} 0 0 [junit4] 2> 128237 INFO (qtp1261620044-804) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[36 (1590969457164943360)]} 0 0 [junit4] 2> 128238 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[37 (1590969457167040512)]} 0 0 [junit4] 2> 128240 INFO (qtp1261620044-802) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[38 (1590969457169137664)]} 0 0 [junit4] 2> 128242 INFO (qtp1095492985-893) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[39 (1590969457171234816)]} 0 0 [junit4] 2> 128244 INFO (qtp1261620044-804) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[40 (1590969457172283392)]} 0 0 [junit4] 2> 128246 INFO (qtp1261620044-802) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[41 (1590969457174380544)]} 0 0 [junit4] 2> 128258 INFO (qtp1261620044-804) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[42 (1590969457186963456)]} 0 9 [junit4] 2> 128259 INFO (qtp1261620044-802) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[43 (1590969457189060608)]} 0 0 [junit4] 2> 128261 INFO (qtp1261620044-804) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[44 (1590969457191157760)]} 0 0 [junit4] 2> 128263 INFO (qtp1095492985-861) [n:127.0.0.1:40623_solr c:testMetricTrigger s:shard2 r:core_node4 x:testMetricTrigger_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[45 (1590969457192206336)]} 0 0 [junit4] 2> 128264 INFO (qtp1261620044-802) [n:127.0.0.1:35245_solr c:testMetricTrigger s:shard1 r:core_node2 x:testMetricTrigger_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testMetricTrigger_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[46 (1590969457194303488)]} 0 0 [junit4] 2> 128266 INFO (qtp1261620044-804) [n:127.0.0.1 [...truncated too long message...] e=936673710 [junit4] 2> 14747 T61 oasm.SolrMetricManager.closeReporters Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 14747 T61 oasmr.SolrJmxReporter.close Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@44a5ccc3: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / null [junit4] 2> 14747 T61 oasm.SolrMetricManager.closeReporters Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 14747 T61 oasmr.SolrJmxReporter.close Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3b5cd135: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / null [junit4] 2> 14747 T61 oasm.SolrMetricManager.closeReporters Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 14755 T61 oasmr.SolrJmxReporter.close Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@226477d1: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / null [junit4] 2> 14758 T270 x:x:collection1 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@bfb837c [junit4] 2> 14758 T270 x:x:collection1 oasm.SolrMetricManager.closeReporters Closing metric reporters for registry=solr.core.collection1, tag=201032572 [junit4] 2> 14758 T270 x:x:collection1 oasmr.SolrJmxReporter.close Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2000c393: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / null [junit4] 2> 14779 T61 oejs.AbstractConnector.doStop Stopped ServerConnector@7767f1b0{SSL,[ssl, http/1.1]}{127.0.0.1:0} [junit4] 2> 14779 T61 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=421148479 [junit4] 2> 14780 T61 oasm.SolrMetricManager.closeReporters Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 14780 T61 oasm.SolrMetricManager.closeReporters Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 14780 T61 oasm.SolrMetricManager.closeReporters Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 14798 T273 x:x:collection1 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@372ecdc1 [junit4] 2> 14798 T273 x:x:collection1 oasm.SolrMetricManager.closeReporters Closing metric reporters for registry=solr.core.collection1, tag=925814209 [junit4] 2> 14804 T61 oejsh.ContextHandler.doStop Stopped o.e.j.s.ServletContextHandler@1fa179c0{/solr,null,UNAVAILABLE} [junit4] 2> 14804 T61 oejss.HouseKeeper.stopScavenging Stopped scavenging [junit4] 2> 44803 T61 oejut.QueuedThreadPool.doStop WARN QueuedThreadPool@qtp1878999664{STOPPING,8<=8<=10000,i=0,q=1} Couldn't stop Thread[qtp1878999664-257,5,TGRP-TestSolrEntityProcessorEndToEnd] [junit4] 2> 44804 T61 oas.SolrTestCaseJ4.tearDown ###Ending testFullImportFieldsParam [junit4] 2> 44805 T61 oas.SolrTestCaseJ4.clearObjectTrackerAndCheckEmpty ------------------------------------------------------- Done waiting for tracked resources to be released [junit4] 2> Jan 29, 2018 11:53:49 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> Jan 29, 2018 11:54:09 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> SEVERE: 1 thread leaked from SUITE scope at org.apache.solr.handler.dataimport.TestSolrEntityProcessorEndToEnd: [junit4] 2> 1) Thread[id=257, name=qtp1878999664-257, state=TIMED_WAITING, group=TGRP-TestSolrEntityProcessorEndToEnd] [junit4] 2> at java.base@10-ea/jdk.internal.misc.Unsafe.park(Native Method) [junit4] 2> at java.base@10-ea/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234) [junit4] 2> at java.base@10-ea/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2205) [junit4] 2> at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) [junit4] 2> at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) [junit4] 2> at app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) [junit4] 2> at app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) [junit4] 2> at java.base@10-ea/java.lang.Thread.run(Thread.java:844) [junit4] 2> Jan 29, 2018 11:54:09 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll [junit4] 2> INFO: Starting to interrupt leaked threads: [junit4] 2> 1) Thread[id=257, name=qtp1878999664-257, state=TIMED_WAITING, group=TGRP-TestSolrEntityProcessorEndToEnd] [junit4] 2> Jan 29, 2018 11:54:12 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll [junit4] 2> SEVERE: There are still zombie threads that couldn't be terminated: [junit4] 2> 1) Thread[id=257, name=qtp1878999664-257, state=TIMED_WAITING, group=TGRP-TestSolrEntityProcessorEndToEnd] [junit4] 2> at java.base@10-ea/jdk.internal.misc.Unsafe.park(Native Method) [junit4] 2> at java.base@10-ea/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234) [junit4] 2> at java.base@10-ea/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2205) [junit4] 2> at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) [junit4] 2> at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) [junit4] 2> at app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) [junit4] 2> at app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) [junit4] 2> at java.base@10-ea/java.lang.Thread.run(Thread.java:844) [junit4] 2> NOTE: test params are: codec=CheapBastard, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@2faaf246), locale=sw-UG, timezone=Australia/Hobart [junit4] 2> NOTE: Linux 4.13.0-32-generic amd64/Oracle Corporation 10-ea (64-bit)/cpus=8,threads=3,free=285402120,total=536870912 [junit4] 2> NOTE: All tests run in this JVM: [TestDocBuilder2, TestURLDataSource, TestDocBuilder, TestDateFormatTransformer, TestEphemeralCache, TestJdbcDataSource, TestXPathEntityProcessor, TestRegexTransformer, TestWriterImpl, TestSolrEntityProcessorEndToEnd] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSolrEntityProcessorEndToEnd -Dtests.seed=109044116134926 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sw-UG -Dtests.timezone=Australia/Hobart -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 0.00s J2 | TestSolrEntityProcessorEndToEnd (suite) <<< [junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.handler.dataimport.TestSolrEntityProcessorEndToEnd: [junit4] > 1) Thread[id=257, name=qtp1878999664-257, state=TIMED_WAITING, group=TGRP-TestSolrEntityProcessorEndToEnd] [junit4] > at java.base@10-ea/jdk.internal.misc.Unsafe.park(Native Method) [junit4] > at java.base@10-ea/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234) [junit4] > at java.base@10-ea/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2205) [junit4] > at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) [junit4] > at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) [junit4] > at app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) [junit4] > at app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) [junit4] > at java.base@10-ea/java.lang.Thread.run(Thread.java:844) [junit4] > at __randomizedtesting.SeedInfo.seed([109044116134926]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: [junit4] > 1) Thread[id=257, name=qtp1878999664-257, state=TIMED_WAITING, group=TGRP-TestSolrEntityProcessorEndToEnd] [junit4] > at java.base@10-ea/jdk.internal.misc.Unsafe.park(Native Method) [junit4] > at java.base@10-ea/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234) [junit4] > at java.base@10-ea/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2205) [junit4] > at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) [junit4] > at app//org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) [junit4] > at app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) [junit4] > at app//org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) [junit4] > at java.base@10-ea/java.lang.Thread.run(Thread.java:844) [junit4] > at __randomizedtesting.SeedInfo.seed([109044116134926]:0) [junit4] Completed [38/38 (1!)] on J2 in 60.58s, 7 tests, 2 errors <<< FAILURES! [...truncated 35225 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org