Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2371/
3 tests failed. FAILED: org.apache.solr.cloud.ReplaceNodeNoTargetTest.test Error Message: Stack Trace: java.lang.AssertionError at __randomizedtesting.SeedInfo.seed([7E61BA1420306C65:F63585CE8ECC019D]:0) at org.junit.Assert.fail(Assert.java:92) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertTrue(Assert.java:54) at org.apache.solr.cloud.ReplaceNodeNoTargetTest.test(ReplaceNodeNoTargetTest.java:92) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.cloud.TestSegmentSorting.testSegmentTerminateEarly Error Message: KeeperErrorCode = Session expired for /clusterstate.json Stack Trace: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /clusterstate.json at __randomizedtesting.SeedInfo.seed([7E61BA1420306C65:AEC77DB9FC62F80F]:0) at org.apache.zookeeper.KeeperException.create(KeeperException.java:130) at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) at org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java:1105) at org.apache.solr.common.cloud.SolrZkClient.lambda$exists$3(SolrZkClient.java:316) at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60) at org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:316) at org.apache.solr.common.cloud.ZkStateReader.createClusterStateWatchersAndUpdate(ZkStateReader.java:421) at org.apache.solr.cloud.MiniSolrCloudCluster.deleteAllCollections(MiniSolrCloudCluster.java:442) at org.apache.solr.cloud.TestSegmentSorting.ensureClusterEmpty(TestSegmentSorting.java:63) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992) at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:47) 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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration.testSearchRate 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([7E61BA1420306C65:2329A49DEFF6CA2A]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration.testSearchRate(TestTriggerIntegration.java:1179) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) Build Log: [...truncated 12649 lines...] [junit4] Suite: org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration [junit4] 2> 1703507 INFO (SUITE-TestTriggerIntegration-seed#[7E61BA1420306C65]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.sim.TestTriggerIntegration_7E61BA1420306C65-001/init-core-data-001 [junit4] 2> 1703508 WARN (SUITE-TestTriggerIntegration-seed#[7E61BA1420306C65]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=9 numCloses=9 [junit4] 2> 1703579 INFO (SUITE-TestTriggerIntegration-seed#[7E61BA1420306C65]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 1703581 INFO (SUITE-TestTriggerIntegration-seed#[7E61BA1420306C65]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 1703699 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger [junit4] 2> 1703699 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 0 [junit4] 2> 1703699 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10000_solr] [junit4] 2> 1703699 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion -1 [junit4] 2> 1703699 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 0 [junit4] 2> 1703734 INFO (TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[7E61BA1420306C65]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testNodeAddedTrigger [junit4] 2> 1703734 DEBUG (TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1 [junit4] 2> 1703736 INFO (TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and clearing object cache... [junit4] 2> 1703736 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 1703749 DEBUG (TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly [junit4] 2> 1703750 WARN (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Interrupted [junit4] 2> java.lang.InterruptedException [junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireInterruptibly(AbstractQueuedSynchronizer.java:1220) [junit4] 2> at java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:335) [junit4] 2> at org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:163) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 1703771 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger [junit4] 2> 1703771 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2 [junit4] 2> 1703771 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10001_solr, 127.0.0.1:10000_solr] [junit4] 2> 1703786 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion -1 [junit4] 2> 1703786 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2 [junit4] 2> 1703787 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 1703787 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2 [junit4] 2> 1703789 DEBUG (ScheduledTrigger-2693-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1703809 DEBUG (ScheduledTrigger-2693-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1703888 DEBUG (ScheduledTrigger-2693-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1703920 DEBUG (ScheduledTrigger-2693-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1703937 DEBUG (simCloudManagerPool-2692-thread-1) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 1703937 DEBUG (simCloudManagerPool-2692-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3 [junit4] 2> 1703956 DEBUG (ScheduledTrigger-2693-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1703957 DEBUG (simCloudManagerPool-2692-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10001_solr, 127.0.0.1:10000_solr] [junit4] 2> 1703960 DEBUG (simCloudManagerPool-2692-thread-1) [ ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:10001_solr, 127.0.0.1:10000_solr] [junit4] 2> 1703960 DEBUG (simCloudManagerPool-2692-thread-1) [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=1, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration$TestTriggerAction}], enabled=true} [junit4] 2> 1703960 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3 [junit4] 2> 1703961 INFO (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.s.TestTriggerIntegration TestTriggerAction init [junit4] 2> 1703961 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3 [junit4] 2> 1703962 DEBUG (ScheduledTrigger-2693-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 1703962 DEBUG (ScheduledTrigger-2693-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 1703963 DEBUG (ScheduledTrigger-2693-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:10002_solr at time 1829612082916381 [junit4] 2> 1703981 DEBUG (ScheduledTrigger-2693-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 3 [junit4] 2> 1703997 DEBUG (ScheduledTrigger-2693-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 1703997 DEBUG (ScheduledTrigger-2693-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 1703998 DEBUG (ScheduledTrigger-2693-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger firing registered processor for nodes: [127.0.0.1:10002_solr] added at times [1829612082916381], now=1829613835550881 [junit4] 2> 1703998 DEBUG (ScheduledTrigger-2693-thread-4) [ ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"68005c2476c1dT5hva8tnv73xs97gc1pb9bjp33", [junit4] 2> "source":"node_added_trigger", [junit4] 2> "eventTime":1829612082916381, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[1829612082916381], [junit4] 2> "nodeNames":["127.0.0.1:10002_solr"]}} [junit4] 2> 1703998 DEBUG (ScheduledTrigger-2693-thread-4) [ ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [.auto_add_replicas, node_added_trigger] [junit4] 2> 1704070 DEBUG (AutoscalingActionExecutor-2694-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"68005c2476c1dT5hva8tnv73xs97gc1pb9bjp33", [junit4] 2> "source":"node_added_trigger", [junit4] 2> "eventTime":1829612082916381, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[1829612082916381], [junit4] 2> "_enqueue_time_":1829613862328881, [junit4] 2> "nodeNames":["127.0.0.1:10002_solr"]}} [junit4] 2> 1704083 DEBUG (simCloudManagerPool-2692-thread-3) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 1704083 DEBUG (simCloudManagerPool-2692-thread-3) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 4 [junit4] 2> 1704103 DEBUG (simCloudManagerPool-2692-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10001_solr, 127.0.0.1:10000_solr, 127.0.0.1:10002_solr] [junit4] 2> 1704103 DEBUG (simCloudManagerPool-2692-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:10001_solr, 127.0.0.1:10000_solr, 127.0.0.1:10002_solr] [junit4] 2> 1704103 DEBUG (simCloudManagerPool-2692-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=1, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration$TestTriggerAction}], enabled=true} [junit4] 2> 1704107 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4 [junit4] 2> 1704107 DEBUG (AutoscalingActionExecutor-2694-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Resuming trigger: .auto_add_replicas after 100ms [junit4] 2> 1704107 DEBUG (AutoscalingActionExecutor-2694-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger after 100ms [junit4] 2> 1704107 DEBUG (AutoscalingActionExecutor-2694-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing took 1860 ms for event id=68005c2476c1dT5hva8tnv73xs97gc1pb9bjp33 [junit4] 2> 1704107 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion 4 [junit4] 2> 1704155 INFO (TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[7E61BA1420306C65]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testNodeAddedTrigger [junit4] 2> 1704155 INFO (TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase [junit4] 2> [junit4] 2> 1704155 INFO (TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ############################################# [junit4] 2> 1704155 INFO (TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ############ FINAL CLUSTER STATS ############ [junit4] 2> 1704156 INFO (TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ############################################# [junit4] 2> [junit4] 2> 1704156 INFO (TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Live nodes: 3 [junit4] 2> 1704156 INFO (TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Empty nodes: 3 [junit4] 2> 1704156 INFO (TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Dead nodes: 0 [junit4] 2> 1704157 INFO (TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Collections: [] [junit4] 2> 1704157 INFO (TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Max replicas per node: 0 [junit4] 2> 1704157 INFO (TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Min replicas per node: 0 [junit4] 2> 1704157 INFO (TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Total replicas: 0 [junit4] 2> 1704157 INFO (TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ######### Final Solr op counts ########## [junit4] 2> 1704158 INFO (TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## - autoscaling 2 [junit4] 2> 1704158 INFO (TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## - update 2 [junit4] 2> 1704158 INFO (TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ######### Autoscaling event counts ########### [junit4] 2> 1704159 INFO (TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## * Trigger: node_added_trigger [junit4] 2> 1704172 INFO (TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## - STARTED 1 [junit4] 2> 1704172 INFO (TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## - SUCCEEDED 1 [junit4] 2> 1704175 INFO (TEST-TestTriggerIntegration.testCooldown-seed#[7E61BA1420306C65]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCooldown [junit4] 2> 1704175 DEBUG (TEST-TestTriggerIntegration.testCooldown-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 5 [junit4] 2> 1704180 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 5 [junit4] 2> 1704180 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 1704180 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 1704180 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVersion 5 [junit4] 2> 1704195 INFO (TEST-TestTriggerIntegration.testCooldown-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and clearing object cache... [junit4] 2> 1704195 DEBUG (TEST-TestTriggerIntegration.testCooldown-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly [junit4] 2> 1704196 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger [junit4] 2> 1704196 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 6 [junit4] 2> 1704196 WARN (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 1704196 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10000_solr, 127.0.0.1:10002_solr] [junit4] 2> 1704196 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6, lastZnodeVersion -1 [junit4] 2> 1704196 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 6 [junit4] 2> 1704197 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 1704197 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6, lastZnodeVersion 6 [junit4] 2> 1704197 DEBUG (ScheduledTrigger-2696-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1704236 DEBUG (ScheduledTrigger-2696-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1704259 DEBUG (ScheduledTrigger-2696-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1704293 DEBUG (ScheduledTrigger-2696-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1704298 DEBUG (simCloudManagerPool-2695-thread-1) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 1704298 DEBUG (simCloudManagerPool-2695-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 7 [junit4] 2> 1704299 DEBUG (simCloudManagerPool-2695-thread-1) [ ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:10000_solr, 127.0.0.1:10002_solr] [junit4] 2> 1704299 DEBUG (simCloudManagerPool-2695-thread-1) [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_cooldown_trigger instantiated with properties: {event=nodeAdded, waitFor=1, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration$TestTriggerAction}], enabled=true} [junit4] 2> 1704299 DEBUG (simCloudManagerPool-2695-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10000_solr, 127.0.0.1:10002_solr] [junit4] 2> 1704299 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 7 [junit4] 2> 1704322 DEBUG (ScheduledTrigger-2696-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1704323 DEBUG (simCloudManagerPool-2695-thread-2) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 1704323 DEBUG (simCloudManagerPool-2695-thread-2) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 8 [junit4] 2> 1704299 INFO (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.s.TestTriggerIntegration TestTriggerAction init [junit4] 2> 1704323 DEBUG (ScheduledTrigger-2696-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_cooldown_trigger [junit4] 2> 1704323 DEBUG (ScheduledTrigger-2696-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2 [junit4] 2> 1704323 DEBUG (simCloudManagerPool-2695-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:10000_solr, 127.0.0.1:10002_solr] [junit4] 2> 1704323 DEBUG (simCloudManagerPool-2695-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_cooldown_trigger instantiated with properties: {event=nodeAdded, waitFor=1, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration$TestTriggerAction}], enabled=true} [junit4] 2> 1704323 DEBUG (simCloudManagerPool-2695-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10000_solr, 127.0.0.1:10002_solr] [junit4] 2> 1704324 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 8, lastZnodeVersion 7 [junit4] 2> 1704324 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 8 [junit4] 2> 1704324 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 8, lastZnodeVersion 8 [junit4] 2> 1704342 DEBUG (ScheduledTrigger-2696-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 3 [junit4] 2> 1704343 DEBUG (ScheduledTrigger-2696-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_cooldown_trigger [junit4] 2> 1704343 DEBUG (ScheduledTrigger-2696-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 1704343 DEBUG (ScheduledTrigger-2696-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:10003_solr at time 1829631125073831 [junit4] 2> 1704362 DEBUG (ScheduledTrigger-2696-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 3 [junit4] 2> 1704379 DEBUG (ScheduledTrigger-2696-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_cooldown_trigger [junit4] 2> 1704379 DEBUG (ScheduledTrigger-2696-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 1704379 DEBUG (ScheduledTrigger-2696-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_cooldown_trigger firing registered processor for nodes: [127.0.0.1:10003_solr] added at times [1829631125073831], now=1829632927729381 [junit4] 2> 1704380 DEBUG (ScheduledTrigger-2696-thread-3) [ ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"6800a3147afa7T5hva8tnv73xs97gc1pb9bjp36", [junit4] 2> "source":"node_added_cooldown_trigger", [junit4] 2> "eventTime":1829631125073831, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[1829631125073831], [junit4] 2> "nodeNames":["127.0.0.1:10003_solr"]}} [junit4] 2> 1704416 DEBUG (ScheduledTrigger-2696-thread-3) [ ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [.auto_add_replicas, node_added_cooldown_trigger] [junit4] 2> 1704416 DEBUG (AutoscalingActionExecutor-2697-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"6800a3147afa7T5hva8tnv73xs97gc1pb9bjp36", [junit4] 2> "source":"node_added_cooldown_trigger", [junit4] 2> "eventTime":1829631125073831, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[1829631125073831], [junit4] 2> "_enqueue_time_":1829634742747131, [junit4] 2> "nodeNames":["127.0.0.1:10003_solr"]}} [junit4] 2> 1704418 DEBUG (AutoscalingActionExecutor-2697-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Resuming trigger: .auto_add_replicas after 100ms [junit4] 2> 1704418 DEBUG (AutoscalingActionExecutor-2697-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_cooldown_trigger after 100ms [junit4] 2> 1704418 DEBUG (AutoscalingActionExecutor-2697-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing took 92 ms for event id=6800a3147afa7T5hva8tnv73xs97gc1pb9bjp36 [junit4] 2> 1704518 DEBUG (ScheduledTrigger-2696-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 1704519 DEBUG (ScheduledTrigger-2696-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_cooldown_trigger [junit4] 2> 1704519 DEBUG (ScheduledTrigger-2696-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4 [junit4] 2> 1704519 DEBUG (ScheduledTrigger-2696-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:10004_solr at time 1829639889053281 [junit4] 2> 1704539 DEBUG (ScheduledTrigger-2696-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 1704539 DEBUG (ScheduledTrigger-2696-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_cooldown_trigger [junit4] 2> 1704539 DEBUG (ScheduledTrigger-2696-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4 [junit4] 2> 1704539 DEBUG (ScheduledTrigger-2696-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_cooldown_trigger firing registered processor for nodes: [127.0.0.1:10004_solr] added at times [1829639889053281], now=1829640901068581 [junit4] 2> 1704539 DEBUG (ScheduledTrigger-2696-thread-2) [ ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"6800c3ba76661T5hva8tnv73xs97gc1pb9bjp39", [junit4] 2> "source":"node_added_cooldown_trigger", [junit4] 2> "eventTime":1829639889053281, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[1829639889053281], [junit4] 2> "nodeNames":["127.0.0.1:10004_solr"]}} [junit4] 2> 1704539 DEBUG (ScheduledTrigger-2696-thread-2) [ ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [.auto_add_replicas, node_added_cooldown_trigger] [junit4] 2> 1704540 DEBUG (AutoscalingActionExecutor-2697-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"6800c3ba76661T5hva8tnv73xs97gc1pb9bjp39", [junit4] 2> "source":"node_added_cooldown_trigger", [junit4] 2> "eventTime":1829639889053281, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[1829639889053281], [junit4] 2> "_enqueue_time_":1829640910811281, [junit4] 2> "nodeNames":["127.0.0.1:10004_solr"]}} [junit4] 2> 1704540 DEBUG (AutoscalingActionExecutor-2697-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Resuming trigger: .auto_add_replicas after 100ms [junit4] 2> 1704540 DEBUG (AutoscalingActionExecutor-2697-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_cooldown_trigger after 100ms [junit4] 2> 1704540 DEBUG (AutoscalingActionExecutor-2697-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing took 25 ms for event id=6800c3ba76661T5hva8tnv73xs97gc1pb9bjp39 [junit4] 2> 1704580 INFO (TEST-TestTriggerIntegration.testCooldown-seed#[7E61BA1420306C65]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCooldown [junit4] 2> 1704580 INFO (TEST-TestTriggerIntegration.testCooldown-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase [junit4] 2> [junit4] 2> 1704580 INFO (TEST-TestTriggerIntegration.testCooldown-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ############################################# [junit4] 2> 1704580 INFO (TEST-TestTriggerIntegration.testCooldown-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ############ FINAL CLUSTER STATS ############ [junit4] 2> 1704580 INFO (TEST-TestTriggerIntegration.testCooldown-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ############################################# [junit4] 2> [junit4] 2> 1704580 INFO (TEST-TestTriggerIntegration.testCooldown-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Live nodes: 4 [junit4] 2> 1704580 INFO (TEST-TestTriggerIntegration.testCooldown-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Empty nodes: 4 [junit4] 2> 1704580 INFO (TEST-TestTriggerIntegration.testCooldown-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Dead nodes: 0 [junit4] 2> 1704580 INFO (TEST-TestTriggerIntegration.testCooldown-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Collections: [] [junit4] 2> 1704580 INFO (TEST-TestTriggerIntegration.testCooldown-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Max replicas per node: 0 [junit4] 2> 1704581 INFO (TEST-TestTriggerIntegration.testCooldown-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Min replicas per node: 0 [junit4] 2> 1704581 INFO (TEST-TestTriggerIntegration.testCooldown-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Total replicas: 0 [junit4] 2> 1704581 INFO (TEST-TestTriggerIntegration.testCooldown-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ######### Final Solr op counts ########## [junit4] 2> 1704581 INFO (TEST-TestTriggerIntegration.testCooldown-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## - autoscaling 2 [junit4] 2> 1704581 INFO (TEST-TestTriggerIntegration.testCooldown-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## - update 4 [junit4] 2> 1704581 INFO (TEST-TestTriggerIntegration.testCooldown-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ######### Autoscaling event counts ########### [junit4] 2> 1704581 INFO (TEST-TestTriggerIntegration.testCooldown-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## * Trigger: node_added_cooldown_trigger [junit4] 2> 1704581 INFO (TEST-TestTriggerIntegration.testCooldown-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## - STARTED 2 [junit4] 2> 1704581 INFO (TEST-TestTriggerIntegration.testCooldown-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## - SUCCEEDED 2 [junit4] 2> 1704584 INFO (TEST-TestTriggerIntegration.testNodeLostTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testNodeLostTriggerRestoreState [junit4] 2> 1704584 DEBUG (TEST-TestTriggerIntegration.testNodeLostTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 9 [junit4] 2> 1704585 INFO (TEST-TestTriggerIntegration.testNodeLostTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and clearing object cache... [junit4] 2> 1704585 WARN (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 1704586 DEBUG (TEST-TestTriggerIntegration.testNodeLostTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly [junit4] 2> 1704586 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger [junit4] 2> 1704587 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 10 [junit4] 2> 1704587 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10004_solr, 127.0.0.1:10002_solr] [junit4] 2> 1704587 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 10, lastZnodeVersion -1 [junit4] 2> 1704587 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 10 [junit4] 2> 1704587 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 1704587 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 10, lastZnodeVersion 10 [junit4] 2> 1704587 DEBUG (ScheduledTrigger-2699-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1704607 DEBUG (ScheduledTrigger-2699-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1704628 DEBUG (ScheduledTrigger-2699-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1704648 DEBUG (ScheduledTrigger-2699-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1704668 DEBUG (ScheduledTrigger-2699-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1704688 DEBUG (simCloudManagerPool-2698-thread-1) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 1704688 DEBUG (simCloudManagerPool-2698-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 11 [junit4] 2> 1704688 DEBUG (simCloudManagerPool-2698-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10004_solr, 127.0.0.1:10005_solr, 127.0.0.1:10002_solr] [junit4] 2> 1704689 DEBUG (simCloudManagerPool-2698-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10004_solr, 127.0.0.1:10005_solr, 127.0.0.1:10002_solr] [junit4] 2> 1704689 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 11 [junit4] 2> 1704689 INFO (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.s.TestTriggerIntegration TestTriggerAction init [junit4] 2> 1704689 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 1704689 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 11, lastZnodeVersion 11 [junit4] 2> 1704689 DEBUG (ScheduledTrigger-2699-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 3 [junit4] 2> 1704689 DEBUG (ScheduledTrigger-2699-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_restore_trigger with currently live nodes: 3 [junit4] 2> 1704709 DEBUG (ScheduledTrigger-2699-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1704709 DEBUG (ScheduledTrigger-2699-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Tracking lost node: 127.0.0.1:10005_solr [junit4] 2> 1704709 DEBUG (ScheduledTrigger-2699-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_restore_trigger with currently live nodes: 2 [junit4] 2> 1704709 DEBUG (ScheduledTrigger-2699-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Tracking lost node: 127.0.0.1:10005_solr [junit4] 2> 1704720 DEBUG (simCloudManagerPool-2698-thread-2) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 1704720 DEBUG (simCloudManagerPool-2698-thread-2) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 12 [junit4] 2> 1704720 DEBUG (simCloudManagerPool-2698-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10004_solr, 127.0.0.1:10002_solr] [junit4] 2> 1704721 DEBUG (simCloudManagerPool-2698-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10004_solr, 127.0.0.1:10002_solr] [junit4] 2> 1704721 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 12 [junit4] 2> 1704721 INFO (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.s.TestTriggerIntegration TestTriggerAction init [junit4] 2> 1704721 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger Adding lost node from marker path: 127.0.0.1:10005_solr [junit4] 2> 1704721 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 1704721 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 12, lastZnodeVersion 12 [junit4] 2> 1704721 DEBUG (ScheduledTrigger-2699-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_restore_trigger with currently live nodes: 2 [junit4] 2> 1704721 DEBUG (ScheduledTrigger-2699-thread-3) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger firing registered processor for lost nodes: [127.0.0.1:10005_solr] [junit4] 2> 1704721 DEBUG (ScheduledTrigger-2699-thread-3) [ ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"6800e97146bf5T5hva8tnv73xs97gc1pb9bjp3c", [junit4] 2> "source":"node_lost_restore_trigger", [junit4] 2> "eventTime":1829650012859381, [junit4] 2> "eventType":"NODELOST", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[1829650012859381], [junit4] 2> "nodeNames":["127.0.0.1:10005_solr"]}} [junit4] 2> 1704722 DEBUG (ScheduledTrigger-2699-thread-3) [ ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [.auto_add_replicas, node_lost_restore_trigger] [junit4] 2> 1704722 DEBUG (AutoscalingActionExecutor-2700-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"6800e97146bf5T5hva8tnv73xs97gc1pb9bjp3c", [junit4] 2> "source":"node_lost_restore_trigger", [junit4] 2> "eventTime":1829650012859381, [junit4] 2> "eventType":"NODELOST", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[1829650012859381], [junit4] 2> "_enqueue_time_":1829650041560381, [junit4] 2> "nodeNames":["127.0.0.1:10005_solr"]}} [junit4] 2> 1704722 INFO (TEST-TestTriggerIntegration.testNodeLostTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testNodeLostTriggerRestoreState [junit4] 2> 1704722 INFO (TEST-TestTriggerIntegration.testNodeLostTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase [junit4] 2> [junit4] 2> 1704722 INFO (TEST-TestTriggerIntegration.testNodeLostTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ############################################# [junit4] 2> 1704722 INFO (TEST-TestTriggerIntegration.testNodeLostTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ############ FINAL CLUSTER STATS ############ [junit4] 2> 1704723 INFO (TEST-TestTriggerIntegration.testNodeLostTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ############################################# [junit4] 2> [junit4] 2> 1704723 INFO (TEST-TestTriggerIntegration.testNodeLostTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Live nodes: 2 [junit4] 2> 1704723 INFO (TEST-TestTriggerIntegration.testNodeLostTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Empty nodes: 2 [junit4] 2> 1704723 INFO (TEST-TestTriggerIntegration.testNodeLostTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Dead nodes: 1 [junit4] 2> 1704723 INFO (TEST-TestTriggerIntegration.testNodeLostTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## 127.0.0.1:10005_solr [junit4] 2> 1704723 INFO (TEST-TestTriggerIntegration.testNodeLostTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Collections: [] [junit4] 2> 1704723 INFO (TEST-TestTriggerIntegration.testNodeLostTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Max replicas per node: 0 [junit4] 2> 1704723 INFO (TEST-TestTriggerIntegration.testNodeLostTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Min replicas per node: 0 [junit4] 2> 1704723 INFO (TEST-TestTriggerIntegration.testNodeLostTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Total replicas: 0 [junit4] 2> 1704723 INFO (TEST-TestTriggerIntegration.testNodeLostTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ######### Final Solr op counts ########## [junit4] 2> 1704723 INFO (TEST-TestTriggerIntegration.testNodeLostTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## - autoscaling 2 [junit4] 2> 1704723 INFO (TEST-TestTriggerIntegration.testNodeLostTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## - update 1 [junit4] 2> 1704723 INFO (TEST-TestTriggerIntegration.testNodeLostTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ######### Autoscaling event counts ########### [junit4] 2> 1704723 INFO (TEST-TestTriggerIntegration.testNodeLostTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## * Trigger: node_lost_restore_trigger [junit4] 2> 1704723 INFO (TEST-TestTriggerIntegration.testNodeLostTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## - STARTED 1 [junit4] 2> 1704726 INFO (TEST-TestTriggerIntegration.testListeners-seed#[7E61BA1420306C65]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testListeners [junit4] 2> 1704726 DEBUG (TEST-TestTriggerIntegration.testListeners-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 13 [junit4] 2> 1704726 DEBUG (TEST-TestTriggerIntegration.testListeners-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimNodeStateProvider - removing dead node values: 127.0.0.1:10005_solr [junit4] 2> 1704726 INFO (TEST-TestTriggerIntegration.testListeners-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and clearing object cache... [junit4] 2> 1704726 WARN (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 1704726 DEBUG (TEST-TestTriggerIntegration.testListeners-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly [junit4] 2> 1704727 WARN (AutoscalingActionExecutor-2700-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Exception executing actions [junit4] 2> org.apache.lucene.store.AlreadyClosedException: ScheduledTrigger node_lost_restore_trigger has been closed. [junit4] 2> at org.apache.solr.cloud.autoscaling.ScheduledTriggers$ScheduledTrigger.dequeue(ScheduledTriggers.java:522) [junit4] 2> at org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:307) [junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 1704727 DEBUG (AutoscalingActionExecutor-2700-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing took 249 ms for event id=6800e97146bf5T5hva8tnv73xs97gc1pb9bjp3c [junit4] 2> 1704727 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger [junit4] 2> 1704728 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 14 [junit4] 2> 1704728 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10004_solr, 127.0.0.1:10002_solr] [junit4] 2> 1704728 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 14, lastZnodeVersion -1 [junit4] 2> 1704728 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 14 [junit4] 2> 1704728 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 1704728 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 14, lastZnodeVersion 14 [junit4] 2> 1704748 DEBUG (ScheduledTrigger-2702-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1704777 DEBUG (ScheduledTrigger-2702-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1704797 DEBUG (ScheduledTrigger-2702-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1704850 DEBUG (ScheduledTrigger-2702-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1704852 DEBUG (simCloudManagerPool-2701-thread-1) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 1704852 DEBUG (simCloudManagerPool-2701-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 15 [junit4] 2> 1704852 DEBUG (simCloudManagerPool-2701-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10004_solr, 127.0.0.1:10002_solr] [junit4] 2> 1704852 DEBUG (simCloudManagerPool-2701-thread-1) [ ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:10004_solr, 127.0.0.1:10002_solr] [junit4] 2> 1704852 DEBUG (simCloudManagerPool-2701-thread-1) [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=1, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration$TestTriggerAction}, {name=test1, class=org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration$TestDummyAction}], enabled=true} [junit4] 2> 1704866 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 15 [junit4] 2> 1704866 INFO (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.s.TestTriggerIntegration TestTriggerAction init [junit4] 2> 1704866 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 15, lastZnodeVersion 15 [junit4] 2> 1704867 DEBUG (ScheduledTrigger-2702-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 1704867 DEBUG (ScheduledTrigger-2702-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2 [junit4] 2> 1704867 DEBUG (simCloudManagerPool-2701-thread-2) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 1704867 DEBUG (simCloudManagerPool-2701-thread-2) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 16 [junit4] 2> 1704868 DEBUG (simCloudManagerPool-2701-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10004_solr, 127.0.0.1:10002_solr] [junit4] 2> 1704868 DEBUG (simCloudManagerPool-2701-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:10004_solr, 127.0.0.1:10002_solr] [junit4] 2> 1704868 DEBUG (simCloudManagerPool-2701-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=1, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration$TestTriggerAction}, {name=test1, class=org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration$TestDummyAction}], enabled=true} [junit4] 2> 1704868 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 16 [junit4] 2> 1704868 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 16, lastZnodeVersion 16 [junit4] 2> 1704868 DEBUG (simCloudManagerPool-2701-thread-3) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 1704869 DEBUG (simCloudManagerPool-2701-thread-3) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 17 [junit4] 2> 1704869 DEBUG (simCloudManagerPool-2701-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10004_solr, 127.0.0.1:10002_solr] [junit4] 2> 1704869 DEBUG (simCloudManagerPool-2701-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:10004_solr, 127.0.0.1:10002_solr] [junit4] 2> 1704869 DEBUG (simCloudManagerPool-2701-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=1, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration$TestTriggerAction}, {name=test1, class=org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration$TestDummyAction}], enabled=true} [junit4] 2> 1704869 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 17 [junit4] 2> 1704869 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 17, lastZnodeVersion 17 [junit4] 2> 1704886 DEBUG (ScheduledTrigger-2702-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 3 [junit4] 2> 1704887 DEBUG (ScheduledTrigger-2702-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 1704887 DEBUG (ScheduledTrigger-2702-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 1704887 DEBUG (ScheduledTrigger-2702-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:10006_solr at time 1829658306461131 [junit4] 2> 1704907 DEBUG (ScheduledTrigger-2702-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 3 [junit4] 2> 1704988 DEBUG (ScheduledTrigger-2702-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 1704988 DEBUG (ScheduledTrigger-2702-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 1704988 DEBUG (ScheduledTrigger-2702-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger firing registered processor for nodes: [127.0.0.1:10006_solr] added at times [1829658306461131], now=1829663371832381 [junit4] 2> 1704988 DEBUG (ScheduledTrigger-2702-thread-4) [ ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"68010856abdcbT5hva8tnv73xs97gc1pb9bjp3e", [junit4] 2> "source":"node_added_trigger", [junit4] 2> "eventTime":1829658306461131, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[1829658306461131], [junit4] 2> "nodeNames":["127.0.0.1:10006_solr"]}} [junit4] 2> 1704989 DEBUG (ScheduledTrigger-2702-thread-4) [ ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [.auto_add_replicas, node_added_trigger] [junit4] 2> 1704992 DEBUG (AutoscalingActionExecutor-2703-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"68010856abdcbT5hva8tnv73xs97gc1pb9bjp3e", [junit4] 2> "source":"node_added_trigger", [junit4] 2> "eventTime":1829658306461131, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[1829658306461131], [junit4] 2> "_enqueue_time_":1829663387096381, [junit4] 2> "nodeNames":["127.0.0.1:10006_solr"]}} [junit4] 2> 1705044 DEBUG (AutoscalingActionExecutor-2703-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Resuming trigger: .auto_add_replicas after 100ms [junit4] 2> 1705044 DEBUG (AutoscalingActionExecutor-2703-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger after 100ms [junit4] 2> 1705044 DEBUG (AutoscalingActionExecutor-2703-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing took 2612 ms for event id=68010856abdcbT5hva8tnv73xs97gc1pb9bjp3e [junit4] 2> 1705144 DEBUG (ScheduledTrigger-2702-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 1705145 DEBUG (ScheduledTrigger-2702-thread-1) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 1705145 DEBUG (ScheduledTrigger-2702-thread-1) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4 [junit4] 2> 1705145 DEBUG (ScheduledTrigger-2702-thread-1) [ ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:10007_solr at time 1829671202810831 [junit4] 2> 1705179 DEBUG (ScheduledTrigger-2702-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 1705179 DEBUG (ScheduledTrigger-2702-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 1705179 DEBUG (ScheduledTrigger-2702-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4 [junit4] 2> 1705179 DEBUG (ScheduledTrigger-2702-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger firing registered processor for nodes: [127.0.0.1:10007_solr] added at times [1829671202810831], now=1829672929183981 [junit4] 2> 1705180 DEBUG (ScheduledTrigger-2702-thread-4) [ ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"6801386196bcfT5hva8tnv73xs97gc1pb9bjp3h", [junit4] 2> "source":"node_added_trigger", [junit4] 2> "eventTime":1829671202810831, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[1829671202810831], [junit4] 2> "nodeNames":["127.0.0.1:10007_solr"]}} [junit4] 2> 1705180 DEBUG (ScheduledTrigger-2702-thread-4) [ ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [.auto_add_replicas, node_added_trigger] [junit4] 2> 1705181 DEBUG (AutoscalingActionExecutor-2703-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"6801386196bcfT5hva8tnv73xs97gc1pb9bjp3h", [junit4] 2> "source":"node_added_trigger", [junit4] 2> "eventTime":1829671202810831, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[1829671202810831], [junit4] 2> "_enqueue_time_":1829672940257581, [junit4] 2> "nodeNames":["127.0.0.1:10007_solr"]}} [junit4] 2> 1705200 WARN (AutoscalingActionExecutor-2703-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Exception executing actions [junit4] 2> java.lang.Exception: Error executing action: test1 for trigger event: { [junit4] 2> "id":"6801386196bcfT5hva8tnv73xs97gc1pb9bjp3h", [junit4] 2> "source":"node_added_trigger", [junit4] 2> "eventTime":1829671202810831, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[1829671202810831], [junit4] 2> "_enqueue_time_":1829672940257581, [junit4] 2> "nodeNames":["127.0.0.1:10007_solr"]}} [junit4] 2> at org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:300) [junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> Caused by: java.lang.RuntimeException: failure [junit4] 2> at org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration$TestDummyAction.process(TestTriggerIntegration.java:892) [junit4] 2> at org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:297) [junit4] 2> ... 6 more [junit4] 2> 1705200 DEBUG (AutoscalingActionExecutor-2703-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Resuming trigger: .auto_add_replicas after 100ms [junit4] 2> 1705200 DEBUG (AutoscalingActionExecutor-2703-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger after 100ms [junit4] 2> 1705200 DEBUG (AutoscalingActionExecutor-2703-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing took 966 ms for event id=6801386196bcfT5hva8tnv73xs97gc1pb9bjp3h [junit4] 2> 1705281 INFO (TEST-TestTriggerIntegration.testListeners-seed#[7E61BA1420306C65]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testListeners [junit4] 2> 1705281 INFO (TEST-TestTriggerIntegration.testListeners-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase [junit4] 2> [junit4] 2> 1705281 INFO (TEST-TestTriggerIntegration.testListeners-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ############################################# [junit4] 2> 1705281 INFO (TEST-TestTriggerIntegration.testListeners-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ############ FINAL CLUSTER STATS ############ [junit4] 2> 1705281 INFO (TEST-TestTriggerIntegration.testListeners-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ############################################# [junit4] 2> [junit4] 2> 1705281 INFO (TEST-TestTriggerIntegration.testListeners-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Live nodes: 4 [junit4] 2> 1705281 INFO (TEST-TestTriggerIntegration.testListeners-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Empty nodes: 4 [junit4] 2> 1705281 INFO (TEST-TestTriggerIntegration.testListeners-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Dead nodes: 0 [junit4] 2> 1705281 INFO (TEST-TestTriggerIntegration.testListeners-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Collections: [] [junit4] 2> 1705281 INFO (TEST-TestTriggerIntegration.testListeners-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Max replicas per node: 0 [junit4] 2> 1705281 INFO (TEST-TestTriggerIntegration.testListeners-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Min replicas per node: 0 [junit4] 2> 1705281 INFO (TEST-TestTriggerIntegration.testListeners-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Total replicas: 0 [junit4] 2> 1705281 INFO (TEST-TestTriggerIntegration.testListeners-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ######### Final Solr op counts ########## [junit4] 2> 1705282 INFO (TEST-TestTriggerIntegration.testListeners-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## - autoscaling 3 [junit4] 2> 1705282 INFO (TEST-TestTriggerIntegration.testListeners-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## - update 4 [junit4] 2> 1705282 INFO (TEST-TestTriggerIntegration.testListeners-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ######### Autoscaling event counts ########### [junit4] 2> 1705282 INFO (TEST-TestTriggerIntegration.testListeners-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## * Trigger: node_added_trigger [junit4] 2> 1705282 INFO (TEST-TestTriggerIntegration.testListeners-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## - FAILED 1 [junit4] 2> 1705282 INFO (TEST-TestTriggerIntegration.testListeners-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## - STARTED 2 [junit4] 2> 1705282 INFO (TEST-TestTriggerIntegration.testListeners-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## - SUCCEEDED 1 [junit4] 2> 1705301 INFO (TEST-TestTriggerIntegration.testNodeAddedTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testNodeAddedTriggerRestoreState [junit4] 2> 1705301 DEBUG (TEST-TestTriggerIntegration.testNodeAddedTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 18 [junit4] 2> 1705301 INFO (TEST-TestTriggerIntegration.testNodeAddedTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and clearing object cache... [junit4] 2> 1705334 WARN (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 1705362 DEBUG (TEST-TestTriggerIntegration.testNodeAddedTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly [junit4] 2> 1705363 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger [junit4] 2> 1705363 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 19 [junit4] 2> 1705363 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10004_solr, 127.0.0.1:10002_solr] [junit4] 2> 1705363 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 19, lastZnodeVersion -1 [junit4] 2> 1705363 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 19 [junit4] 2> 1705364 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 1705364 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 19, lastZnodeVersion 19 [junit4] 2> 1705364 DEBUG (ScheduledTrigger-2705-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1705399 DEBUG (ScheduledTrigger-2705-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1705427 DEBUG (ScheduledTrigger-2705-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1705451 DEBUG (ScheduledTrigger-2705-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1705474 DEBUG (simCloudManagerPool-2704-thread-1) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 1705488 DEBUG (simCloudManagerPool-2704-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 20 [junit4] 2> 1705488 DEBUG (ScheduledTrigger-2705-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1705505 DEBUG (simCloudManagerPool-2704-thread-1) [ ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:10004_solr, 127.0.0.1:10002_solr] [junit4] 2> 1705505 DEBUG (simCloudManagerPool-2704-thread-1) [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_restore_trigger instantiated with properties: {event=nodeAdded, waitFor=5, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration$TestTriggerAction}], enabled=true} [junit4] 2> 1705505 DEBUG (simCloudManagerPool-2704-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10004_solr, 127.0.0.1:10002_solr] [junit4] 2> 1705508 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 20 [junit4] 2> 1705508 INFO (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.s.TestTriggerIntegration TestTriggerAction init [junit4] 2> 1705508 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 20, lastZnodeVersion 20 [junit4] 2> 1705524 DEBUG (ScheduledTrigger-2705-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1705525 DEBUG (ScheduledTrigger-2705-thread-1) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_restore_trigger [junit4] 2> 1705525 DEBUG (ScheduledTrigger-2705-thread-1) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2 [junit4] 2> 1705545 DEBUG (ScheduledTrigger-2705-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 3 [junit4] 2> 1705564 DEBUG (ScheduledTrigger-2705-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_restore_trigger [junit4] 2> 1705578 DEBUG (ScheduledTrigger-2705-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 1705578 DEBUG (ScheduledTrigger-2705-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:10008_solr at time 1829692837812331 [junit4] 2> 1705601 DEBUG (ScheduledTrigger-2705-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_restore_trigger [junit4] 2> 1705601 DEBUG (ScheduledTrigger-2705-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 1705601 DEBUG (ScheduledTrigger-2705-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 3 [junit4] 2> 1705602 DEBUG (simCloudManagerPool-2704-thread-2) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 1705614 DEBUG (simCloudManagerPool-2704-thread-2) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 21 [junit4] 2> 1705614 DEBUG (simCloudManagerPool-2704-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:10004_solr, 127.0.0.1:10002_solr, 127.0.0.1:10008_solr] [junit4] 2> 1705614 DEBUG (simCloudManagerPool-2704-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_restore_trigger instantiated with properties: {event=nodeAdded, waitFor=0, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration$TestTriggerAction}], enabled=true} [junit4] 2> 1705614 DEBUG (simCloudManagerPool-2704-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10004_solr, 127.0.0.1:10002_solr, 127.0.0.1:10008_solr] [junit4] 2> 1705615 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 21 [junit4] 2> 1705615 INFO (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.s.TestTriggerIntegration TestTriggerAction init [junit4] 2> 1705615 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeAddedTrigger Adding node from marker path: 127.0.0.1:10008_solr [junit4] 2> 1705615 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 21, lastZnodeVersion 21 [junit4] 2> 1705615 DEBUG (ScheduledTrigger-2705-thread-1) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_restore_trigger [junit4] 2> 1705615 DEBUG (ScheduledTrigger-2705-thread-1) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 1705615 DEBUG (ScheduledTrigger-2705-thread-1) [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_restore_trigger firing registered processor for nodes: [127.0.0.1:10008_solr] added at times [1829694707890031], now=1829694717537181 [junit4] 2> 1705615 DEBUG (ScheduledTrigger-2705-thread-1) [ ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"68018ff1c776fT5hva8tnv73xs97gc1pb9bjp3k", [junit4] 2> "source":"node_added_restore_trigger", [junit4] 2> "eventTime":1829694707890031, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[1829694707890031], [junit4] 2> "nodeNames":["127.0.0.1:10008_solr"]}} [junit4] 2> 1705616 DEBUG (ScheduledTrigger-2705-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [.auto_add_replicas, node_added_restore_trigger] [junit4] 2> 1705619 DEBUG (AutoscalingActionExecutor-2706-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"68018ff1c776fT5hva8tnv73xs97gc1pb9bjp3k", [junit4] 2> "source":"node_added_restore_trigger", [junit4] 2> "eventTime":1829694707890031, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[1829694707890031], [junit4] 2> "_enqueue_time_":1829694737436581, [junit4] 2> "nodeNames":["127.0.0.1:10008_solr"]}} [junit4] 2> 1705619 DEBUG (AutoscalingActionExecutor-2706-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Resuming trigger: .auto_add_replicas after 100ms [junit4] 2> 1705620 DEBUG (AutoscalingActionExecutor-2706-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_restore_trigger after 100ms [junit4] 2> 1705620 DEBUG (AutoscalingActionExecutor-2706-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing took 26 ms for event id=68018ff1c776fT5hva8tnv73xs97gc1pb9bjp3k [junit4] 2> 1705620 INFO (TEST-TestTriggerIntegration.testNodeAddedTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testNodeAddedTriggerRestoreState [junit4] 2> 1705620 INFO (TEST-TestTriggerIntegration.testNodeAddedTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase [junit4] 2> [junit4] 2> 1705620 INFO (TEST-TestTriggerIntegration.testNodeAddedTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ############################################# [junit4] 2> 1705620 INFO (TEST-TestTriggerIntegration.testNodeAddedTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ############ FINAL CLUSTER STATS ############ [junit4] 2> 1705620 INFO (TEST-TestTriggerIntegration.testNodeAddedTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ############################################# [junit4] 2> [junit4] 2> 1705620 INFO (TEST-TestTriggerIntegration.testNodeAddedTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Live nodes: 3 [junit4] 2> 1705620 INFO (TEST-TestTriggerIntegration.testNodeAddedTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Empty nodes: 3 [junit4] 2> 1705620 INFO (TEST-TestTriggerIntegration.testNodeAddedTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Dead nodes: 0 [junit4] 2> 1705620 INFO (TEST-TestTriggerIntegration.testNodeAddedTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Collections: [] [junit4] 2> 1705620 INFO (TEST-TestTriggerIntegration.testNodeAddedTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Max replicas per node: 0 [junit4] 2> 1705620 INFO (TEST-TestTriggerIntegration.testNodeAddedTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Min replicas per node: 0 [junit4] 2> 1705620 INFO (TEST-TestTriggerIntegration.testNodeAddedTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Total replicas: 0 [junit4] 2> 1705620 INFO (TEST-TestTriggerIntegration.testNodeAddedTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ######### Final Solr op counts ########## [junit4] 2> 1705620 INFO (TEST-TestTriggerIntegration.testNodeAddedTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## - autoscaling 2 [junit4] 2> 1705620 INFO (TEST-TestTriggerIntegration.testNodeAddedTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## - update 2 [junit4] 2> 1705620 INFO (TEST-TestTriggerIntegration.testNodeAddedTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ######### Autoscaling event counts ########### [junit4] 2> 1705620 INFO (TEST-TestTriggerIntegration.testNodeAddedTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## * Trigger: node_added_restore_trigger [junit4] 2> 1705620 INFO (TEST-TestTriggerIntegration.testNodeAddedTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## - STARTED 1 [junit4] 2> 1705620 INFO (TEST-TestTriggerIntegration.testNodeAddedTriggerRestoreState-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ## - SUCCEEDED 1 [junit4] 2> 1705636 INFO (TEST-TestTriggerIntegration.testNodeMarkersRegistration-seed#[7E61BA1420306C65]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testNodeMarkersRegistration [junit4] 2> 1705636 DEBUG (TEST-TestTriggerIntegration.testNodeMarkersRegistration-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 22 [junit4] 2> 1705636 INFO (TEST-TestTriggerIntegration.testNodeMarkersRegistration-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and clearing object cache... [junit4] 2> 1705636 DEBUG (TEST-TestTriggerIntegration.testNodeMarkersRegistration-seed#[7E61BA1420306C65]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly [junit4] 2> 1705637 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger [junit4] 2> 1705637 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 23 [junit4] 2> 1705637 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10004_solr, 127.0.0.1:10008_solr] [junit4] 2> 1705637 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 23, lastZnodeVersion -1 [junit4] 2> 1705637 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 23 [junit4] 2> 1705638 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 1705638 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 23, lastZnodeVersion 23 [junit4] 2> 1705638 DEBUG (ScheduledTrigger-2 [...truncated too long message...] eate(SolrZkClient.java:365) [junit4] 2> at org.apache.solr.cloud.ZkController.lambda$registerLiveNodesListener$2(ZkController.java:885) [junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.lambda$refreshLiveNodes$1(ZkStateReader.java:704) [junit4] 2> at java.util.concurrent.ConcurrentHashMap$KeySetView.forEach(ConcurrentHashMap.java:4649) [junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.refreshLiveNodes(ZkStateReader.java:703) [junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.access$900(ZkStateReader.java:72) [junit4] 2> at org.apache.solr.common.cloud.ZkStateReader$LiveNodeWatcher.refreshAndWatch(ZkStateReader.java:1090) [junit4] 2> at org.apache.solr.common.cloud.ZkStateReader$LiveNodeWatcher.process(ZkStateReader.java:1085) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$1.lambda$process$1(SolrZkClient.java:269) [junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 3020806 INFO (jetty-closer-2691-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@22272d6c{/solr,null,UNAVAILABLE} [junit4] 2> 3020807 INFO (jetty-closer-2691-thread-4) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 3021786 WARN (zkCallback-2740-thread-1-processing-n:127.0.0.1:40066_solr) [n:127.0.0.1:40066_solr c:replacenodetest_coll_notarget s:shard3 r:core_node16 x:replacenodetest_coll_notarget_shard3_replica_n9] o.a.s.c.SyncStrategy Closed, skipping sync up. [junit4] 2> 3021787 INFO (zkCallback-2740-thread-1-processing-n:127.0.0.1:40066_solr) [n:127.0.0.1:40066_solr c:replacenodetest_coll_notarget s:shard3 r:core_node16 x:replacenodetest_coll_notarget_shard3_replica_n9] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway [junit4] 2> 3021787 INFO (zkCallback-2740-thread-1-processing-n:127.0.0.1:40066_solr) [n:127.0.0.1:40066_solr c:replacenodetest_coll_notarget s:shard3 r:core_node16 x:replacenodetest_coll_notarget_shard3_replica_n9] o.a.s.c.SolrCore [replacenodetest_coll_notarget_shard3_replica_n9] CLOSING SolrCore org.apache.solr.core.SolrCore@2e92c93 [junit4] 2> 3021787 INFO (zkCallback-2740-thread-1-processing-n:127.0.0.1:40066_solr) [n:127.0.0.1:40066_solr c:replacenodetest_coll_notarget s:shard3 r:core_node16 x:replacenodetest_coll_notarget_shard3_replica_n9] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.replacenodetest_coll_notarget.shard3.replica_n9, tag=48835731 [junit4] 2> 3021787 INFO (zkCallback-2740-thread-1-processing-n:127.0.0.1:40066_solr) [n:127.0.0.1:40066_solr c:replacenodetest_coll_notarget s:shard3 r:core_node16 x:replacenodetest_coll_notarget_shard3_replica_n9] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2c2f341: rootName = solr_40066, domain = solr.core.replacenodetest_coll_notarget.shard3.replica_n9, service url = null, agent id = null] for registry solr.core.replacenodetest_coll_notarget.shard3.replica_n9 / com.codahale.metrics.MetricRegistry@105b7100 [junit4] 2> 3021795 INFO (zkCallback-2740-thread-1-processing-n:127.0.0.1:40066_solr) [n:127.0.0.1:40066_solr c:replacenodetest_coll_notarget s:shard3 r:core_node16 x:replacenodetest_coll_notarget_shard3_replica_n9] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.replacenodetest_coll_notarget.shard3.leader, tag=48835731 [junit4] 2> 3021837 INFO (jetty-closer-2691-thread-6) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@26663780{/solr,null,UNAVAILABLE} [junit4] 2> 3021838 INFO (jetty-closer-2691-thread-6) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 3021875 ERROR (SUITE-ReplaceNodeNoTargetTest-seed#[7E61BA1420306C65]-worker) [ ] 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> 3021890 INFO (SUITE-ReplaceNodeNoTargetTest-seed#[7E61BA1420306C65]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:46334 46334 [junit4] 2> 3021955 INFO (Thread-4108) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:46334 46334 [junit4] 2> 3021971 WARN (Thread-4108) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 9 /solr/collections/replacenodetest_coll_notarget/terms/shard4 [junit4] 2> 7 /solr/aliases.json [junit4] 2> 6 /solr/security.json [junit4] 2> 6 /solr/configs/conf1 [junit4] 2> 5 /solr/collections/replacenodetest_coll_notarget/terms/shard1 [junit4] 2> 5 /solr/collections/replacenodetest_coll_notarget/terms/shard3 [junit4] 2> 3 /solr/collections/replacenodetest_coll_notarget/terms/shard5 [junit4] 2> 2 /solr/collections/replacenodetest_coll_notarget/terms/shard2 [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 86 /solr/collections/replacenodetest_coll_notarget/state.json [junit4] 2> 7 /solr/clusterprops.json [junit4] 2> 7 /solr/clusterstate.json [junit4] 2> 2 /solr/collections/replacenodetest_coll_notarget/leader_elect/shard4/election/72177583022735376-core_node18-n_0000000000 [junit4] 2> 2 /solr/overseer_elect/election/72177583022735372-127.0.0.1:38198_solr-n_0000000003 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 7 /solr/live_nodes [junit4] 2> 7 /solr/collections [junit4] 2> [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.ReplaceNodeNoTargetTest_7E61BA1420306C65-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=787, maxMBSortInHeap=6.5115622176441565, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@29ba76a2), locale=et-EE, timezone=Asia/Yerevan [junit4] 2> NOTE: Linux 4.4.0-112-generic amd64/Oracle Corporation 1.8.0_152 (64-bit)/cpus=4,threads=1,free=255820296,total=512753664 [junit4] 2> NOTE: All tests run in this JVM: [CdcrBootstrapTest, TestImpersonationWithHadoopAuth, TestHdfsUpdateLog, DateMathParserTest, HighlighterMaxOffsetTest, NodeLostTriggerTest, TestPolicyCloud, TestLocalFSCloudBackupRestore, InfixSuggestersTest, AtomicUpdateProcessorFactoryTest, LargeFieldTest, TestConfigOverlay, HdfsWriteToMultipleCollectionsTest, TestFastWriter, TestJmxIntegration, ClusterStateUpdateTest, TestBadConfig, TestSuggestSpellingConverter, TestBulkSchemaConcurrent, CoreMergeIndexesAdminHandlerTest, BadCopyFieldTest, TestFieldSortValues, LeaderElectionTest, TestSolrCoreProperties, TestDistributedStatsComponentCardinality, TestLegacyFieldCache, OverriddenZkACLAndCredentialsProvidersTest, TestQuerySenderListener, LegacyCloudClusterPropTest, DistributedFacetPivotSmallTest, HLLSerializationTest, TestSystemCollAutoCreate, UpdateLogTest, TestSmileRequest, DistributedQueryComponentOptimizationTest, TestCloudSearcherWarming, TestCoreContainer, NumericFieldsTest, TestTrieFacet, BlockCacheTest, CollectionsAPIDistributedZkTest, TestRangeQuery, TestNumericRangeQuery64, TermVectorComponentTest, TestSQLHandlerNonCloud, PreAnalyzedFieldTest, TestFieldCacheSortRandom, HttpSolrCallGetCoreTest, SpatialFilterTest, TestMiniSolrCloudClusterSSL, TestRestManager, MinimalSchemaTest, DistributedSpellCheckComponentTest, TestSchemaManager, PeerSyncReplicationTest, TimeZoneUtilsTest, SolrIndexMetricsTest, TestDistribIDF, BJQParserTest, StatelessScriptUpdateProcessorFactoryTest, TestReloadDeadlock, TestTriggerIntegration, TestMaxTokenLenTokenizer, TestComplexPhraseLeadingWildcard, SolrMetricManagerTest, UUIDUpdateProcessorFallbackTest, TestSolrCoreSnapshots, PKIAuthenticationIntegrationTest, UninvertDocValuesMergePolicyTest, DistributedFacetPivotLargeTest, TestManagedStopFilterFactory, TestRawTransformer, NoCacheHeaderTest, HdfsRecoveryZkTest, WordBreakSolrSpellCheckerTest, CollectionsAPISolrJTest, TestDynamicFieldCollectionResource, TestLeaderElectionZkExpiry, ComputePlanActionTest, TestHashQParserPlugin, ZkStateReaderTest, TestTolerantUpdateProcessorCloud, TestSolrConfigHandler, TestNodeLostTrigger, TestCodecSupport, AutoscalingHistoryHandlerTest, TestDirectoryFactory, CoreAdminHandlerTest, SolrGangliaReporterTest, HttpTriggerListenerTest, CacheHeaderTest, TestCoreDiscovery, HdfsBasicDistributedZk2Test, TestStressInPlaceUpdates, ZkFailoverTest, StatsReloadRaceTest, TestDownShardTolerantSearch, TestSubQueryTransformerDistrib, NotRequiredUniqueKeyTest, TestClassicSimilarityFactory, TestConfigsApi, HttpPartitionTest, ShowFileRequestHandlerTest, TestInfoStreamLogging, TestRealTimeGet, TestReqParamsAPI, TlogReplayBufferedWhileIndexingTest, MoreLikeThisHandlerTest, SortSpecParsingTest, SuggesterTSTTest, TestNestedDocsSort, TestSSLRandomization, CoreSorterTest, TestLRUStatsCache, TestSchemaVersionResource, TestSizeLimitedDistributedMap, AliasIntegrationTest, TestJavabinTupleStreamParser, TestQueryTypes, ConvertedLegacyTest, SearchHandlerTest, TestUninvertingReader, TestLegacyTerms, TestFiltering, ReplaceNodeNoTargetTest] [junit4] Completed [344/778 (3!)] on J2 in 25.61s, 1 test, 1 failure <<< FAILURES! [...truncated 49957 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org