Build: https://builds.apache.org/job/Lucene-Solr-Tests-7.x/524/
1 tests failed.
FAILED:
org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration.testNodeLostTrigger
Error Message:
The TriggerAction should have been created by now
Stack Trace:
java.lang.AssertionError: The TriggerAction should have been created by now
at
__randomizedtesting.SeedInfo.seed([B5246C2488C2024A:B1DAC536B20880D0]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration.testNodeLostTrigger(TestTriggerIntegration.java:454)
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 1808 lines...]
[junit4] JVM J2: stdout was not empty, see:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/build/core/test/temp/junit4-J2-20180323_080138_197131128250282072604.sysout
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] codec: SimpleText, pf: Lucene50, dvf: Memory
[junit4] <<< JVM J2: EOF ----
[...truncated 10618 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration
[junit4] 2> Creating dataDir:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.sim.TestTriggerIntegration_B5246C2488C2024A-001/init-core-data-001
[junit4] 2> 706999 WARN
(SUITE-TestTriggerIntegration-seed#[B5246C2488C2024A]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 707016 INFO
(SUITE-TestTriggerIntegration-seed#[B5246C2488C2024A]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 707036 INFO
(SUITE-TestTriggerIntegration-seed#[B5246C2488C2024A]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 707036 INFO
(SUITE-TestTriggerIntegration-seed#[B5246C2488C2024A]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] IGNOR/A 0.00s J1 |
TestTriggerIntegration.testNodeLostTriggerRestoreState
[junit4] > Assumption #1: 'badapple' test group is disabled
(@BadApple(bugUrl=https://issues.apache.org/jira/browse/SOLR-12028))
[junit4] 2> 707317 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 707351 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 0
[junit4] 2> 707351 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> 707351 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion -1
[junit4] 2> 707351 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 0
[junit4] 2> 707385 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 707385 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion 0
[junit4] 2> 707444 DEBUG (ScheduledTrigger-584-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 707566 DEBUG (ScheduledTrigger-584-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 707603 DEBUG (ScheduledTrigger-584-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 707695 INFO
(TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.SolrTestCaseJ4 ###Starting testNodeAddedTrigger
[junit4] 2> 707712 DEBUG (ScheduledTrigger-584-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 707712 DEBUG
(TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 1
[junit4] 2> 707712 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 707715 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 707715 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 707715 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 707729 INFO
(TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and clearing
object cache...
[junit4] 2> 707729 WARN (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Interrupted
[junit4] 2> java.lang.InterruptedException
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2014)
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2048)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:177)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 707785 DEBUG
(TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed
explicitly
[junit4] 2> 707806 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 707806 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 2
[junit4] 2> 707806 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> 707806 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion -1
[junit4] 2> 707806 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 707842 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 707842 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 707842 DEBUG (ScheduledTrigger-587-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 707951 DEBUG (ScheduledTrigger-587-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 707969 DEBUG (simCloudManagerPool-586-thread-1) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 707969 DEBUG (simCloudManagerPool-586-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
3
[junit4] 2> 707988 DEBUG (ScheduledTrigger-587-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 707989 DEBUG (simCloudManagerPool-586-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10001_solr,
127.0.0.1:10000_solr]
[junit4] 2> 707992 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 707992 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 707992 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 708025 DEBUG (ScheduledTrigger-587-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 708045 DEBUG (ScheduledTrigger-587-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 708058 DEBUG (simCloudManagerPool-586-thread-2) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 708076 DEBUG (simCloudManagerPool-586-thread-2) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
4
[junit4] 2> 708077 DEBUG (simCloudManagerPool-586-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10001_solr,
127.0.0.1:10000_solr]
[junit4] 2> 708079 DEBUG (simCloudManagerPool-586-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:10001_solr,
127.0.0.1:10000_solr]
[junit4] 2> 708079 DEBUG (simCloudManagerPool-586-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger instantiated
with properties: {event=nodeAdded, waitFor=3, actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration$TestTriggerAction}],
enabled=true}
[junit4] 2> 708079 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4
[junit4] 2> 708080 INFO (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.s.TestTriggerIntegration TestTriggerAction init
[junit4] 2> 708080 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion 4
[junit4] 2> 708081 DEBUG (ScheduledTrigger-587-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3
[junit4] 2> 708081 DEBUG (ScheduledTrigger-587-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 708098 DEBUG (ScheduledTrigger-587-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 708098 DEBUG (ScheduledTrigger-587-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:10002_solr at time
4135299579857477
[junit4] 2> 708101 DEBUG (ScheduledTrigger-587-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3
[junit4] 2> 708147 DEBUG (ScheduledTrigger-587-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 708148 DEBUG (ScheduledTrigger-587-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 708148 DEBUG (ScheduledTrigger-587-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3
[junit4] 2> 708171 DEBUG (ScheduledTrigger-587-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3
[junit4] 2> 708171 DEBUG (ScheduledTrigger-587-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 708171 DEBUG (ScheduledTrigger-587-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 708171 DEBUG (ScheduledTrigger-587-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger firing
registered processor for nodes: [127.0.0.1:10002_solr] added at times
[4135299579857477], now=4135303216325577
[junit4] 2> 708191 DEBUG (ScheduledTrigger-587-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3
[junit4] 2> 708212 DEBUG (ScheduledTrigger-587-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3
[junit4] 2> 708230 DEBUG (ScheduledTrigger-587-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"eb108767f4a45T32ukelut8vuap8xks2467fk5w",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":4135299579857477,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[4135299579857477],
[junit4] 2> "nodeNames":["127.0.0.1:10002_solr"]}}
[junit4] 2> 708266 DEBUG (ScheduledTrigger-587-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers Pausing all triggers: [.auto_add_replicas,
node_added_trigger]
[junit4] 2> 708347 DEBUG (AutoscalingActionExecutor-588-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"eb108767f4a45T32ukelut8vuap8xks2467fk5w",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":4135299579857477,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[4135299579857477],
[junit4] 2> "_enqueue_time_":4135307974401677,
[junit4] 2> "nodeNames":["127.0.0.1:10002_solr"]}}
[junit4] 2> 708429 DEBUG (simCloudManagerPool-586-thread-5) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 708429 DEBUG (simCloudManagerPool-586-thread-5) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
5
[junit4] 2> 708430 DEBUG (simCloudManagerPool-586-thread-5) [ ]
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> 708430 DEBUG (simCloudManagerPool-586-thread-5) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger instantiated
with properties: {event=nodeAdded, waitFor=3, actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration$TestTriggerAction}],
enabled=true}
[junit4] 2> 708430 DEBUG (simCloudManagerPool-586-thread-5) [ ]
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> 708430 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 5
[junit4] 2> 708430 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVersion 5
[junit4] 2> 708451 DEBUG (AutoscalingActionExecutor-588-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Resuming trigger: .auto_add_replicas after 100ms
[junit4] 2> 708452 DEBUG (AutoscalingActionExecutor-588-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger after 100ms
[junit4] 2> 708452 DEBUG (AutoscalingActionExecutor-588-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -- processing took 5251 ms for event
id=eb108767f4a45T32ukelut8vuap8xks2467fk5w
[junit4] 2> 708484 INFO
(TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.SolrTestCaseJ4 ###Ending testNodeAddedTrigger
[junit4] 2> 708484 INFO
(TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase
[junit4] 2>
[junit4] 2> 708484 INFO
(TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase #############################################
[junit4] 2> 708484 INFO
(TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ############ FINAL CLUSTER STATS ############
[junit4] 2> 708484 INFO
(TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase #############################################
[junit4] 2>
[junit4] 2> 708484 INFO
(TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Live nodes: 3
[junit4] 2> 708484 INFO
(TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Empty nodes: 3
[junit4] 2> 708484 INFO
(TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Dead nodes: 0
[junit4] 2> 708485 INFO
(TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Collections: []
[junit4] 2> 708485 INFO
(TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Max replicas per node: 0
[junit4] 2> 708485 INFO
(TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Min replicas per node: 0
[junit4] 2> 708486 INFO
(TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Total replicas: 0
[junit4] 2> 708486 INFO
(TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ######### Final Solr op counts ##########
[junit4] 2> 708486 INFO
(TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## - autoscaling 3
[junit4] 2> 708487 INFO
(TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## - update 2
[junit4] 2> 708487 INFO
(TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ######### Autoscaling event counts
###########
[junit4] 2> 708521 INFO
(TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## * Trigger: node_added_trigger
[junit4] 2> 708522 INFO
(TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## - STARTED 1
[junit4] 2> 708522 INFO
(TEST-TestTriggerIntegration.testNodeAddedTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## - SUCCEEDED 1
[junit4] 2> 708574 DEBUG (ScheduledTrigger-587-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3
[junit4] 2> 708575 DEBUG (ScheduledTrigger-587-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 708575 DEBUG (ScheduledTrigger-587-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 708595 DEBUG (ScheduledTrigger-587-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3
[junit4] 2> 708595 DEBUG (ScheduledTrigger-587-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 708596 DEBUG (ScheduledTrigger-587-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 708630 DEBUG (ScheduledTrigger-587-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3
[junit4] 2> 708630 DEBUG (ScheduledTrigger-587-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 708630 DEBUG (ScheduledTrigger-587-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 708666 DEBUG (ScheduledTrigger-587-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3
[junit4] 2> 708666 DEBUG (ScheduledTrigger-587-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 708666 DEBUG (ScheduledTrigger-587-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 708670 INFO
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.SolrTestCaseJ4 ###Starting testTriggerThrottling
[junit4] 2> 708670 DEBUG
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 6
[junit4] 2> 708670 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 6
[junit4] 2> 708683 INFO
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and
clearing object cache...
[junit4] 2> 708684 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 708684 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 708684 DEBUG
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed
explicitly
[junit4] 2> 708719 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:165)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 708756 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 708757 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 7
[junit4] 2> 708757 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> 708758 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 7, lastZnodeVersion -1
[junit4] 2> 708758 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 7
[junit4] 2> 708778 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 708778 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 7, lastZnodeVersion 7
[junit4] 2> 708778 DEBUG (ScheduledTrigger-590-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 708838 DEBUG (ScheduledTrigger-590-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 708911 DEBUG (simCloudManagerPool-589-thread-1) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 708911 DEBUG (simCloudManagerPool-589-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
8
[junit4] 2> 708983 DEBUG (simCloudManagerPool-589-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10000_solr,
127.0.0.1:10002_solr]
[junit4] 2> 708984 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 8
[junit4] 2> 708984 DEBUG (ScheduledTrigger-590-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 709014 DEBUG (ScheduledTrigger-590-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 709028 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 709028 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 8, lastZnodeVersion 8
[junit4] 2> 709029 DEBUG (simCloudManagerPool-589-thread-2) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 709030 DEBUG (simCloudManagerPool-589-thread-2) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
9
[junit4] 2> 709030 DEBUG (simCloudManagerPool-589-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10000_solr,
127.0.0.1:10002_solr]
[junit4] 2> 709030 DEBUG (simCloudManagerPool-589-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:10000_solr,
127.0.0.1:10002_solr]
[junit4] 2> 709030 DEBUG (simCloudManagerPool-589-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 instantiated
with properties: {event=nodeAdded, waitFor=0, actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration$ThrottlingTesterAction}],
enabled=true}
[junit4] 2> 709030 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 9
[junit4] 2> 709031 INFO (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.s.TestTriggerIntegration TestTriggerAction init
[junit4] 2> 709121 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 9, lastZnodeVersion 9
[junit4] 2> 709166 DEBUG (ScheduledTrigger-590-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 709166 DEBUG (ScheduledTrigger-590-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 709166 DEBUG (ScheduledTrigger-590-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 709167 DEBUG (simCloudManagerPool-589-thread-3) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 709167 DEBUG (simCloudManagerPool-589-thread-3) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
10
[junit4] 2> 709167 DEBUG (simCloudManagerPool-589-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:10000_solr,
127.0.0.1:10002_solr]
[junit4] 2> 709167 DEBUG (simCloudManagerPool-589-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger2 instantiated
with properties: {event=nodeAdded, waitFor=0, actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration$ThrottlingTesterAction}],
enabled=true}
[junit4] 2> 709167 DEBUG (simCloudManagerPool-589-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:10000_solr,
127.0.0.1:10002_solr]
[junit4] 2> 709167 DEBUG (simCloudManagerPool-589-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 instantiated
with properties: {event=nodeAdded, waitFor=0, actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration$ThrottlingTesterAction}],
enabled=true}
[junit4] 2> 709167 DEBUG (simCloudManagerPool-589-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10000_solr,
127.0.0.1:10002_solr]
[junit4] 2> 709174 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 10
[junit4] 2> 709174 INFO (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.s.TestTriggerIntegration TestTriggerAction init
[junit4] 2> 709174 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 10, lastZnodeVersion 10
[junit4] 2> 709182 DEBUG (ScheduledTrigger-590-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2
[junit4] 2> 709182 DEBUG (ScheduledTrigger-590-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 709194 DEBUG (ScheduledTrigger-590-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3
[junit4] 2> 709231 DEBUG (ScheduledTrigger-590-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 709231 DEBUG (ScheduledTrigger-590-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 709231 DEBUG (ScheduledTrigger-590-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:10003_solr at time
4135356205823777
[junit4] 2> 709231 DEBUG (ScheduledTrigger-590-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 firing
registered processor for nodes: [127.0.0.1:10003_solr] added at times
[4135356205823777], now=4135356207661977
[junit4] 2> 709231 DEBUG (ScheduledTrigger-590-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"eb115a5aaf721T32ukelut8vuap8xks2467fk5z",
[junit4] 2> "source":"node_added_trigger1",
[junit4] 2> "eventTime":4135356205823777,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[4135356205823777],
[junit4] 2> "nodeNames":["127.0.0.1:10003_solr"]}}
[junit4] 2> 709233 DEBUG (ScheduledTrigger-590-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers Pausing all triggers: [.auto_add_replicas,
node_added_trigger2, node_added_trigger1]
[junit4] 2> 709292 DEBUG (AutoscalingActionExecutor-591-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"eb115a5aaf721T32ukelut8vuap8xks2467fk5z",
[junit4] 2> "source":"node_added_trigger1",
[junit4] 2> "eventTime":4135356205823777,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[4135356205823777],
[junit4] 2> "_enqueue_time_":4135356310240677,
[junit4] 2> "nodeNames":["127.0.0.1:10003_solr"]}}
[junit4] 2> 709292 INFO (AutoscalingActionExecutor-591-thread-1) [ ]
o.a.s.c.a.s.TestTriggerIntegration action executed from node_added_trigger1
[junit4] 2> 709314 DEBUG (AutoscalingActionExecutor-591-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Resuming trigger: .auto_add_replicas after 100ms
[junit4] 2> 709314 DEBUG (AutoscalingActionExecutor-591-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger2 after 100ms
[junit4] 2> 709314 DEBUG (AutoscalingActionExecutor-591-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger1 after 100ms
[junit4] 2> 709314 DEBUG (AutoscalingActionExecutor-591-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -- processing took 1134 ms for event
id=eb115a5aaf721T32ukelut8vuap8xks2467fk5z
[junit4] 2> 709417 DEBUG (ScheduledTrigger-590-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3
[junit4] 2> 709418 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 709418 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 709451 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3
[junit4] 2> 709451 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 709451 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 709451 DEBUG (ScheduledTrigger-590-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2
[junit4] 2> 709451 DEBUG (ScheduledTrigger-590-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 709451 DEBUG (ScheduledTrigger-590-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:10003_solr at time
4135367208843827
[junit4] 2> 709451 DEBUG (ScheduledTrigger-590-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger2 firing
registered processor for nodes: [127.0.0.1:10003_solr] added at times
[4135367208843827], now=4135367211063127
[junit4] 2> 709451 DEBUG (ScheduledTrigger-590-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"eb118357fba33T32ukelut8vuap8xks2467fk62",
[junit4] 2> "source":"node_added_trigger2",
[junit4] 2> "eventTime":4135367208843827,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[4135367208843827],
[junit4] 2> "nodeNames":["127.0.0.1:10003_solr"]}}
[junit4] 2> 709451 DEBUG (ScheduledTrigger-590-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Pausing all triggers: [.auto_add_replicas,
node_added_trigger2, node_added_trigger1]
[junit4] 2> 709466 DEBUG (AutoscalingActionExecutor-591-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"eb118357fba33T32ukelut8vuap8xks2467fk62",
[junit4] 2> "source":"node_added_trigger2",
[junit4] 2> "eventTime":4135367208843827,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[4135367208843827],
[junit4] 2> "_enqueue_time_":4135367226100927,
[junit4] 2> "nodeNames":["127.0.0.1:10003_solr"]}}
[junit4] 2> 709467 INFO (AutoscalingActionExecutor-591-thread-1) [ ]
o.a.s.c.a.s.TestTriggerIntegration last action at 4135359252124077 time =
4135367990862627
[junit4] 2> 709467 INFO (AutoscalingActionExecutor-591-thread-1) [ ]
o.a.s.c.a.s.TestTriggerIntegration action executed from node_added_trigger2
[junit4] 2> 709467 DEBUG (AutoscalingActionExecutor-591-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Resuming trigger: .auto_add_replicas after 100ms
[junit4] 2> 709467 DEBUG (AutoscalingActionExecutor-591-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger2 after 100ms
[junit4] 2> 709467 DEBUG (AutoscalingActionExecutor-591-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger1 after 100ms
[junit4] 2> 709467 DEBUG (AutoscalingActionExecutor-591-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -- processing took 46 ms for event
id=eb118357fba33T32ukelut8vuap8xks2467fk62
[junit4] 2> 709487 DEBUG (simCloudManagerPool-589-thread-8) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 709487 DEBUG (simCloudManagerPool-589-thread-8) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
11
[junit4] 2> 709487 DEBUG (simCloudManagerPool-589-thread-8) [ ]
o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:10000_solr,
127.0.0.1:10002_solr, 127.0.0.1:10003_solr]
[junit4] 2> 709487 DEBUG (simCloudManagerPool-589-thread-8) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger2 instantiated
with properties: {event=nodeAdded, waitFor=0, actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration$ThrottlingTesterAction}],
enabled=true}
[junit4] 2> 709488 DEBUG (simCloudManagerPool-589-thread-8) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10000_solr,
127.0.0.1:10002_solr, 127.0.0.1:10003_solr]
[junit4] 2> 709488 DEBUG (simCloudManagerPool-589-thread-8) [ ]
o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:10000_solr,
127.0.0.1:10002_solr, 127.0.0.1:10003_solr]
[junit4] 2> 709488 DEBUG (simCloudManagerPool-589-thread-8) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 instantiated
with properties: {event=nodeAdded, waitFor=0, actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration$ThrottlingTesterAction}],
enabled=true}
[junit4] 2> 709488 DEBUG (simCloudManagerPool-589-thread-8) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10000_solr,
127.0.0.1:10002_solr, 127.0.0.1:10003_solr]
[junit4] 2> 709488 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 11
[junit4] 2> 709488 INFO (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.s.TestTriggerIntegration TestTriggerAction init
[junit4] 2> 709488 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 11, lastZnodeVersion 11
[junit4] 2> 709488 DEBUG (ScheduledTrigger-590-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger1 with
currently live nodes: 3
[junit4] 2> 709489 DEBUG (simCloudManagerPool-589-thread-9) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 709490 DEBUG (simCloudManagerPool-589-thread-9) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
12
[junit4] 2> 709490 DEBUG (simCloudManagerPool-589-thread-9) [ ]
o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:10000_solr,
127.0.0.1:10002_solr, 127.0.0.1:10003_solr]
[junit4] 2> 709490 DEBUG (simCloudManagerPool-589-thread-9) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger2 instantiated
with properties: {event=nodeAdded, waitFor=0, actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration$ThrottlingTesterAction}],
enabled=true}
[junit4] 2> 709490 DEBUG (simCloudManagerPool-589-thread-9) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10000_solr,
127.0.0.1:10002_solr, 127.0.0.1:10003_solr]
[junit4] 2> 709490 DEBUG (simCloudManagerPool-589-thread-9) [ ]
o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:10000_solr,
127.0.0.1:10002_solr, 127.0.0.1:10003_solr]
[junit4] 2> 709490 DEBUG (simCloudManagerPool-589-thread-9) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 instantiated
with properties: {event=nodeAdded, waitFor=0, actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration$ThrottlingTesterAction}],
enabled=true}
[junit4] 2> 709490 DEBUG (simCloudManagerPool-589-thread-9) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10000_solr,
127.0.0.1:10002_solr, 127.0.0.1:10003_solr]
[junit4] 2> 709490 DEBUG (simCloudManagerPool-589-thread-9) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10000_solr,
127.0.0.1:10002_solr, 127.0.0.1:10003_solr]
[junit4] 2> 709490 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 12
[junit4] 2> 709503 INFO (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.s.TestTriggerIntegration TestTriggerAction init
[junit4] 2> 709504 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger2 with
currently live nodes: 2
[junit4] 2> 709504 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Tracking lost node: 127.0.0.1:10003_solr
[junit4] 2> 709504 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger NodeLostTrigger firing registered processor for lost
nodes: [127.0.0.1:10003_solr]
[junit4] 2> 709504 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"eb118d354cc0dT32ukelut8vuap8xks2467fk65",
[junit4] 2> "source":"node_lost_trigger2",
[junit4] 2> "eventTime":4135369856830477,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[4135369856830477],
[junit4] 2> "nodeNames":["127.0.0.1:10003_solr"]}}
[junit4] 2> 709505 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers Pausing all triggers: [node_lost_trigger2,
.auto_add_replicas, node_added_trigger2, node_lost_trigger1,
node_added_trigger1]
[junit4] 2> 709505 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 12, lastZnodeVersion 12
[junit4] 2> 709506 DEBUG (AutoscalingActionExecutor-591-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"eb118d354cc0dT32ukelut8vuap8xks2467fk65",
[junit4] 2> "source":"node_lost_trigger2",
[junit4] 2> "eventTime":4135369856830477,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[4135369856830477],
[junit4] 2> "_enqueue_time_":4135369888710677,
[junit4] 2> "nodeNames":["127.0.0.1:10003_solr"]}}
[junit4] 2> 709506 INFO (AutoscalingActionExecutor-591-thread-1) [ ]
o.a.s.c.a.s.TestTriggerIntegration action executed from node_lost_trigger2
[junit4] 2> 709523 DEBUG (AutoscalingActionExecutor-591-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Resuming trigger: node_lost_trigger2 after 100ms
[junit4] 2> 709559 DEBUG (AutoscalingActionExecutor-591-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Resuming trigger: .auto_add_replicas after 100ms
[junit4] 2> 709559 DEBUG (AutoscalingActionExecutor-591-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger2 after 100ms
[junit4] 2> 709559 DEBUG (AutoscalingActionExecutor-591-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Resuming trigger: node_lost_trigger1 after 100ms
[junit4] 2> 709559 DEBUG (AutoscalingActionExecutor-591-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger1 after 100ms
[junit4] 2> 709559 DEBUG (AutoscalingActionExecutor-591-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -- processing took 2695 ms for event
id=eb118d354cc0dT32ukelut8vuap8xks2467fk65
[junit4] 2> 709669 DEBUG (ScheduledTrigger-590-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 709669 DEBUG (ScheduledTrigger-590-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Tracking lost node: 127.0.0.1:10003_solr
[junit4] 2> 709669 DEBUG (ScheduledTrigger-590-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2
[junit4] 2> 709669 DEBUG (ScheduledTrigger-590-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 709671 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 709671 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 709685 DEBUG (ScheduledTrigger-590-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger2 with
currently live nodes: 2
[junit4] 2> 709687 DEBUG (ScheduledTrigger-590-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger1 with
currently live nodes: 2
[junit4] 2> 709687 DEBUG (ScheduledTrigger-590-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Tracking lost node: 127.0.0.1:10003_solr
[junit4] 2> 709687 DEBUG (ScheduledTrigger-590-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger NodeLostTrigger firing registered processor for lost
nodes: [127.0.0.1:10003_solr]
[junit4] 2> 709687 DEBUG (ScheduledTrigger-590-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"eb11af5cbcfe1T32ukelut8vuap8xks2467fk68",
[junit4] 2> "source":"node_lost_trigger1",
[junit4] 2> "eventTime":4135379024990177,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[4135379024990177],
[junit4] 2> "nodeNames":["127.0.0.1:10003_solr"]}}
[junit4] 2> 709688 DEBUG (ScheduledTrigger-590-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Pausing all triggers: [node_lost_trigger2,
.auto_add_replicas, node_added_trigger2, node_lost_trigger1,
node_added_trigger1]
[junit4] 2> 709743 DEBUG (AutoscalingActionExecutor-591-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"eb11af5cbcfe1T32ukelut8vuap8xks2467fk68",
[junit4] 2> "source":"node_lost_trigger1",
[junit4] 2> "eventTime":4135379024990177,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[4135379024990177],
[junit4] 2> "_enqueue_time_":4135379041502677,
[junit4] 2> "nodeNames":["127.0.0.1:10003_solr"]}}
[junit4] 2> 709743 INFO (AutoscalingActionExecutor-591-thread-1) [ ]
o.a.s.c.a.s.TestTriggerIntegration last action at 4135369945194877 time =
4135381823389977
[junit4] 2> 709743 INFO (AutoscalingActionExecutor-591-thread-1) [ ]
o.a.s.c.a.s.TestTriggerIntegration action executed from node_lost_trigger1
[junit4] 2> 709745 INFO
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.SolrTestCaseJ4 ###Ending testTriggerThrottling
[junit4] 2> 709745 INFO
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase
[junit4] 2>
[junit4] 2> 709745 INFO
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase
#############################################
[junit4] 2> 709745 INFO
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ############ FINAL CLUSTER STATS
############
[junit4] 2> 709746 INFO
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase
#############################################
[junit4] 2>
[junit4] 2> 709746 INFO
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Live nodes: 2
[junit4] 2> 709746 INFO
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Empty nodes: 2
[junit4] 2> 709746 INFO
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Dead nodes: 1
[junit4] 2> 709746 INFO
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## 127.0.0.1:10003_solr
[junit4] 2> 709746 INFO
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Collections: []
[junit4] 2> 709746 INFO
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Max replicas per node: 0
[junit4] 2> 709746 INFO
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Min replicas per node: 0
[junit4] 2> 709746 INFO
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Total replicas: 0
[junit4] 2> 709746 INFO
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ######### Final Solr op counts ##########
[junit4] 2> 709746 INFO
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## - autoscaling 5
[junit4] 2> 709746 INFO
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## - update 7
[junit4] 2> 709746 INFO
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ######### Autoscaling event counts
###########
[junit4] 2> 709746 INFO
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## * Trigger: node_added_trigger1
[junit4] 2> 709746 INFO
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## - STARTED 1
[junit4] 2> 709746 INFO
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## - SUCCEEDED 1
[junit4] 2> 709772 DEBUG (AutoscalingActionExecutor-591-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Resuming trigger: node_lost_trigger2 after 100ms
[junit4] 2> 709779 DEBUG (AutoscalingActionExecutor-591-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Resuming trigger: .auto_add_replicas after 100ms
[junit4] 2> 709779 DEBUG (AutoscalingActionExecutor-591-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger2 after 100ms
[junit4] 2> 709779 DEBUG (AutoscalingActionExecutor-591-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Resuming trigger: node_lost_trigger1 after 100ms
[junit4] 2> 709779 DEBUG (AutoscalingActionExecutor-591-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger1 after 100ms
[junit4] 2> 709779 DEBUG (AutoscalingActionExecutor-591-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -- processing took 1803 ms for event
id=eb11af5cbcfe1T32ukelut8vuap8xks2467fk68
[junit4] 2> 709779 INFO
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## * Trigger: node_added_trigger2
[junit4] 2> 709779 INFO
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## - STARTED 1
[junit4] 2> 709779 INFO
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## - SUCCEEDED 1
[junit4] 2> 709779 INFO
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## * Trigger: node_lost_trigger1
[junit4] 2> 709779 INFO
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## - STARTED 1
[junit4] 2> 709779 INFO
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## * Trigger: node_lost_trigger2
[junit4] 2> 709779 INFO
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## - STARTED 1
[junit4] 2> 709779 INFO
(TEST-TestTriggerIntegration.testTriggerThrottling-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## - SUCCEEDED 1
[junit4] 2> 709967 DEBUG (ScheduledTrigger-590-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger2 with
currently live nodes: 2
[junit4] 2> 709967 DEBUG (ScheduledTrigger-590-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 709967 DEBUG (ScheduledTrigger-590-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2
[junit4] 2> 709967 DEBUG (ScheduledTrigger-590-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 709967 DEBUG (ScheduledTrigger-590-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger1 with
currently live nodes: 2
[junit4] 2> 709967 DEBUG (ScheduledTrigger-590-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 709967 DEBUG (ScheduledTrigger-590-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 710022 DEBUG (ScheduledTrigger-590-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger2 with
currently live nodes: 2
[junit4] 2> 710022 DEBUG (ScheduledTrigger-590-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 710022 DEBUG (ScheduledTrigger-590-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2
[junit4] 2> 710022 DEBUG (ScheduledTrigger-590-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 710022 DEBUG (ScheduledTrigger-590-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger1 with
currently live nodes: 2
[junit4] 2> 710022 DEBUG (ScheduledTrigger-590-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 710022 DEBUG (ScheduledTrigger-590-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 710095 DEBUG (ScheduledTrigger-590-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 710095 DEBUG (ScheduledTrigger-590-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2
[junit4] 2> 710095 DEBUG (ScheduledTrigger-590-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 710098 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger1 with
currently live nodes: 2
[junit4] 2> 710098 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 710098 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 710098 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger2 with
currently live nodes: 2
[junit4] 2> 710177 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 710177 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger1 with
currently live nodes: 2
[junit4] 2> 710177 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 710177 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 710177 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger2 with
currently live nodes: 2
[junit4] 2> 710177 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2
[junit4] 2> 710177 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 710199 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 710199 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger1 with
currently live nodes: 2
[junit4] 2> 710199 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 710199 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 710199 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger2 with
currently live nodes: 2
[junit4] 2> 710200 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2
[junit4] 2> 710200 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 710233 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 710233 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger1 with
currently live nodes: 2
[junit4] 2> 710233 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 710233 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 710233 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger2 with
currently live nodes: 2
[junit4] 2> 710233 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2
[junit4] 2> 710233 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 710323 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 710323 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger1 with
currently live nodes: 2
[junit4] 2> 710323 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 710323 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 710323 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger2 with
currently live nodes: 2
[junit4] 2> 710323 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2
[junit4] 2> 710323 DEBUG (ScheduledTrigger-590-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 710343 DEBUG (ScheduledTrigger-590-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 710343 DEBUG (ScheduledTrigger-590-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger1 with
currently live nodes: 2
[junit4] 2> 710343 DEBUG (ScheduledTrigger-590-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 710343 DEBUG (ScheduledTrigger-590-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 710343 DEBUG (ScheduledTrigger-590-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger2 with
currently live nodes: 2
[junit4] 2> 710343 DEBUG (ScheduledTrigger-590-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2
[junit4] 2> 710343 DEBUG (ScheduledTrigger-590-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 710418 INFO
(TEST-TestTriggerIntegration.testEventQueue-seed#[B5246C2488C2024A]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testEventQueue
[junit4] 2> 710418 DEBUG
(TEST-TestTriggerIntegration.testEventQueue-seed#[B5246C2488C2024A]) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
13
[junit4] 2> 710418 DEBUG
(TEST-TestTriggerIntegration.testEventQueue-seed#[B5246C2488C2024A]) [ ]
o.a.s.c.a.s.SimNodeStateProvider - removing dead node values:
127.0.0.1:10003_solr
[junit4] 2> 710418 INFO
(TEST-TestTriggerIntegration.testEventQueue-seed#[B5246C2488C2024A]) [ ]
o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and clearing
object cache...
[junit4] 2> 710419 DEBUG
(TEST-TestTriggerIntegration.testEventQueue-seed#[B5246C2488C2024A]) [ ]
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 710419 WARN (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are
closed, exiting.
[junit4] 2> 710428 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 710428 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 14
[junit4] 2> 710494 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> 710494 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 14, lastZnodeVersion -1
[junit4] 2> 710494 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 14
[junit4] 2> 710550 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 710550 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 14, lastZnodeVersion 14
[junit4] 2> 710551 DEBUG (ScheduledTrigger-593-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 710773 DEBUG (ScheduledTrigger-593-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 710802 DEBUG (ScheduledTrigger-593-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 710803 DEBUG (simCloudManagerPool-592-thread-1) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 710803 DEBUG (simCloudManagerPool-592-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
15
[junit4] 2> 710841 DEBUG (simCloudManagerPool-592-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10000_solr,
127.0.0.1:10002_solr]
[junit4] 2> 710841 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 15
[junit4] 2> 710842 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 710842 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 15, lastZnodeVersion 15
[junit4] 2> 710856 DEBUG (simCloudManagerPool-592-thread-2) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 710856 DEBUG (simCloudManagerPool-592-thread-2) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
16
[junit4] 2> 710857 DEBUG (simCloudManagerPool-592-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10000_solr,
127.0.0.1:10002_solr]
[junit4] 2> 710857 INFO (simCloudManagerPool-592-thread-2) [ ]
o.a.s.c.a.s.TestTriggerIntegration TestEventQueueAction instantiated
[junit4] 2> 710857 DEBUG (simCloudManagerPool-592-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:10000_solr,
127.0.0.1:10002_solr]
[junit4] 2> 710857 DEBUG (simCloudManagerPool-592-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 instantiated
with properties: {event=nodeAdded, waitFor=1, actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration$TestEventQueueAction}],
enabled=true}
[junit4] 2> 710857 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 16
[junit4] 2> 710857 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.s.TestTriggerIntegration TestTriggerAction init
[junit4] 2> 710858 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 16, lastZnodeVersion 16
[junit4] 2> 710863 DEBUG (ScheduledTrigger-593-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3
[junit4] 2> 710863 DEBUG (ScheduledTrigger-593-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 710863 DEBUG (ScheduledTrigger-593-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 710863 DEBUG (ScheduledTrigger-593-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:10004_solr at time
4135437829412627
[junit4] 2> 710886 DEBUG (ScheduledTrigger-593-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3
[junit4] 2> 710886 DEBUG (ScheduledTrigger-593-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 710886 DEBUG (ScheduledTrigger-593-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 710886 DEBUG (ScheduledTrigger-593-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 firing
registered processor for nodes: [127.0.0.1:10004_solr] added at times
[4135437829412627], now=4135438950586477
[junit4] 2> 710886 DEBUG (ScheduledTrigger-593-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"eb128a6d01713T32ukelut8vuap8xks2467fk6b",
[junit4] 2> "source":"node_added_trigger1",
[junit4] 2> "eventTime":4135437829412627,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[4135437829412627],
[junit4] 2> "nodeNames":["127.0.0.1:10004_solr"]}}
[junit4] 2> 710973 DEBUG (ScheduledTrigger-593-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers Pausing all triggers: [.auto_add_replicas,
node_added_trigger1]
[junit4] 2> 711009 DEBUG (AutoscalingActionExecutor-594-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"eb128a6d01713T32ukelut8vuap8xks2467fk6b",
[junit4] 2> "source":"node_added_trigger1",
[junit4] 2> "eventTime":4135437829412627,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[4135437829412627],
[junit4] 2> "_enqueue_time_":4135443290018577,
[junit4] 2> "nodeNames":["127.0.0.1:10004_solr"]}}
[junit4] 2> 711010 INFO (AutoscalingActionExecutor-594-thread-1) [ ]
o.a.s.c.a.s.TestTriggerIntegration -- event: {
[junit4] 2> "id":"eb128a6d01713T32ukelut8vuap8xks2467fk6b",
[junit4] 2> "source":"node_added_trigger1",
[junit4] 2> "eventTime":4135437829412627,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[4135437829412627],
[junit4] 2> "_enqueue_time_":4135443290018577,
[junit4] 2> "nodeNames":["127.0.0.1:10004_solr"]}}
[junit4] 2> 711010 INFO
(TEST-TestTriggerIntegration.testEventQueue-seed#[B5246C2488C2024A]) [ ]
o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and clearing
object cache...
[junit4] 2> 711010 WARN (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are
closed, exiting.
[junit4] 2> 711023 DEBUG
(TEST-TestTriggerIntegration.testEventQueue-seed#[B5246C2488C2024A]) [ ]
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 711024 WARN (AutoscalingActionExecutor-594-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Exception executing actions
[junit4] 2> org.apache.lucene.store.AlreadyClosedException:
ScheduledTrigger node_added_trigger1 has been closed.
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ScheduledTriggers$TriggerWrapper.dequeue(ScheduledTriggers.java:533)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:318)
[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> 711024 DEBUG (AutoscalingActionExecutor-594-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -- processing took 748 ms for event
id=eb128a6d01713T32ukelut8vuap8xks2467fk6b
[junit4] 2> 711042 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 16
[junit4] 2> 711042 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> 711042 INFO (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.s.TestTriggerIntegration TestEventQueueAction instantiated
[junit4] 2> 711042 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:10004_solr,
127.0.0.1:10002_solr]
[junit4] 2> 711042 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 instantiated
with properties: {event=nodeAdded, waitFor=1, actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration$TestEventQueueAction}],
enabled=true}
[junit4] 2> 711042 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 16, lastZnodeVersion -1
[junit4] 2> 711042 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 16
[junit4] 2> 711042 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeLostTrigger Adding lost node from marker path:
127.0.0.1:10000_solr
[junit4] 2> 711106 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.s.TestTriggerIntegration TestTriggerAction init
[junit4] 2> 711107 DEBUG (ScheduledTrigger-596-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 711107 DEBUG (ScheduledTrigger-596-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Tracking lost node: 127.0.0.1:10000_solr
[junit4] 2> 711108 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 16, lastZnodeVersion 16
[junit4] 2> 711128 DEBUG (ScheduledTrigger-596-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 711254 DEBUG (ScheduledTrigger-596-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"eb128a6d01713T32ukelut8vuap8xks2467fk6b",
[junit4] 2> "source":"node_added_trigger1",
[junit4] 2> "eventTime":4135437829412627,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[4135437829412627],
[junit4] 2> "_dequeue_time_":4135450166797427,
[junit4] 2> "_enqueue_time_":4135443290018577,
[junit4] 2> "nodeNames":["127.0.0.1:10004_solr"],
[junit4] 2> "replaying":true}}
[junit4] 2> 711325 DEBUG (ScheduledTrigger-596-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 711325 DEBUG (ScheduledTrigger-596-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers Pausing all triggers: [.auto_add_replicas,
node_added_trigger1]
[junit4] 2> 711351 DEBUG (ScheduledTrigger-596-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 711351 DEBUG (ScheduledTrigger-596-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 711351 DEBUG (AutoscalingActionExecutor-597-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"eb128a6d01713T32ukelut8vuap8xks2467fk6b",
[junit4] 2> "source":"node_added_trigger1",
[junit4] 2> "eventTime":4135437829412627,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[4135437829412627],
[junit4] 2> "_dequeue_time_":4135450166797427,
[junit4] 2> "_enqueue_time_":4135443290018577,
[junit4] 2> "nodeNames":["127.0.0.1:10004_solr"],
[junit4] 2> "replaying":true}}
[junit4] 2> 711351 INFO (AutoscalingActionExecutor-597-thread-1) [ ]
o.a.s.c.a.s.TestTriggerIntegration -- event: {
[junit4] 2> "id":"eb128a6d01713T32ukelut8vuap8xks2467fk6b",
[junit4] 2> "source":"node_added_trigger1",
[junit4] 2> "eventTime":4135437829412627,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[4135437829412627],
[junit4] 2> "_dequeue_time_":4135450166797427,
[junit4] 2> "_enqueue_time_":4135443290018577,
[junit4] 2> "nodeNames":["127.0.0.1:10004_solr"],
[junit4] 2> "replaying":true}}
[junit4] 2> 711379 INFO
(TEST-TestTriggerIntegration.testEventQueue-seed#[B5246C2488C2024A]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testEventQueue
[junit4] 2> 711379 INFO
(TEST-TestTriggerIntegration.testEventQueue-seed#[B5246C2488C2024A]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase
[junit4] 2>
[junit4] 2> 711379 INFO
(TEST-TestTriggerIntegration.testEventQueue-seed#[B5246C2488C2024A]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase #############################################
[junit4] 2> 711379 INFO
(TEST-TestTriggerIntegration.testEventQueue-seed#[B5246C2488C2024A]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ############ FINAL CLUSTER STATS ############
[junit4] 2> 711379 INFO
(TEST-TestTriggerIntegration.testEventQueue-seed#[B5246C2488C2024A]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase #############################################
[junit4] 2>
[junit4] 2> 711379 INFO
(TEST-TestTriggerIntegration.testEventQueue-seed#[B5246C2488C2024A]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## Live nodes: 2
[junit4] 2> 711379 INFO
(TEST-TestTriggerIntegration.testEventQueue-seed#[B5246C2488C2024A]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## Empty nodes: 2
[junit4] 2> 711379 INFO
(TEST-TestTriggerIntegration.testEventQueue-seed#[B5246C2488C2024A]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## Dead nodes: 1
[junit4] 2> 711379 INFO
(TEST-TestTriggerIntegration.testEventQueue-seed#[B5246C2488C2024A]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## 127.0.0.1:10000_solr
[junit4] 2> 711379 INFO
(TEST-TestTriggerIntegration.testEventQueue-seed#[B5246C2488C2024A]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## Collections: []
[junit4] 2> 711379 INFO
(TEST-TestTriggerIntegration.testEventQueue-seed#[B5246C2488C2024A]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## Max replicas per node: 0
[junit4] 2> 711379 INFO
(TEST-TestTriggerIntegration.testEventQueue-seed#[B5246C2488C2024A]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## Min replicas per node: 0
[junit4] 2> 711379 INFO
(TEST-TestTriggerIntegration.testEventQueue-seed#[B5246C2488C2024A]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## Total replicas: 0
[junit4] 2> 711379 INFO
(TEST-TestTriggerIntegration.testEventQueue-seed#[B5246C2488C2024A]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ######### Final Solr op counts ##########
[junit4] 2> 711379 INFO
(TEST-TestTriggerIntegration.testEventQueue-seed#[B5246C2488C2024A]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## - autoscaling 2
[junit4] 2> 711380 INFO
(TEST-TestTriggerIntegration.testEventQueue-seed#[B5246C2488C2024A]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## - update 2
[junit4] 2> 711380 INFO
(TEST-TestTriggerIntegration.testEventQueue-seed#[B5246C2488C2024A]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ######### Autoscaling event counts ###########
[junit4] 2> 711380 INFO
(TEST-TestTriggerIntegration.testEventQueue-seed#[B5246C2488C2024A]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## * Trigger: node_added_trigger1
[junit4] 2> 711380 INFO
(TEST-TestTriggerIntegration.testEventQueue-seed#[B5246C2488C2024A]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## - STARTED 2
[junit4] IGNOR/A 0.00s J1 | TestTriggerIntegration.testEventFromRestoredState
[junit4] > Assumption #1: 'badapple' test group is disabled
(@BadApple(bugUrl=https://issues.apache.org/jira/browse/SOLR-12028))
[junit4] IGNOR/A 0.00s J1 | TestTriggerIntegration.testCooldown
[junit4] > Assumption #1: 'badapple' test group is disabled
(@BadApple(bugUrl=https://issues.apache.org/jira/browse/SOLR-12028))
[junit4] 2> 711425 DEBUG (AutoscalingActionExecutor-597-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Resuming trigger: .auto_add_replicas after 100ms
[junit4] 2> 711425 DEBUG (AutoscalingActionExecutor-597-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger1 after 100ms
[junit4] 2> 711425 DEBUG (AutoscalingActionExecutor-597-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -- processing took 3671 ms for event
id=eb128a6d01713T32ukelut8vuap8xks2467fk6b
[junit4] 2> 711938 DEBUG (ScheduledTrigger-596-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 712025 DEBUG (ScheduledTrigger-596-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 712025 DEBUG (ScheduledTrigger-596-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 712062 INFO
(TEST-TestTriggerIntegration.testNodeAddedTriggerRestoreState-seed#[B5246C2488C2024A])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testNodeAddedTriggerRestoreState
[junit4] 2> 712062 DEBUG
(TEST-TestTriggerIntegration.testNodeAddedTriggerRestoreState-seed#[B5246C2488C2024A])
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 17
[junit4] 2> 712063 DEBUG
(TEST-TestTriggerIntegration.testNodeAddedTriggerRestoreState-seed#[B5246C2488C2024A])
[ ] o.a.s.c.a.s.SimNodeStateProvider - removing dead node values:
127.0.0.1:10000_solr
[junit4] 2> 712063 INFO
(TEST-TestTriggerIntegration.testNodeAddedTriggerRestoreState-seed#[B5246C2488C2024A])
[ ] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and
clearing object cache...
[junit4] 2> 712063 WARN (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are
closed, exiting.
[junit4] 2> 712128 DEBUG (ScheduledTrigger-596-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 712129 DEBUG (ScheduledTrigger-596-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 712129 DEBUG (ScheduledTrigger-596-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 712170 DEBUG
(TEST-TestTriggerIntegration.testNodeAddedTriggerRestoreState-seed#[B5246C2488C2024A])
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed
explicitly
[junit4] 2> 712178 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 712178 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 18
[junit4] 2> 712179 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> 712179 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 18, lastZnodeVersion -1
[junit4] 2> 712179 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 18
[junit4] 2> 712230 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 712230 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 18, lastZnodeVersion 18
[junit4] 2> 712232 DEBUG (ScheduledTrigger-599-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 712278 DEBUG (ScheduledTrigger-599-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 712303 DEBUG (simCloudManagerPool-598-thread-1) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 712303 DEBUG (simCloudManagerPool-598-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
19
[junit4] 2> 712315 DEBUG (ScheduledTrigger-599-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 712413 DEBUG (simCloudManagerPool-598-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10004_solr,
127.0.0.1:10002_solr]
[junit4] 2> 712433 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 19
[junit4] 2> 712433 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 712433 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 19, lastZnodeVersion 19
[junit4] 2> 712488 DEBUG (ScheduledTrigger-599-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 712489 DEBUG (simCloudManagerPool-598-thread-2) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 712522 DEBUG (ScheduledTrigger-599-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 712522 DEBUG (simCloudManagerPool-598-thread-2) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
20
[junit4] 2> 712523 DEBUG (simCloudManagerPool-598-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:10004_solr,
127.0.0.1:10002_solr]
[junit4] 2> 712523 DEBUG (simCloudManagerPool-598-thread-2) [ ]
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> 712523 DEBUG (simCloudManagerPool-598-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10004_solr,
127.0.0.1:10002_solr]
[junit4] 2> 712523 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 20
[junit4] 2> 712523 INFO (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.s.TestTriggerIntegration TestTriggerAction init
[junit4] 2> 712523 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 20, lastZnodeVersion 20
[junit4] 2> 712524 DEBUG (ScheduledTrigger-599-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_restore_trigger
[junit4] 2> 712524 DEBUG (ScheduledTrigger-599-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 712551 DEBUG (ScheduledTrigger-599-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3
[junit4] 2> 712551 DEBUG (ScheduledTrigger-599-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_restore_trigger
[junit4] 2> 712551 DEBUG (ScheduledTrigger-599-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 712551 DEBUG (ScheduledTrigger-599-t
[...truncated too long message...]
ulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread
OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 713063 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 713064 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 23
[junit4] 2> 713064 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10005_solr,
127.0.0.1:10002_solr]
[junit4] 2> 713064 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 23, lastZnodeVersion -1
[junit4] 2> 713064 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 23
[junit4] 2> 713066 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 713066 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 23, lastZnodeVersion 23
[junit4] 2> 713066 DEBUG (ScheduledTrigger-602-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 713175 DEBUG (ScheduledTrigger-602-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 713194 DEBUG (simCloudManagerPool-601-thread-1) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 713194 DEBUG (simCloudManagerPool-601-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
24
[junit4] 2> 713248 DEBUG (ScheduledTrigger-602-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 713248 DEBUG (simCloudManagerPool-601-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10005_solr,
127.0.0.1:10002_solr]
[junit4] 2> 713249 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 24
[junit4] 2> 713249 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 713249 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 24, lastZnodeVersion 24
[junit4] 2> 713259 DEBUG (simCloudManagerPool-601-thread-2) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 713259 DEBUG (simCloudManagerPool-601-thread-2) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
25
[junit4] 2> 713260 DEBUG (simCloudManagerPool-601-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10005_solr,
127.0.0.1:10002_solr]
[junit4] 2> 713260 DEBUG (simCloudManagerPool-601-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10005_solr,
127.0.0.1:10002_solr]
[junit4] 2> 713376 INFO
(TEST-TestTriggerIntegration.testNodeLostTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.SolrTestCaseJ4 ###Ending testNodeLostTrigger
[junit4] 2> 713376 INFO
(TEST-TestTriggerIntegration.testNodeLostTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase
[junit4] 2>
[junit4] 2> 713376 INFO
(TEST-TestTriggerIntegration.testNodeLostTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase #############################################
[junit4] 2> 713376 INFO
(TEST-TestTriggerIntegration.testNodeLostTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ############ FINAL CLUSTER STATS ############
[junit4] 2> 713376 INFO
(TEST-TestTriggerIntegration.testNodeLostTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase #############################################
[junit4] 2>
[junit4] 2> 713376 INFO
(TEST-TestTriggerIntegration.testNodeLostTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Live nodes: 2
[junit4] 2> 713376 INFO
(TEST-TestTriggerIntegration.testNodeLostTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Empty nodes: 2
[junit4] 2> 713376 INFO
(TEST-TestTriggerIntegration.testNodeLostTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Dead nodes: 0
[junit4] 2> 713376 INFO
(TEST-TestTriggerIntegration.testNodeLostTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Collections: []
[junit4] 2> 713376 INFO
(TEST-TestTriggerIntegration.testNodeLostTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Max replicas per node: 0
[junit4] 2> 713376 INFO
(TEST-TestTriggerIntegration.testNodeLostTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Min replicas per node: 0
[junit4] 2> 713376 INFO
(TEST-TestTriggerIntegration.testNodeLostTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Total replicas: 0
[junit4] 2> 713376 INFO
(TEST-TestTriggerIntegration.testNodeLostTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ######### Final Solr op counts ##########
[junit4] 2> 713376 INFO
(TEST-TestTriggerIntegration.testNodeLostTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## - autoscaling 2
[junit4] 2> 713376 INFO
(TEST-TestTriggerIntegration.testNodeLostTrigger-seed#[B5246C2488C2024A]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ######### Autoscaling event counts
###########
[junit4] 2> 713377 WARN (ScheduledTrigger-602-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger ran but was already closed
[junit4] 2> 713377 ERROR (ScheduledTrigger-602-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers Unexpected exception from trigger:
.scheduled_maintenance
[junit4] 2> java.lang.RuntimeException: Trigger has been closed
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ScheduledTrigger.run(ScheduledTrigger.java:146)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ScheduledTriggers$TriggerWrapper.run(ScheduledTriggers.java:576)
[junit4] 2> at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[junit4] 2> at
java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
[junit4] 2> at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
[junit4] 2> at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
[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> 713377 DEBUG (ScheduledTrigger-602-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=TestTriggerIntegration -Dtests.method=testNodeLostTrigger
-Dtests.seed=B5246C2488C2024A -Dtests.multiplier=2 -Dtests.slow=true
-Dtests.locale=ro-RO -Dtests.timezone=America/Santo_Domingo
-Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 0.72s J1 | TestTriggerIntegration.testNodeLostTrigger <<<
[junit4] > Throwable #1: java.lang.AssertionError: The TriggerAction
should have been created by now
[junit4] > at
__randomizedtesting.SeedInfo.seed([B5246C2488C2024A:B1DAC536B20880D0]:0)
[junit4] > at
org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration.testNodeLostTrigger(TestTriggerIntegration.java:454)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 713469 DEBUG
(SUITE-TestTriggerIntegration-seed#[B5246C2488C2024A]-worker) [ ]
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.sim.TestTriggerIntegration_B5246C2488C2024A-001
[junit4] 2> 713485 WARN (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are
closed, exiting.
[junit4] 2> NOTE: test params are:
codec=FastCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST,
chunkSize=7, maxDocsPerChunk=8, blockSize=4),
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST,
chunkSize=7, blockSize=4)), sim=RandomSimilarity(queryNorm=false): {},
locale=ro-RO, timezone=America/Santo_Domingo
[junit4] 2> NOTE: Linux 4.4.0-112-generic amd64/Oracle Corporation
1.8.0_152 (64-bit)/cpus=4,threads=1,free=121775296,total=389545984
[junit4] 2> NOTE: All tests run in this JVM: [SoftAutoCommitTest,
SortSpecParsingTest, TestReloadAndDeleteDocs, HighlighterMaxOffsetTest,
AssignTest, ZkStateReaderTest, TestNumericRangeQuery64, HdfsThreadLeakTest,
JsonLoaderTest, TestSearchPerf, BasicFunctionalityTest,
TestGenericDistributedQueue, TestMaxScoreQueryParser, TestFastLRUCache,
TestReversedWildcardFilterFactory, TestFieldSortValues, ResourceLoaderTest,
TestSimpleTrackingShardHandler, TestReloadDeadlock, SolrInfoBeanTest,
HdfsBasicDistributedZk2Test, TestDefaultStatsCache, TestPolicyCloud,
TestPerFieldSimilarity, TestTriggerIntegration]
[junit4] Completed [90/784 (1!)] on J1 in 6.84s, 11 tests, 1 failure, 6
skipped <<< FAILURES!
[...truncated 52020 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
