Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/7694/
Java: 64bit/jdk-12-ea+23 -XX:-UseCompressedOops -XX:+UseParallelGC
2 tests failed.
FAILED:
org.apache.solr.cloud.autoscaling.sim.TestSimTriggerIntegration.testEventQueue
Error Message:
action wasn't interrupted
Stack Trace:
java.lang.AssertionError: action wasn't interrupted
at
__randomizedtesting.SeedInfo.seed([F41D2D6F85AD480A:3DA86FC18CCA8EFF]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at
org.apache.solr.cloud.autoscaling.sim.TestSimTriggerIntegration.testEventQueue(TestSimTriggerIntegration.java:717)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:567)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
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:947)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:835)
FAILED:
org.apache.solr.cloud.autoscaling.sim.TestSimTriggerIntegration.testEventQueue
Error Message:
action wasn't interrupted
Stack Trace:
java.lang.AssertionError: action wasn't interrupted
at
__randomizedtesting.SeedInfo.seed([F41D2D6F85AD480A:3DA86FC18CCA8EFF]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at
org.apache.solr.cloud.autoscaling.sim.TestSimTriggerIntegration.testEventQueue(TestSimTriggerIntegration.java:717)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:567)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
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:947)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:835)
Build Log:
[...truncated 14596 lines...]
[junit4] Suite:
org.apache.solr.cloud.autoscaling.sim.TestSimTriggerIntegration
[junit4] 2> 2754995 INFO
(SUITE-TestSimTriggerIntegration-seed#[F41D2D6F85AD480A]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.sim.TestSimTriggerIntegration_F41D2D6F85AD480A-001\init-core-data-001
[junit4] 2> 2754997 INFO
(SUITE-TestSimTriggerIntegration-seed#[F41D2D6F85AD480A]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 2755000 INFO
(SUITE-TestSimTriggerIntegration-seed#[F41D2D6F85AD480A]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0,
clientAuth=0.0/0.0)
[junit4] 2> 2755002 INFO
(TEST-TestSimTriggerIntegration.testNodeAddedTrigger-seed#[F41D2D6F85AD480A]) [
] o.a.s.SolrTestCaseJ4 ###Starting testNodeAddedTrigger
[junit4] 2> 2755014 DEBUG
(TEST-TestSimTriggerIntegration.testNodeAddedTrigger-seed#[F41D2D6F85AD480A]) [
] o.a.s.c.a.s.SimClusterStateProvider --- new Overseer leader:
127.0.0.1:10242_solr
[junit4] 2> 2755014 DEBUG
(TEST-TestSimTriggerIntegration.testNodeAddedTrigger-seed#[F41D2D6F85AD480A]) [
] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=0
[junit4] 2> 2755014 DEBUG
(TEST-TestSimTriggerIntegration.testNodeAddedTrigger-seed#[F41D2D6F85AD480A]) [
] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 0
[junit4] 2> 2755014 INFO
(TEST-TestSimTriggerIntegration.testNodeAddedTrigger-seed#[F41D2D6F85AD480A]) [
] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics
history in memory.
[junit4] 2> 2755015 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 2755016 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 1
[junit4] 2> 2755016 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 2755016 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 2755016 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial
livenodes: [127.0.0.1:10242_solr, 127.0.0.1:10243_solr]
[junit4] 2> 2755016 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- cleaning old nodeLost / nodeAdded markers
[junit4] 2> 2755016 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 2755018 DEBUG (ScheduledTrigger-15102-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 2755019 DEBUG (simCloudManagerPool-15101-thread-2) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2755019 DEBUG (simCloudManagerPool-15101-thread-2) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
2
[junit4] 2> 2755019 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 2755019 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- cleaning old nodeLost / nodeAdded markers
[junit4] 2> 2755019 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 2755023 DEBUG (simCloudManagerPool-15101-thread-3) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2755023 DEBUG (simCloudManagerPool-15101-thread-3) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
3
[junit4] 2> 2755023 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 2755023 INFO (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.s.TestSimTriggerIntegration TestTriggerAction init
[junit4] 2> 2755023 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger - Initial
livenodes: [127.0.0.1:10242_solr, 127.0.0.1:10243_solr]
[junit4] 2> 2755023 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] 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.TestSimTriggerIntegration$TestTriggerAction}],
enabled=true}
[junit4] 2> 2755023 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- cleaning old nodeLost / nodeAdded markers
[junit4] 2> 2755023 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 2755023 DEBUG (ScheduledTrigger-15102-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2755023 DEBUG (ScheduledTrigger-15102-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 2755027 DEBUG
(TEST-TestSimTriggerIntegration.testNodeAddedTrigger-seed#[F41D2D6F85AD480A]) [
] o.a.s.c.a.s.SimClusterStateProvider -- creating marker:
/autoscaling/nodeAdded/127.0.0.1:10244_solr
[junit4] 2> 2755037 DEBUG (ScheduledTrigger-15102-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3 and last live nodes: 2
[junit4] 2> 2755043 DEBUG (ScheduledTrigger-15102-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2755044 DEBUG (ScheduledTrigger-15102-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 2755044 DEBUG (ScheduledTrigger-15102-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:10244_solr at time
452238400048800
[junit4] 2> 2755056 DEBUG (ScheduledTrigger-15102-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3 and last live nodes: 3
[junit4] 2> 2755067 DEBUG (ScheduledTrigger-15102-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2755067 DEBUG (ScheduledTrigger-15102-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 2755074 DEBUG (ScheduledTrigger-15102-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3 and last live nodes: 3
[junit4] 2> 2755086 DEBUG (ScheduledTrigger-15102-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2755086 DEBUG (ScheduledTrigger-15102-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 2755097 DEBUG (ScheduledTrigger-15102-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3 and last live nodes: 3
[junit4] 2> 2755109 DEBUG (ScheduledTrigger-15102-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2755109 DEBUG (ScheduledTrigger-15102-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 2755109 DEBUG (ScheduledTrigger-15102-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger firing
registered processor for nodes: [127.0.0.1:10244_solr] added at times
[452238400048800], now=452242188688800
[junit4] 2> 2755122 DEBUG (ScheduledTrigger-15102-thread-2) [ ]
o.a.s.c.a.SystemLogListener Collection .system missing, skip sending event {
[junit4] 2> "id":"19b4ef5304ea0Tf5c0sizl0xhb730z14e7h6kfz",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":452238400048800,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[452238400048800],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":452242969863800,
[junit4] 2> "nodeNames":["127.0.0.1:10244_solr"]}}
[junit4] 2> 2755122 DEBUG (ScheduledTrigger-15102-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Removing new node from tracking: 127.0.0.1:10244_solr
[junit4] 2> 2755122 DEBUG (AutoscalingActionExecutor-15103-thread-1) [
] o.a.s.c.a.SystemLogListener Collection .system missing, skip sending event {
[junit4] 2> "id":"19b4ef5304ea0Tf5c0sizl0xhb730z14e7h6kfz",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":452238400048800,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[452238400048800],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":452242969863800,
[junit4] 2> "nodeNames":["127.0.0.1:10244_solr"]}}
[junit4] 2> 2755125 DEBUG (simCloudManagerPool-15101-thread-4) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2755125 DEBUG (simCloudManagerPool-15101-thread-4) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
4
[junit4] 2> 2755125 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4
[junit4] 2> 2755125 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- cleaning old nodeLost / nodeAdded markers
[junit4] 2> 2755125 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion 4
[junit4] 2> 2755162 WARN (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Interrupted
[junit4] 2> java.lang.InterruptedException: null
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2056)
~[?:?]
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2090)
~[?:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:201)
[java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:835) [?:?]
[junit4] 2> 2755163 DEBUG
(TEST-TestSimTriggerIntegration.testNodeAddedTrigger-seed#[F41D2D6F85AD480A]) [
] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed
explicitly
[junit4] 2> 2755163 INFO
(TEST-TestSimTriggerIntegration.testNodeAddedTrigger-seed#[F41D2D6F85AD480A]) [
] o.a.s.SolrTestCaseJ4 ###Ending testNodeAddedTrigger
[junit4] 2> 2755163 INFO
(TEST-TestSimTriggerIntegration.testSearchRate-seed#[F41D2D6F85AD480A]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testSearchRate
[junit4] 2> 2755215 DEBUG
(TEST-TestSimTriggerIntegration.testSearchRate-seed#[F41D2D6F85AD480A]) [ ]
o.a.s.c.a.s.SimClusterStateProvider --- new Overseer leader:
127.0.0.1:10245_solr
[junit4] 2> 2755216 DEBUG
(TEST-TestSimTriggerIntegration.testSearchRate-seed#[F41D2D6F85AD480A]) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=0
[junit4] 2> 2755216 DEBUG
(TEST-TestSimTriggerIntegration.testSearchRate-seed#[F41D2D6F85AD480A]) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 0
[junit4] 2> 2755216 INFO
(TEST-TestSimTriggerIntegration.testSearchRate-seed#[F41D2D6F85AD480A]) [ ]
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history
in memory.
[junit4] 2> 2755217 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 2755217 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 1
[junit4] 2> 2755217 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 2755217 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 2755218 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial
livenodes: [127.0.0.1:10245_solr, 127.0.0.1:10246_solr]
[junit4] 2> 2755218 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- cleaning old nodeLost / nodeAdded markers
[junit4] 2> 2755218 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 2755223 DEBUG (ScheduledTrigger-15108-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 2755224 DEBUG (simCloudManagerPool-15107-thread-2) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2755224 DEBUG (simCloudManagerPool-15107-thread-2) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
2
[junit4] 2> 2755225 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 2755225 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- cleaning old nodeLost / nodeAdded markers
[junit4] 2> 2755225 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 2755226 DEBUG (simCloudManagerPool-15107-thread-3) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- simCreateCollection collection1,
currentVersion=1
[junit4] 2> 2755226 DEBUG (simCloudManagerPool-15107-thread-3) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=1
[junit4] 2> 2755226 DEBUG (simCloudManagerPool-15107-thread-3) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 1
[junit4] 2> 2755235 DEBUG (simCloudManagerPool-15107-thread-3) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=2
[junit4] 2> 2755235 DEBUG (simCloudManagerPool-15107-thread-3) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 2
[junit4] 2> 2755236 DEBUG (simCloudManagerPool-15107-thread-3) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- finished createCollection collection1,
currentVersion=3
[junit4] 2> 2755242 DEBUG (ScheduledTrigger-15108-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 2755246 DEBUG (simCloudManagerPool-15107-thread-4) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=3
[junit4] 2> 2755246 DEBUG (simCloudManagerPool-15107-thread-4) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 3
[junit4] 2> 2755255 DEBUG (simCloudManagerPool-15107-thread-5) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=4
[junit4] 2> 2755255 DEBUG (simCloudManagerPool-15107-thread-5) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 4
[junit4] 2> 2755255 DEBUG
(TEST-TestSimTriggerIntegration.testSearchRate-seed#[F41D2D6F85AD480A]) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=5
[junit4] 2> 2755255 DEBUG
(TEST-TestSimTriggerIntegration.testSearchRate-seed#[F41D2D6F85AD480A]) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 5
[junit4] 2> 2755255 INFO
(TEST-TestSimTriggerIntegration.testSearchRate-seed#[F41D2D6F85AD480A]) [ ]
o.a.s.c.CloudTestUtils -- shards without leaders: [shard1]
[junit4] 2> 2755255 DEBUG (simCloudManagerPool-15107-thread-6) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for collection1 /
shard1 (currentVersion=6): {"core_node1":{
[junit4] 2> "core":"collection1_shard1_replica_n1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "node_name":"127.0.0.1:10246_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 2755257 DEBUG
(TEST-TestSimTriggerIntegration.testSearchRate-seed#[F41D2D6F85AD480A]) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=6
[junit4] 2> 2755258 DEBUG
(TEST-TestSimTriggerIntegration.testSearchRate-seed#[F41D2D6F85AD480A]) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 6
[junit4] 2> 2755261 DEBUG (ScheduledTrigger-15108-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 2755270 DEBUG (simCloudManagerPool-15107-thread-8) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=7
[junit4] 2> 2755270 DEBUG (simCloudManagerPool-15107-thread-8) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 7
[junit4] 2> 2755270 DEBUG (simCloudManagerPool-15107-thread-8) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2755270 DEBUG (simCloudManagerPool-15107-thread-8) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
3
[junit4] 2> 2755280 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 2755280 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- cleaning old nodeLost / nodeAdded markers
[junit4] 2> 2755280 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 2755294 DEBUG (ScheduledTrigger-15108-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 2755331 DEBUG (ScheduledTrigger-15108-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 2755335 DEBUG (ScheduledTrigger-15108-thread-4) [ ]
o.a.s.c.a.SearchRateTrigger ### rates for node 127.0.0.1:10245_solr
[junit4] 2> 2755335 DEBUG (ScheduledTrigger-15108-thread-4) [ ]
o.a.s.c.a.SearchRateTrigger ### rates for node 127.0.0.1:10246_solr
[junit4] 2> 2755335 DEBUG (simCloudManagerPool-15107-thread-9) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2755337 DEBUG (simCloudManagerPool-15107-thread-9) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
4
[junit4] 2> 2755337 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4
[junit4] 2> 2755337 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- cleaning old nodeLost / nodeAdded markers
[junit4] 2> 2755337 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion 4
[junit4] 2> 2755392 DEBUG (ScheduledTrigger-15108-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 2755421 DEBUG (ScheduledTrigger-15108-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 2755421 DEBUG (ScheduledTrigger-15108-thread-1) [ ]
o.a.s.c.a.SearchRateTrigger ### rates for node 127.0.0.1:10245_solr
[junit4] 2> 2755421 DEBUG (ScheduledTrigger-15108-thread-1) [ ]
o.a.s.c.a.SearchRateTrigger ###
metrics:solr.core.collection1.shard1.replica_n2:QUERY./select.requestTimes:1minRate
250
[junit4] 2> 2755423 DEBUG (ScheduledTrigger-15108-thread-1) [ ]
o.a.s.c.a.SearchRateTrigger ### rates for node 127.0.0.1:10246_solr
[junit4] 2> 2755423 DEBUG (ScheduledTrigger-15108-thread-1) [ ]
o.a.s.c.a.SearchRateTrigger ###
metrics:solr.core.collection1.shard1.replica_n1:QUERY./select.requestTimes:1minRate
250
[junit4] 2> 2755423 DEBUG (ScheduledTrigger-15108-thread-1) [ ]
o.a.s.c.a.SearchRateTrigger ## Collection: collection1
[junit4] 2> 2755426 DEBUG (ScheduledTrigger-15108-thread-1) [ ]
o.a.s.c.a.SearchRateTrigger ## - shard1
[junit4] 2> 2755426 DEBUG (ScheduledTrigger-15108-thread-1) [ ]
o.a.s.c.a.SearchRateTrigger ## collection1_shard1_replica_n2 250.0
[junit4] 2> 2755426 DEBUG (ScheduledTrigger-15108-thread-1) [ ]
o.a.s.c.a.SearchRateTrigger ## collection1_shard1_replica_n1 250.0
[junit4] 2> 2755435 DEBUG (ScheduledTrigger-15108-thread-1) [ ]
o.a.s.c.a.SearchRateTrigger -- collection1.shard1: totalShardRate=500.0,
shardRate=250.0
[junit4] 2> 2755439 DEBUG (ScheduledTrigger-15108-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 2755453 DEBUG (ScheduledTrigger-15108-thread-3) [ ]
o.a.s.c.a.SearchRateTrigger ### rates for node 127.0.0.1:10245_solr
[junit4] 2> 2755453 DEBUG (ScheduledTrigger-15108-thread-3) [ ]
o.a.s.c.a.SearchRateTrigger ###
metrics:solr.core.collection1.shard1.replica_n2:QUERY./select.requestTimes:1minRate
250
[junit4] 2> 2755453 DEBUG (ScheduledTrigger-15108-thread-3) [ ]
o.a.s.c.a.SearchRateTrigger ### rates for node 127.0.0.1:10246_solr
[junit4] 2> 2755453 DEBUG (ScheduledTrigger-15108-thread-3) [ ]
o.a.s.c.a.SearchRateTrigger ###
metrics:solr.core.collection1.shard1.replica_n1:QUERY./select.requestTimes:1minRate
250
[junit4] 2> 2755453 DEBUG (ScheduledTrigger-15108-thread-3) [ ]
o.a.s.c.a.SearchRateTrigger ## Collection: collection1
[junit4] 2> 2755453 DEBUG (ScheduledTrigger-15108-thread-3) [ ]
o.a.s.c.a.SearchRateTrigger ## - shard1
[junit4] 2> 2755453 DEBUG (ScheduledTrigger-15108-thread-3) [ ]
o.a.s.c.a.SearchRateTrigger ## collection1_shard1_replica_n2 250.0
[junit4] 2> 2755453 DEBUG (ScheduledTrigger-15108-thread-3) [ ]
o.a.s.c.a.SearchRateTrigger ## collection1_shard1_replica_n1 250.0
[junit4] 2> 2755453 DEBUG (ScheduledTrigger-15108-thread-3) [ ]
o.a.s.c.a.SearchRateTrigger -- collection1.shard1: totalShardRate=500.0,
shardRate=250.0
[junit4] 2> 2755458 DEBUG (ScheduledTrigger-15108-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 2755472 DEBUG (ScheduledTrigger-15108-thread-1) [ ]
o.a.s.c.a.SearchRateTrigger ### rates for node 127.0.0.1:10245_solr
[junit4] 2> 2755472 DEBUG (ScheduledTrigger-15108-thread-1) [ ]
o.a.s.c.a.SearchRateTrigger ###
metrics:solr.core.collection1.shard1.replica_n2:QUERY./select.requestTimes:1minRate
250
[junit4] 2> 2755472 DEBUG (ScheduledTrigger-15108-thread-1) [ ]
o.a.s.c.a.SearchRateTrigger ### rates for node 127.0.0.1:10246_solr
[junit4] 2> 2755472 DEBUG (ScheduledTrigger-15108-thread-1) [ ]
o.a.s.c.a.SearchRateTrigger ###
metrics:solr.core.collection1.shard1.replica_n1:QUERY./select.requestTimes:1minRate
250
[junit4] 2> 2755472 DEBUG (ScheduledTrigger-15108-thread-1) [ ]
o.a.s.c.a.SearchRateTrigger ## Collection: collection1
[junit4] 2> 2755472 DEBUG (ScheduledTrigger-15108-thread-1) [ ]
o.a.s.c.a.SearchRateTrigger ## - shard1
[junit4] 2> 2755472 DEBUG (ScheduledTrigger-15108-thread-1) [ ]
o.a.s.c.a.SearchRateTrigger ## collection1_shard1_replica_n2 250.0
[junit4] 2> 2755472 DEBUG (ScheduledTrigger-15108-thread-1) [ ]
o.a.s.c.a.SearchRateTrigger ## collection1_shard1_replica_n1 250.0
[junit4] 2> 2755472 DEBUG (ScheduledTrigger-15108-thread-1) [ ]
o.a.s.c.a.SearchRateTrigger -- collection1.shard1: totalShardRate=500.0,
shardRate=250.0
[junit4] 2> 2755476 DEBUG (ScheduledTrigger-15108-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 2755478 DEBUG (ScheduledTrigger-15108-thread-1) [ ]
o.a.s.c.a.SystemLogListener Collection .system missing, skip sending event {
[junit4] 2> "id":"19b541deebc28Tf5c0sizl0xhb730z14e7h6kg1",
[junit4] 2> "source":"search_rate_trigger",
[junit4] 2> "eventTime":452260558453800,
[junit4] 2> "eventType":"SEARCHRATE",
[junit4] 2> "properties":{
[junit4] 2> "hotReplicas":[
[junit4] 2> {"core_node2":{
[junit4] 2> "core":"collection1_shard1_replica_n2",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "node_name":"127.0.0.1:10245_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "rate":250.0,
[junit4] 2> "state":"active",
[junit4] 2> "QUERY./select.requestTimes:1minRate":250,
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}},
[junit4] 2> {"core_node1":{
[junit4] 2> "core":"collection1_shard1_replica_n1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "node_name":"127.0.0.1:10246_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "rate":250.0,
[junit4] 2> "state":"active",
[junit4] 2> "QUERY./select.requestTimes:1minRate":250,
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}],
[junit4] 2> "coldNodes":{},
[junit4] 2> "coldShards":{},
[junit4] 2> "coldReplicas":[],
[junit4] 2> "violationType":["hotShards"],
[junit4] 2> "coldCollections":{},
[junit4] 2> "hotShards":{"collection1":{"shard1":250.0}},
[junit4] 2> "hotCollections":{"collection1":500.0},
[junit4] 2> "hotNodes":{
[junit4] 2> "127.0.0.1:10245_solr":250.0,
[junit4] 2> "127.0.0.1:10246_solr":250.0},
[junit4] 2> "_enqueue_time_":452263489223800,
[junit4] 2> "requestedOps":[
[junit4] 2> {
[junit4] 2> "action":"ADDREPLICA",
[junit4] 2> "hints":{"COLL_SHARD":[{
[junit4] 2> "first":"collection1",
[junit4] 2> "second":"shard1"}]}},
[junit4] 2> {
[junit4] 2> "action":"ADDREPLICA",
[junit4] 2> "hints":{"COLL_SHARD":[{
[junit4] 2> "first":"collection1",
[junit4] 2> "second":"shard1"}]}},
[junit4] 2> {
[junit4] 2> "action":"ADDREPLICA",
[junit4] 2> "hints":{"COLL_SHARD":[{
[junit4] 2> "first":"collection1",
[junit4] 2> "second":"shard1"}]}}]}}
[junit4] 2> 2755483 DEBUG (AutoscalingActionExecutor-15109-thread-1) [
] o.a.s.c.a.ComputePlanAction -- processing event: {
[junit4] 2> "id":"19b541deebc28Tf5c0sizl0xhb730z14e7h6kg1",
[junit4] 2> "source":"search_rate_trigger",
[junit4] 2> "eventTime":452260558453800,
[junit4] 2> "eventType":"SEARCHRATE",
[junit4] 2> "properties":{
[junit4] 2> "hotReplicas":[
[junit4] 2> {"core_node2":{
[junit4] 2> "core":"collection1_shard1_replica_n2",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "node_name":"127.0.0.1:10245_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "rate":250.0,
[junit4] 2> "state":"active",
[junit4] 2> "QUERY./select.requestTimes:1minRate":250,
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}},
[junit4] 2> {"core_node1":{
[junit4] 2> "core":"collection1_shard1_replica_n1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "node_name":"127.0.0.1:10246_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "rate":250.0,
[junit4] 2> "state":"active",
[junit4] 2> "QUERY./select.requestTimes:1minRate":250,
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}],
[junit4] 2> "coldNodes":{},
[junit4] 2> "coldShards":{},
[junit4] 2> "coldReplicas":[],
[junit4] 2> "violationType":["hotShards"],
[junit4] 2> "coldCollections":{},
[junit4] 2> "hotShards":{"collection1":{"shard1":250.0}},
[junit4] 2> "hotCollections":{"collection1":500.0},
[junit4] 2> "hotNodes":{
[junit4] 2> "127.0.0.1:10245_solr":250.0,
[junit4] 2> "127.0.0.1:10246_solr":250.0},
[junit4] 2> "_enqueue_time_":452263489223800,
[junit4] 2> "requestedOps":[
[junit4] 2> {
[junit4] 2> "action":"ADDREPLICA",
[junit4] 2> "hints":{"COLL_SHARD":[{
[junit4] 2> "first":"collection1",
[junit4] 2> "second":"shard1"}]}},
[junit4] 2> {
[junit4] 2> "action":"ADDREPLICA",
[junit4] 2> "hints":{"COLL_SHARD":[{
[junit4] 2> "first":"collection1",
[junit4] 2> "second":"shard1"}]}},
[junit4] 2> {
[junit4] 2> "action":"ADDREPLICA",
[junit4] 2> "hints":{"COLL_SHARD":[{
[junit4] 2> "first":"collection1",
[junit4] 2> "second":"shard1"}]}}]}} with context
properties: {AFTER_ACTION=[start], BEFORE_ACTION=[start, compute]}
[junit4] 2> 2755484 DEBUG (AutoscalingActionExecutor-15109-thread-1) [
] o.a.s.c.a.ComputePlanAction Computed Plan:
action=ADDREPLICA&collection=collection1&shard=shard1&node=127.0.0.1:10246_solr&type=NRT
[junit4] 2> 2755484 DEBUG (AutoscalingActionExecutor-15109-thread-1) [
] o.a.s.c.a.ComputePlanAction Computed Plan:
action=ADDREPLICA&collection=collection1&shard=shard1&node=127.0.0.1:10245_solr&type=NRT
[junit4] 2> 2755484 DEBUG (AutoscalingActionExecutor-15109-thread-1) [
] o.a.s.c.a.ComputePlanAction Computed Plan:
action=ADDREPLICA&collection=collection1&shard=shard1&node=127.0.0.1:10245_solr&type=NRT
[junit4] 2> 2755484 DEBUG (AutoscalingActionExecutor-15109-thread-1) [
] o.a.s.c.a.ExecutePlanAction -- processing event: {
[junit4] 2> "id":"19b541deebc28Tf5c0sizl0xhb730z14e7h6kg1",
[junit4] 2> "source":"search_rate_trigger",
[junit4] 2> "eventTime":452260558453800,
[junit4] 2> "eventType":"SEARCHRATE",
[junit4] 2> "properties":{
[junit4] 2> "hotReplicas":[
[junit4] 2> {"core_node2":{
[junit4] 2> "core":"collection1_shard1_replica_n2",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "node_name":"127.0.0.1:10245_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "rate":250.0,
[junit4] 2> "state":"active",
[junit4] 2> "QUERY./select.requestTimes:1minRate":250,
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}},
[junit4] 2> {"core_node1":{
[junit4] 2> "core":"collection1_shard1_replica_n1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "node_name":"127.0.0.1:10246_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "rate":250.0,
[junit4] 2> "state":"active",
[junit4] 2> "QUERY./select.requestTimes:1minRate":250,
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}],
[junit4] 2> "__start__":3,
[junit4] 2> "coldNodes":{},
[junit4] 2> "coldShards":{},
[junit4] 2> "coldReplicas":[],
[junit4] 2> "violationType":["hotShards"],
[junit4] 2> "coldCollections":{},
[junit4] 2> "hotShards":{"collection1":{"shard1":250.0}},
[junit4] 2> "hotCollections":{"collection1":500.0},
[junit4] 2> "hotNodes":{
[junit4] 2> "127.0.0.1:10245_solr":250.0,
[junit4] 2> "127.0.0.1:10246_solr":250.0},
[junit4] 2> "_enqueue_time_":452263489223800,
[junit4] 2> "requestedOps":[
[junit4] 2> {
[junit4] 2> "action":"ADDREPLICA",
[junit4] 2> "hints":{"COLL_SHARD":[{
[junit4] 2> "first":"collection1",
[junit4] 2> "second":"shard1"}]}},
[junit4] 2> {
[junit4] 2> "action":"ADDREPLICA",
[junit4] 2> "hints":{"COLL_SHARD":[{
[junit4] 2> "first":"collection1",
[junit4] 2> "second":"shard1"}]}},
[junit4] 2> {
[junit4] 2> "action":"ADDREPLICA",
[junit4] 2> "hints":{"COLL_SHARD":[{
[junit4] 2> "first":"collection1",
[junit4] 2> "second":"shard1"}]}}]}} with context
properties: {operations=[{
[junit4] 2>
"class":"org.apache.solr.client.solrj.request.CollectionAdminRequest$AddReplica",
[junit4] 2> "method":"GET",
[junit4] 2> "params.action":"ADDREPLICA",
[junit4] 2> "params.collection":"collection1",
[junit4] 2> "params.shard":"shard1",
[junit4] 2> "params.node":"127.0.0.1:10246_solr",
[junit4] 2> "params.type":"NRT"}, {
[junit4] 2>
"class":"org.apache.solr.client.solrj.request.CollectionAdminRequest$AddReplica",
[junit4] 2> "method":"GET",
[junit4] 2> "params.action":"ADDREPLICA",
[junit4] 2> "params.collection":"collection1",
[junit4] 2> "params.shard":"shard1",
[junit4] 2> "params.node":"127.0.0.1:10245_solr",
[junit4] 2> "params.type":"NRT"}, {
[junit4] 2>
"class":"org.apache.solr.client.solrj.request.CollectionAdminRequest$AddReplica",
[junit4] 2> "method":"GET",
[junit4] 2> "params.action":"ADDREPLICA",
[junit4] 2> "params.collection":"collection1",
[junit4] 2> "params.shard":"shard1",
[junit4] 2> "params.node":"127.0.0.1:10245_solr",
[junit4] 2> "params.type":"NRT"}], AFTER_ACTION=[start, compute],
BEFORE_ACTION=[start, compute, execute]}
[junit4] 2> 2755485 DEBUG (AutoscalingActionExecutor-15109-thread-1) [
] o.a.s.c.a.ExecutePlanAction Executing operation:
action=ADDREPLICA&collection=collection1&shard=shard1&node=127.0.0.1:10246_solr&type=NRT
[junit4] 2> 2755486 INFO (simCloudManagerPool-15107-thread-10) [ ]
o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10246_solr for creating new
replica of shard shard1 for collection collection1
[junit4] 2> 2755486 INFO (simCloudManagerPool-15107-thread-10) [ ]
o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 2755495 DEBUG (simCloudManagerPool-15107-thread-10) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=8
[junit4] 2> 2755495 DEBUG (simCloudManagerPool-15107-thread-10) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 8
[junit4] 2> 2755496 DEBUG (AutoscalingActionExecutor-15109-thread-1) [
] o.a.s.c.a.ExecutePlanAction Executing operation:
action=ADDREPLICA&collection=collection1&shard=shard1&node=127.0.0.1:10245_solr&type=NRT
[junit4] 2> 2755497 INFO (simCloudManagerPool-15107-thread-13) [ ]
o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10245_solr for creating new
replica of shard shard1 for collection collection1
[junit4] 2> 2755497 INFO (simCloudManagerPool-15107-thread-13) [ ]
o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 2755508 DEBUG (simCloudManagerPool-15107-thread-13) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=9
[junit4] 2> 2755508 DEBUG (simCloudManagerPool-15107-thread-13) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 9
[junit4] 2> 2755509 DEBUG (AutoscalingActionExecutor-15109-thread-1) [
] o.a.s.c.a.ExecutePlanAction Executing operation:
action=ADDREPLICA&collection=collection1&shard=shard1&node=127.0.0.1:10245_solr&type=NRT
[junit4] 2> 2755514 INFO (simCloudManagerPool-15107-thread-16) [ ]
o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10245_solr for creating new
replica of shard shard1 for collection collection1
[junit4] 2> 2755514 INFO (simCloudManagerPool-15107-thread-16) [ ]
o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 2755524 DEBUG (simCloudManagerPool-15107-thread-16) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=10
[junit4] 2> 2755524 DEBUG (simCloudManagerPool-15107-thread-16) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 10
[junit4] 2> 2755525 DEBUG (AutoscalingActionExecutor-15109-thread-1) [
] o.a.s.c.a.SystemLogListener Collection .system missing, skip sending event {
[junit4] 2> "id":"19b541deebc28Tf5c0sizl0xhb730z14e7h6kg1",
[junit4] 2> "source":"search_rate_trigger",
[junit4] 2> "eventTime":452260558453800,
[junit4] 2> "eventType":"SEARCHRATE",
[junit4] 2> "properties":{
[junit4] 2> "hotReplicas":[
[junit4] 2> {"core_node2":{
[junit4] 2> "core":"collection1_shard1_replica_n2",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "node_name":"127.0.0.1:10245_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "rate":250.0,
[junit4] 2> "state":"active",
[junit4] 2> "QUERY./select.requestTimes:1minRate":250,
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}},
[junit4] 2> {"core_node1":{
[junit4] 2> "core":"collection1_shard1_replica_n1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "node_name":"127.0.0.1:10246_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "rate":250.0,
[junit4] 2> "state":"active",
[junit4] 2> "QUERY./select.requestTimes:1minRate":250,
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}],
[junit4] 2> "__start__":3,
[junit4] 2> "coldNodes":{},
[junit4] 2> "coldShards":{},
[junit4] 2> "coldReplicas":[],
[junit4] 2> "violationType":["hotShards"],
[junit4] 2> "coldCollections":{},
[junit4] 2> "hotShards":{"collection1":{"shard1":250.0}},
[junit4] 2> "hotCollections":{"collection1":500.0},
[junit4] 2> "hotNodes":{
[junit4] 2> "127.0.0.1:10245_solr":250.0,
[junit4] 2> "127.0.0.1:10246_solr":250.0},
[junit4] 2> "_enqueue_time_":452263489223800,
[junit4] 2> "requestedOps":[
[junit4] 2> {
[junit4] 2> "action":"ADDREPLICA",
[junit4] 2> "hints":{"COLL_SHARD":[{
[junit4] 2> "first":"collection1",
[junit4] 2> "second":"shard1"}]}},
[junit4] 2> {
[junit4] 2> "action":"ADDREPLICA",
[junit4] 2> "hints":{"COLL_SHARD":[{
[junit4] 2> "first":"collection1",
[junit4] 2> "second":"shard1"}]}},
[junit4] 2> {
[junit4] 2> "action":"ADDREPLICA",
[junit4] 2> "hints":{"COLL_SHARD":[{
[junit4] 2> "first":"collection1",
[junit4] 2> "second":"shard1"}]}}]}}
[junit4] 2> 2755623 DEBUG (ScheduledTrigger-15108-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 2755623 WARN (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Interrupted
[junit4] 2> java.lang.InterruptedException: null
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2056)
~[?:?]
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2090)
~[?:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:201)
[java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:835) [?:?]
[junit4] 2> 2755623 WARN (ScheduledTrigger-15108-thread-2) [ ]
o.a.s.c.a.SearchRateTrigger Error getting ClusterState
[junit4] 2> java.io.IOException: already closed
[junit4] 2> at
org.apache.solr.cloud.autoscaling.sim.SimClusterStateProvider.ensureNotClosed(SimClusterStateProvider.java:2217)
~[test/:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.sim.SimClusterStateProvider.getClusterState(SimClusterStateProvider.java:2121)
~[test/:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.SearchRateTrigger.run(SearchRateTrigger.java:339)
[java/:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ScheduledTriggers$TriggerWrapper.run(ScheduledTriggers.java:633)
[java/:?]
[junit4] 2> at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]
[junit4] 2> at
java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305) [?:?]
[junit4] 2> at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305)
[?:?]
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
[?:?]
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
[?:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:835) [?:?]
[junit4] 2> 2755623 DEBUG
(TEST-TestSimTriggerIntegration.testSearchRate-seed#[F41D2D6F85AD480A]) [ ]
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 2755626 INFO
(TEST-TestSimTriggerIntegration.testSearchRate-seed#[F41D2D6F85AD480A]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testSearchRate
[junit4] 2> 2755626 INFO
(TEST-TestSimTriggerIntegration.testEventFromRestoredState-seed#[F41D2D6F85AD480A])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testEventFromRestoredState
[junit4] 2> 2755717 DEBUG
(TEST-TestSimTriggerIntegration.testEventFromRestoredState-seed#[F41D2D6F85AD480A])
[ ] o.a.s.c.a.s.SimClusterStateProvider --- new Overseer leader:
127.0.0.1:10247_solr
[junit4] 2> 2755717 DEBUG
(TEST-TestSimTriggerIntegration.testEventFromRestoredState-seed#[F41D2D6F85AD480A])
[ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=0
[junit4] 2> 2755718 DEBUG
(TEST-TestSimTriggerIntegration.testEventFromRestoredState-seed#[F41D2D6F85AD480A])
[ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 0
[junit4] 2> 2755718 INFO
(TEST-TestSimTriggerIntegration.testEventFromRestoredState-seed#[F41D2D6F85AD480A])
[ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics
history in memory.
[junit4] 2> 2755718 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 2755718 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 1
[junit4] 2> 2755720 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 2755720 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 2755720 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial
livenodes: [127.0.0.1:10247_solr, 127.0.0.1:10248_solr]
[junit4] 2> 2755720 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- cleaning old nodeLost / nodeAdded markers
[junit4] 2> 2755720 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 2755722 DEBUG (ScheduledTrigger-15114-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 2755723 DEBUG (simCloudManagerPool-15113-thread-2) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2755723 DEBUG (simCloudManagerPool-15113-thread-2) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
2
[junit4] 2> 2755724 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 2755725 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- cleaning old nodeLost / nodeAdded markers
[junit4] 2> 2755725 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 2755726 DEBUG (simCloudManagerPool-15113-thread-3) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2755726 DEBUG (simCloudManagerPool-15113-thread-3) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
3
[junit4] 2> 2755726 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 2755726 INFO (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.s.TestSimTriggerIntegration TestTriggerAction init
[junit4] 2> 2755726 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger - Initial
livenodes: [127.0.0.1:10247_solr, 127.0.0.1:10248_solr]
[junit4] 2> 2755726 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger instantiated
with properties: {event=nodeAdded, waitFor=10, actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.sim.TestSimTriggerIntegration$TestTriggerAction}],
enabled=true}
[junit4] 2> 2755727 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- cleaning old nodeLost / nodeAdded markers
[junit4] 2> 2755727 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 2755735 DEBUG (ScheduledTrigger-15114-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2755735 DEBUG (ScheduledTrigger-15114-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 2755736 DEBUG
(TEST-TestSimTriggerIntegration.testEventFromRestoredState-seed#[F41D2D6F85AD480A])
[ ] o.a.s.c.a.s.SimClusterStateProvider -- creating marker:
/autoscaling/nodeAdded/127.0.0.1:10249_solr
[junit4] 2> 2755743 DEBUG (ScheduledTrigger-15114-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3 and last live nodes: 2
[junit4] 2> 2755754 DEBUG (ScheduledTrigger-15114-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2755754 DEBUG (ScheduledTrigger-15114-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 2755754 DEBUG (ScheduledTrigger-15114-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:10249_solr at time
452279580623800
[junit4] 2> 2755762 DEBUG (ScheduledTrigger-15114-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3 and last live nodes: 3
[junit4] 2> 2755776 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2755776 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 2755783 DEBUG (ScheduledTrigger-15114-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3 and last live nodes: 3
[junit4] 2> 2755795 DEBUG (ScheduledTrigger-15114-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2755795 DEBUG (ScheduledTrigger-15114-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 2755802 DEBUG (ScheduledTrigger-15114-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3 and last live nodes: 3
[junit4] 2> 2755815 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2755815 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 2755819 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3 and last live nodes: 3
[junit4] 2> 2755833 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2755833 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 2755837 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3 and last live nodes: 3
[junit4] 2> 2755851 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2755851 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 2755858 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3 and last live nodes: 3
[junit4] 2> 2755870 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2755870 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 2755876 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3 and last live nodes: 3
[junit4] 2> 2755888 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2755888 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 2755894 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3 and last live nodes: 3
[junit4] 2> 2755906 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2755906 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 2755913 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3 and last live nodes: 3
[junit4] 2> 2755924 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2755924 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 2755931 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3 and last live nodes: 3
[junit4] 2> 2755942 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2755942 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 2755942 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger firing
registered processor for nodes: [127.0.0.1:10249_solr] added at times
[452279580623800], now=452290359598800
[junit4] 2> 2755942 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.SystemLogListener Collection .system missing, skip sending event {
[junit4] 2> "id":"19b588bbe03b8Tf5c0sizl0xhb730z14e7h6kg3",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":452279580623800,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[452279580623800],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":452290377858800,
[junit4] 2> "nodeNames":["127.0.0.1:10249_solr"]}}
[junit4] 2> 2755943 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Removing new node from tracking: 127.0.0.1:10249_solr
[junit4] 2> 2755943 DEBUG
(TEST-TestSimTriggerIntegration.testEventFromRestoredState-seed#[F41D2D6F85AD480A])
[ ] o.a.s.c.a.s.SimClusterStateProvider -- creating marker:
/autoscaling/nodeAdded/127.0.0.1:10250_solr
[junit4] 2> 2755944 DEBUG (AutoscalingActionExecutor-15115-thread-1) [
] o.a.s.c.a.SystemLogListener Collection .system missing, skip sending event {
[junit4] 2> "id":"19b588bbe03b8Tf5c0sizl0xhb730z14e7h6kg3",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":452279580623800,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[452279580623800],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":452290377858800,
[junit4] 2> "nodeNames":["127.0.0.1:10249_solr"]}}
[junit4] 2> 2756032 DEBUG (ScheduledTrigger-15114-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2756032 DEBUG (ScheduledTrigger-15114-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 4
[junit4] 2> 2756032 DEBUG (ScheduledTrigger-15114-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:10250_solr at time
452295550408800
[junit4] 2> 2756032 DEBUG (ScheduledTrigger-15114-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 4 and last live nodes: 3
[junit4] 2> 2756049 DEBUG (ScheduledTrigger-15114-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2756049 DEBUG (ScheduledTrigger-15114-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 4
[junit4] 2> 2756049 DEBUG (ScheduledTrigger-15114-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 4 and last live nodes: 4
[junit4] 2> 2756068 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 4 and last live nodes: 4
[junit4] 2> 2756068 DEBUG (ScheduledTrigger-15114-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2756068 DEBUG (ScheduledTrigger-15114-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 4
[junit4] 2> 2756086 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 4 and last live nodes: 4
[junit4] 2> 2756086 DEBUG (ScheduledTrigger-15114-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2756086 DEBUG (ScheduledTrigger-15114-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 4
[junit4] 2> 2756104 DEBUG (ScheduledTrigger-15114-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2756104 DEBUG (ScheduledTrigger-15114-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 4
[junit4] 2> 2756104 DEBUG (ScheduledTrigger-15114-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 4 and last live nodes: 4
[junit4] 2> 2756116 INFO
(TEST-TestSimTriggerIntegration.testEventFromRestoredState-seed#[F41D2D6F85AD480A])
[ ] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and
clearing object cache...
[junit4] 2> 2756116 INFO (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are
closed, exiting.
[junit4] 2> 2756117 DEBUG
(TEST-TestSimTriggerIntegration.testEventFromRestoredState-seed#[F41D2D6F85AD480A])
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed
explicitly
[junit4] 2> 2756118 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 3
[junit4] 2> 2756118 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 0
[junit4] 2> 2756118 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 2756119 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial
livenodes: [127.0.0.1:10249_solr, 127.0.0.1:10250_solr, 127.0.0.1:10247_solr,
127.0.0.1:10248_solr]
[junit4] 2> 2756119 INFO (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.s.TestSimTriggerIntegration TestTriggerAction init
[junit4] 2> 2756119 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger - Initial
livenodes: [127.0.0.1:10249_solr, 127.0.0.1:10250_solr, 127.0.0.1:10247_solr,
127.0.0.1:10248_solr]
[junit4] 2> 2756119 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger instantiated
with properties: {event=nodeAdded, waitFor=10, actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.sim.TestSimTriggerIntegration$TestTriggerAction}],
enabled=true}
[junit4] 2> 2756119 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeAddedTrigger Adding node from marker path: 127.0.0.1:10249_solr
[junit4] 2> 2756119 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeAddedTrigger Adding node from marker path: 127.0.0.1:10250_solr
[junit4] 2> 2756119 DEBUG (ScheduledTrigger-15119-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 4 and last live nodes: 4
[junit4] 2> 2756119 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- cleaning old nodeLost / nodeAdded markers
[junit4] 2> 2756119 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 2756119 DEBUG (ScheduledTrigger-15119-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2756119 DEBUG (ScheduledTrigger-15119-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 4
[junit4] 2> 2756137 DEBUG (ScheduledTrigger-15119-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 4 and last live nodes: 4
[junit4] 2> 2756137 DEBUG (ScheduledTrigger-15119-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2756137 DEBUG (ScheduledTrigger-15119-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 4
[junit4] 2> 2756154 DEBUG (ScheduledTrigger-15119-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 4 and last live nodes: 4
[junit4] 2> 2756154 DEBUG (ScheduledTrigger-15119-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2756154 DEBUG (ScheduledTrigger-15119-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 4
[junit4] 2> 2756173 DEBUG (ScheduledTrigger-15119-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 4 and last live nodes: 4
[junit4] 2> 2756173 DEBUG (ScheduledTrigger-15119-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2756174 DEBUG (ScheduledTrigger-15119-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 4
[junit4] 2> 2756193 DEBUG (ScheduledTrigger-15119-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 4 and last live nodes: 4
[junit4] 2> 2756193 DEBUG (ScheduledTrigger-15119-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2756193 DEBUG (ScheduledTrigger-15119-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 4
[junit4] 2> 2756210 DEBUG (ScheduledTrigger-15119-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 4 and last live nodes: 4
[junit4] 2> 2756210 DEBUG (ScheduledTrigger-15119-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2756210 DEBUG (ScheduledTrigger-15119-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 4
[junit4] 2> 2756210 DEBUG (ScheduledTrigger-15119-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger firing
registered processor for nodes: [127.0.0.1:10250_solr] added at times
[452295550408800], now=452305886208800
[junit4] 2> 2756211 DEBUG (ScheduledTrigger-15119-thread-1) [ ]
o.a.s.c.a.SystemLogListener Collection .system missing, skip sending event {
[junit4] 2> "id":"19b5c439d9860Tf5c0sizl0xhb730z14e7h6kg4",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":452295550408800,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[452295550408800],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":452305904358800,
[junit4] 2> "nodeNames":["127.0.0.1:10250_solr"]}}
[junit4] 2> 2756211 DEBUG (ScheduledTrigger-15119-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Removing new node from tracking: 127.0.0.1:10250_solr
[junit4] 2> 2756211 INFO (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are
closed, exiting.
[junit4] 2> 2756213 WARN (AutoscalingActionExecutor-15120-thread-1) [
] o.a.s.c.a.SystemLogListener Exception sending event. Collection: .system,
event: {
[junit4] 2> "id":"19b5c439d9860Tf5c0sizl0xhb730z14e7h6kg4",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":452295550408800,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[452295550408800],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":452305904358800,
[junit4] 2> "nodeNames":["127.0.0.1:10250_solr"]}}, exception:
java.io.IOException: already closed
[junit4] 2> 2756213 WARN (AutoscalingActionExecutor-15120-thread-1) [
] o.a.s.c.a.ScheduledTriggers Unhandled exception executing actions
[junit4] 2> org.apache.solr.common.AlreadyClosedException:
ScheduledTrigger node_added_trigger has been closed.
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ScheduledTriggers$TriggerWrapper.dequeue(ScheduledTriggers.java:583)
~[java/:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$add$3(ScheduledTriggers.java:334)
[java/:?]
[junit4] 2> at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]
[junit4] 2> at
java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
[java/:?]
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
[?:?]
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
[?:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:835) [?:?]
[junit4] 2> 2756213 DEBUG
(TEST-TestSimTriggerIntegration.testEventFromRestoredState-seed#[F41D2D6F85AD480A])
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed
explicitly
[junit4] 2> 2756214 INFO
(TEST-TestSimTriggerIntegration.testEventFromRestoredState-seed#[F41D2D6F85AD480A])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testEventFromRestoredState
[junit4] 2> 2756216 INFO
(TEST-TestSimTriggerIntegration.testTriggerThrottling-seed#[F41D2D6F85AD480A])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testTriggerThrottling
[junit4] 2> 2756268 DEBUG
(TEST-TestSimTriggerIntegration.testTriggerThrottling-seed#[F41D2D6F85AD480A])
[ ] o.a.s.c.a.s.SimClusterStateProvider --- new Overseer leader:
127.0.0.1:10251_solr
[junit4] 2> 2756269 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 2756269 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 1
[junit4] 2> 2756269 DEBUG
(TEST-TestSimTriggerIntegration.testTriggerThrottling-seed#[F41D2D6F85AD480A])
[ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=0
[junit4] 2> 2756269 DEBUG
(TEST-TestSimTriggerIntegration.testTriggerThrottling-seed#[F41D2D6F85AD480A])
[ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 0
[junit4] 2> 2756269 INFO
(TEST-TestSimTriggerIntegration.testTriggerThrottling-seed#[F41D2D6F85AD480A])
[ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics
history in memory.
[junit4] 2> 2756269 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 2756269 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 2756269 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial
livenodes: [127.0.0.1:10251_solr, 127.0.0.1:10252_solr]
[junit4] 2> 2756269 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- cleaning old nodeLost / nodeAdded markers
[junit4] 2> 2756269 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 2756270 DEBUG (ScheduledTrigger-15123-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 2756271 DEBUG (simCloudManagerPool-15122-thread-2) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2756271 DEBUG (simCloudManagerPool-15122-thread-2) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
2
[junit4] 2> 2756272 DEBUG (simCloudManagerPool-15122-thread-3) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2756272 DEBUG (simCloudManagerPool-15122-thread-3) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
3
[junit4] 2> 2756272 DEBUG (simCloudManagerPool-15122-thread-4) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2756272 DEBUG (simCloudManagerPool-15122-thread-4) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
4
[junit4] 2> 2756276 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4
[junit4] 2> 2756293 INFO (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.s.TestSimTriggerIntegration TestTriggerAction init
[junit4] 2> 2756293 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger2 - Initial
livenodes: [127.0.0.1:10251_solr, 127.0.0.1:10252_solr]
[junit4] 2> 2756293 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] 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.TestSimTriggerIntegration$ThrottlingTesterAction}],
enabled=true}
[junit4] 2> 2756293 INFO (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.s.TestSimTriggerIntegration TestTriggerAction init
[junit4] 2> 2756293 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 - Initial
livenodes: [127.0.0.1:10251_solr, 127.0.0.1:10252_solr]
[junit4] 2> 2756293 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] 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.TestSimTriggerIntegration$ThrottlingTesterAction}],
enabled=true}
[junit4] 2> 2756293 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- cleaning old nodeLost / nodeAdded markers
[junit4] 2> 2756293 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion 4
[junit4] 2> 2756294 DEBUG
(TEST-TestSimTriggerIntegration.testTriggerThrottling-seed#[F41D2D6F85AD480A])
[ ] o.a.s.c.a.s.SimClusterStateProvider -- creating marker:
/autoscaling/nodeAdded/127.0.0.1:10253_solr
[junit4] 2> 2756295 DEBUG (ScheduledTrigger-15123-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3 and last live nodes: 2
[junit4] 2> 2756295 DEBUG (ScheduledTrigger-15123-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2
[junit4] 2> 2756295 DEBUG (ScheduledTrigger-15123-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 2756295 DEBUG (ScheduledTrigger-15123-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:10253_solr at time
452310758268800
[junit4] 2> 2756295 DEBUG (ScheduledTrigger-15123-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger2 firing
registered processor for nodes: [127.0.0.1:10253_solr] added at times
[452310758268800], now=452310759443800
[junit4] 2> 2756295 DEBUG (ScheduledTrigger-15123-thread-1) [ ]
o.a.s.c.a.SystemLogListener Collection .system missing, skip sending event {
[junit4] 2> "id":"19b5fce131f80Tf5c0sizl0xhb730z14e7h6kg6",
[junit4] 2> "source":"node_added_trigger2",
[junit4] 2> "eventTime":452310758268800,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[452310758268800],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":452310769833800,
[junit4] 2> "nodeNames":["127.0.0.1:10253_solr"]}}
[junit4] 2> 2756295 DEBUG (ScheduledTrigger-15123-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Removing new node from tracking: 127.0.0.1:10253_solr
[junit4] 2> 2756295 INFO (AutoscalingActionExecutor-15124-thread-1) [
] o.a.s.c.a.s.TestSimTriggerIntegration action executed from node_added_trigger2
[junit4] 2> 2756295 DEBUG (AutoscalingActionExecutor-15124-thread-1) [
] o.a.s.c.a.SystemLogListener Collection .system missing, skip sending event {
[junit4] 2> "id":"19b5fce131f80Tf5c0sizl0xhb730z14e7h6kg6",
[junit4] 2> "source":"node_added_trigger2",
[junit4] 2> "eventTime":452310758268800,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[452310758268800],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":452310769833800,
[junit4] 2> "nodeNames":["127.0.0.1:10253_solr"]}}
[junit4] 2> 2756388 DEBUG (ScheduledTrigger-15123-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3 and last live nodes: 3
[junit4] 2> 2756388 DEBUG (ScheduledTrigger-15123-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2
[junit4] 2> 2756388 DEBUG (ScheduledTrigger-15123-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 2756388 DEBUG (ScheduledTrigger-15123-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 2756388 DEBUG (ScheduledTrigger-15123-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 2756388 DEBUG (ScheduledTrigger-15123-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:10253_solr at time
452316184553800
[junit4] 2> 2756388 DEBUG (ScheduledTrigger-15123-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 firing
registered processor for nodes: [127.0.0.1:10253_solr] added at times
[452316184553800], now=452316185843800
[junit4] 2> 2756388 DEBUG (ScheduledTrigger-15123-thread-4) [ ]
o.a.s.c.a.SystemLogListener Collection .system missing, skip sending event {
[junit4] 2> "id":"19b611181a948Tf5c0sizl0xhb730z14e7h6kg7",
[junit4] 2> "source":"node_added_trigger1",
[junit4] 2> "eventTime":452316184553800,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[452316184553800],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":452316193268800,
[junit4] 2> "nodeNames":["127.0.0.1:10253_solr"]}}
[junit4] 2> 2756388 DEBUG (ScheduledTrigger-15123-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Removing new node from tracking: 127.0.0.1:10253_solr
[junit4] 2> 2756390 INFO (AutoscalingActionExecutor-15124-thread-1) [
] o.a.s.c.a.s.TestSimTriggerIntegration last action at 452310812353800 time =
452316256748800
[junit4] 2> 2756390 INFO (AutoscalingActionExecutor-15124-thread-1) [
] o.a.s.c.a.s.TestSimTriggerIntegration action executed from node_added_trigger1
[junit4] 2> 2756390 DEBUG (AutoscalingActionExecutor-15124-thread-1) [
] o.a.s.c.a.SystemLogListener Collection .system missing, skip sending event {
[junit4] 2> "id":"19b611181a948Tf5c0sizl0xhb730z14e7h6kg7",
[junit4] 2> "source":"node_added_trigger1",
[junit4] 2> "eventTime":452316184553800,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[452316184553800],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":452316193268800,
[junit4] 2> "nodeNames":["127.0.0.1:10253_solr"]}}
[junit4] 2> 2756394 DEBUG (simCloudManagerPool-15122-thread-5) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2756394 DEBUG (simCloudManagerPool-15122-thread-5) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
5
[junit4] 2> 2756394 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 5
[junit4] 2> 2756394 INFO (Simulated OverseerAutoScalingTriggerT
[...truncated too long message...]
r1
[junit4] 2> 20127 DEBUG (ScheduledTrigger-155-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 20127 DEBUG (AutoscalingActionExecutor-156-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"15a4e654a5bf0Tf5c0sizl0xhb730z14e7h6c8i",
[junit4] 2> "source":"node_added_trigger1",
[junit4] 2> "eventTime":380767730031600,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[380767730031600],
[junit4] 2> "_dequeue_time_":380769017876600,
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":380768779996600,
[junit4] 2> "nodeNames":["127.0.0.1:10069_solr"],
[junit4] 2> "replaying":true}}
[junit4] 2> 20127 INFO (AutoscalingActionExecutor-156-thread-1) [ ]
o.a.s.c.a.s.TestSimTriggerIntegration -- event: {
[junit4] 2> "id":"15a4e654a5bf0Tf5c0sizl0xhb730z14e7h6c8i",
[junit4] 2> "source":"node_added_trigger1",
[junit4] 2> "eventTime":380767730031600,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[380767730031600],
[junit4] 2> "_dequeue_time_":380769017876600,
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":380768779996600,
[junit4] 2> "nodeNames":["127.0.0.1:10069_solr"],
[junit4] 2> "replaying":true}}
[junit4] 2> 20128 DEBUG (AutoscalingActionExecutor-156-thread-1) [ ]
o.a.s.c.a.SystemLogListener Collection .system missing, skip sending event {
[junit4] 2> "id":"15a4e654a5bf0Tf5c0sizl0xhb730z14e7h6c8i",
[junit4] 2> "source":"node_added_trigger1",
[junit4] 2> "eventTime":380767730031600,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[380767730031600],
[junit4] 2> "_dequeue_time_":380769017876600,
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":380768779996600,
[junit4] 2> "nodeNames":["127.0.0.1:10069_solr"],
[junit4] 2> "replaying":true}}
[junit4] 2> 20128 DEBUG (AutoscalingActionExecutor-156-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Resuming trigger: .auto_add_replicas after 100ms
[junit4] 2> 20128 DEBUG (AutoscalingActionExecutor-156-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger1 after 100ms
[junit4] 2> 20128 DEBUG (AutoscalingActionExecutor-156-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -- processing took 41 ms for event
id=15a4e654a5bf0Tf5c0sizl0xhb730z14e7h6c8i
[junit4] 2> 20225 DEBUG (ScheduledTrigger-155-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 20225 DEBUG (ScheduledTrigger-155-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 20225 DEBUG (ScheduledTrigger-155-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 20245 DEBUG (ScheduledTrigger-155-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 20245 DEBUG (ScheduledTrigger-155-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 20245 DEBUG (ScheduledTrigger-155-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 20266 DEBUG (ScheduledTrigger-155-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 20266 DEBUG (ScheduledTrigger-155-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 20266 DEBUG (ScheduledTrigger-155-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 20280 DEBUG
(TEST-TestSimTriggerIntegration.testEventQueue-seed#[F41D2D6F85AD480A]) [ ]
o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 20280 INFO (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are
closed, exiting.
[junit4] 2> 20280 DEBUG
(TEST-TestSimTriggerIntegration.testEventQueue-seed#[F41D2D6F85AD480A]) [ ]
o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 20281 DEBUG
(TEST-TestSimTriggerIntegration.testEventQueue-seed#[F41D2D6F85AD480A]) [ ]
o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 20281 DEBUG
(TEST-TestSimTriggerIntegration.testEventQueue-seed#[F41D2D6F85AD480A]) [ ]
o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool
executor
[junit4] 2> 20281 DEBUG
(TEST-TestSimTriggerIntegration.testEventQueue-seed#[F41D2D6F85AD480A]) [ ]
o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 20281 DEBUG
(TEST-TestSimTriggerIntegration.testEventQueue-seed#[F41D2D6F85AD480A]) [ ]
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 20281 INFO
(TEST-TestSimTriggerIntegration.testEventQueue-seed#[F41D2D6F85AD480A]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testEventQueue
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=TestSimTriggerIntegration -Dtests.method=testEventQueue
-Dtests.seed=F41D2D6F85AD480A -Dtests.slow=true -Dtests.badapples=true
-Dtests.locale=en-LC -Dtests.timezone=America/Fortaleza -Dtests.asserts=true
-Dtests.file.encoding=US-ASCII
[junit4] FAILURE 0.26s J0 | TestSimTriggerIntegration.testEventQueue <<<
[junit4] > Throwable #1: java.lang.AssertionError: action wasn't
interrupted
[junit4] > at
__randomizedtesting.SeedInfo.seed([F41D2D6F85AD480A:3DA86FC18CCA8EFF]:0)
[junit4] > at
org.apache.solr.cloud.autoscaling.sim.TestSimTriggerIntegration.testEventQueue(TestSimTriggerIntegration.java:717)
[junit4] > at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at
java.base/java.lang.reflect.Method.invoke(Method.java:567)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:835)
[junit4] 2> NOTE: leaving temporary files on disk at:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.sim.TestSimTriggerIntegration_F41D2D6F85AD480A-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): {},
docValues:{}, maxPointsInLeafNode=299, maxMBSortInHeap=5.970933512716436,
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@1491e825),
locale=en-LC, timezone=America/Fortaleza
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 12-ea
(64-bit)/cpus=3,threads=1,free=98853408,total=159907840
[junit4] 2> NOTE: All tests run in this JVM: [TestSimTriggerIntegration,
TestSimTriggerIntegration]
[junit4] Completed [3/5 (1!)] on J0 in 2.65s, 11 tests, 1 failure <<<
FAILURES!
[...truncated 38 lines...]
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.xml:1572:
The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.xml:1099:
There were test failures: 5 suites, 55 tests, 1 failure [seed:
F41D2D6F85AD480A]
Total time: 26 seconds
[repro] Setting last failure code to 1
[repro] Failures:
[repro] 1/5 failed:
org.apache.solr.cloud.autoscaling.sim.TestSimTriggerIntegration
[repro] Exiting with code 1
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST lucene\build
move /y lucene\build lucene\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST solr\build move
/y solr\build solr\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST
lucene\build.orig move /y lucene\build.orig lucene\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST solr\build.orig
move /y solr\build.orig solr\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>exit 0
Archiving artifacts
Setting
ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting
ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting
ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all
warnings.
<Git Blamer> GIT_COMMIT=8762b071bb04b2e391749e6a064966ecfe932862,
workspace=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows
[WARNINGS] Computing warning deltas based on reference build #7693
Recording test results
Setting
ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting
ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting
ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting
ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting
ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
