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: [email protected]
For additional commands, e-mail: [email protected]
