Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/21618/
Java: 32bit/jdk1.8.0_162 -server -XX:+UseSerialGC
3 tests failed.
FAILED:
org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest.testInactiveShardCleanup
Error Message:
should be at least one inactive event
Stack Trace:
java.lang.AssertionError: should be at least one inactive event
at
__randomizedtesting.SeedInfo.seed([848FFD53EE04915F:99A33D218F47B654]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at
org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest.testInactiveShardCleanup(ScheduledMaintenanceTriggerTest.java:218)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED:
org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest.testInactiveShardCleanup
Error Message:
should be at least one inactive event
Stack Trace:
java.lang.AssertionError: should be at least one inactive event
at
__randomizedtesting.SeedInfo.seed([848FFD53EE04915F:99A33D218F47B654]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at
org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest.testInactiveShardCleanup(ScheduledMaintenanceTriggerTest.java:218)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED:
org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest.testInactiveShardCleanup
Error Message:
cleanup action didn't run
Stack Trace:
java.lang.AssertionError: cleanup action didn't run
at
__randomizedtesting.SeedInfo.seed([848FFD53EE04915F:99A33D218F47B654]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at
org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest.testInactiveShardCleanup(ScheduledMaintenanceTriggerTest.java:197)
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 13056 lines...]
[junit4] Suite:
org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest
[junit4] 2> 750016 INFO
(SUITE-ScheduledMaintenanceTriggerTest-seed#[848FFD53EE04915F]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest_848FFD53EE04915F-001/init-core-data-001
[junit4] 2> 750016 WARN
(SUITE-ScheduledMaintenanceTriggerTest-seed#[848FFD53EE04915F]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 750016 INFO
(SUITE-ScheduledMaintenanceTriggerTest-seed#[848FFD53EE04915F]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 750017 INFO
(SUITE-ScheduledMaintenanceTriggerTest-seed#[848FFD53EE04915F]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 750018 INFO
(SUITE-ScheduledMaintenanceTriggerTest-seed#[848FFD53EE04915F]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest_848FFD53EE04915F-001/tempDir-001
[junit4] 2> 750018 INFO
(SUITE-ScheduledMaintenanceTriggerTest-seed#[848FFD53EE04915F]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 750018 INFO (Thread-1907) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 750018 INFO (Thread-1907) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 750019 ERROR (Thread-1907) [ ] o.a.z.s.ZooKeeperServer
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action
on ERROR or SHUTDOWN server state changes
[junit4] 2> 750118 INFO
(SUITE-ScheduledMaintenanceTriggerTest-seed#[848FFD53EE04915F]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:36177
[junit4] 2> 750120 INFO (zkConnectionManagerCallback-6008-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 750122 INFO (jetty-launcher-6005-thread-1) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-22T00:27:37+03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 750138 INFO (jetty-launcher-6005-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 750138 INFO (jetty-launcher-6005-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 750138 INFO (jetty-launcher-6005-thread-1) [ ]
o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 750138 INFO (jetty-launcher-6005-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@5ebbfd{/solr,null,AVAILABLE}
[junit4] 2> 750139 INFO (jetty-launcher-6005-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@1b2bd9e{SSL,[ssl,
http/1.1]}{127.0.0.1:33349}
[junit4] 2> 750139 INFO (jetty-launcher-6005-thread-1) [ ]
o.e.j.s.Server Started @751477ms
[junit4] 2> 750139 INFO (jetty-launcher-6005-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=33349}
[junit4] 2> 750139 ERROR (jetty-launcher-6005-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 750139 INFO (jetty-launcher-6005-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 750139 INFO (jetty-launcher-6005-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 750139 INFO (jetty-launcher-6005-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 750139 INFO (jetty-launcher-6005-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-03-11T15:12:48.814Z
[junit4] 2> 750162 INFO (zkConnectionManagerCallback-6010-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 750163 INFO (jetty-launcher-6005-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 750172 INFO (jetty-launcher-6005-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36177/solr
[junit4] 2> 750185 INFO (zkConnectionManagerCallback-6014-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 750187 INFO
(zkConnectionManagerCallback-6016-thread-1-processing-n:127.0.0.1:33349_solr)
[n:127.0.0.1:33349_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 750214 INFO (jetty-launcher-6005-thread-1)
[n:127.0.0.1:33349_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 750214 INFO (jetty-launcher-6005-thread-1)
[n:127.0.0.1:33349_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:33349_solr
[junit4] 2> 750215 INFO (jetty-launcher-6005-thread-1)
[n:127.0.0.1:33349_solr ] o.a.s.c.Overseer Overseer
(id=72117806927970307-127.0.0.1:33349_solr-n_0000000000) starting
[junit4] 2> 750217 INFO
(zkConnectionManagerCallback-6021-thread-1-processing-n:127.0.0.1:33349_solr)
[n:127.0.0.1:33349_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 750218 INFO (jetty-launcher-6005-thread-1)
[n:127.0.0.1:33349_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:36177/solr ready
[junit4] 2> 750218 DEBUG
(OverseerAutoScalingTriggerThread-72117806927970307-127.0.0.1:33349_solr-n_0000000000)
[n:127.0.0.1:33349_solr ] o.a.s.c.a.OverseerTriggerThread Adding
.auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 750219 INFO (jetty-launcher-6005-thread-1)
[n:127.0.0.1:33349_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:33349_solr
[junit4] 2> 750219 DEBUG
(OverseerAutoScalingTriggerThread-72117806927970307-127.0.0.1:33349_solr-n_0000000000)
[n:127.0.0.1:33349_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 1
[junit4] 2> 750219 INFO
(zkCallback-6020-thread-1-processing-n:127.0.0.1:33349_solr)
[n:127.0.0.1:33349_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 750219 INFO
(zkCallback-6015-thread-1-processing-n:127.0.0.1:33349_solr)
[n:127.0.0.1:33349_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 750219 DEBUG
(OverseerAutoScalingTriggerThread-72117806927970307-127.0.0.1:33349_solr-n_0000000000)
[n:127.0.0.1:33349_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:33349_solr]
[junit4] 2> 750219 DEBUG
(OverseerAutoScalingTriggerThread-72117806927970307-127.0.0.1:33349_solr-n_0000000000)
[n:127.0.0.1:33349_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 750219 DEBUG
(OverseerAutoScalingTriggerThread-72117806927970307-127.0.0.1:33349_solr-n_0000000000)
[n:127.0.0.1:33349_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 1
[junit4] 2> 750221 DEBUG
(OverseerAutoScalingTriggerThread-72117806927970307-127.0.0.1:33349_solr-n_0000000000)
[n:127.0.0.1:33349_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 750221 DEBUG
(OverseerAutoScalingTriggerThread-72117806927970307-127.0.0.1:33349_solr-n_0000000000)
[n:127.0.0.1:33349_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 750221 DEBUG (ScheduledTrigger-3732-thread-1)
[n:127.0.0.1:33349_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 1
[junit4] 2> 750288 INFO (jetty-launcher-6005-thread-1)
[n:127.0.0.1:33349_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33349.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1709d6f
[junit4] 2> 750293 INFO (jetty-launcher-6005-thread-1)
[n:127.0.0.1:33349_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33349.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1709d6f
[junit4] 2> 750294 INFO (jetty-launcher-6005-thread-1)
[n:127.0.0.1:33349_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33349.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1709d6f
[junit4] 2> 750295 INFO (jetty-launcher-6005-thread-1)
[n:127.0.0.1:33349_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest_848FFD53EE04915F-001/tempDir-001/node1/.
[junit4] 2> 750306 INFO (zkConnectionManagerCallback-6023-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 750309 INFO (zkConnectionManagerCallback-6027-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 750310 INFO
(SUITE-ScheduledMaintenanceTriggerTest-seed#[848FFD53EE04915F]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 750310 INFO
(SUITE-ScheduledMaintenanceTriggerTest-seed#[848FFD53EE04915F]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36177/solr ready
[junit4] 2> 750314 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 750314 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 0
[junit4] 2> 750314 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10003_solr]
[junit4] 2> 750314 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion -1
[junit4] 2> 750314 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 0
[junit4] 2> 750322 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 750329 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion 0
[junit4] 2> 750330 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750335 INFO
(TEST-ScheduledMaintenanceTriggerTest.testTriggerDefaults-seed#[848FFD53EE04915F])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testTriggerDefaults
[junit4] 2> 750335 INFO
(TEST-ScheduledMaintenanceTriggerTest.testTriggerDefaults-seed#[848FFD53EE04915F])
[ ] o.a.s.c.a.ScheduledMaintenanceTriggerTest {
[junit4] 2> "cluster-preferences":[
[junit4] 2> {
[junit4] 2> "minimize":"cores",
[junit4] 2> "precision":1},
[junit4] 2> {"maximize":"freedisk"}],
[junit4] 2> "triggers":{
[junit4] 2> ".scheduled_maintenance":{
[junit4] 2> "name":".scheduled_maintenance",
[junit4] 2> "startTime":"NOW",
[junit4] 2> "event":"scheduled",
[junit4] 2> "every":"+1DAY",
[junit4] 2> "actions":[
[junit4] 2> {
[junit4] 2> "name":"inactive_shard_plan",
[junit4] 2> "class":"solr.InactiveShardPlanAction"},
[junit4] 2> {
[junit4] 2> "name":"execute_plan",
[junit4] 2> "class":"solr.ExecutePlanAction"}],
[junit4] 2> "enabled":true},
[junit4] 2> ".auto_add_replicas":{
[junit4] 2> "name":".auto_add_replicas",
[junit4] 2> "event":"nodeLost",
[junit4] 2> "waitFor":120,
[junit4] 2> "actions":[
[junit4] 2> {
[junit4] 2> "name":"auto_add_replicas_plan",
[junit4] 2> "class":"solr.AutoAddReplicasPlanAction"},
[junit4] 2> {
[junit4] 2> "name":"execute_plan",
[junit4] 2> "class":"solr.ExecutePlanAction"}],
[junit4] 2> "enabled":true}},
[junit4] 2> "listeners":{
[junit4] 2> ".scheduled_maintenance.system":{
[junit4] 2> "trigger":".scheduled_maintenance",
[junit4] 2> "afterAction":[],
[junit4] 2> "stage":[
[junit4] 2> "STARTED",
[junit4] 2> "ABORTED",
[junit4] 2> "SUCCEEDED",
[junit4] 2> "FAILED",
[junit4] 2> "BEFORE_ACTION",
[junit4] 2> "AFTER_ACTION",
[junit4] 2> "IGNORED"],
[junit4] 2>
"class":"org.apache.solr.cloud.autoscaling.SystemLogListener",
[junit4] 2> "beforeAction":[]},
[junit4] 2> ".auto_add_replicas.system":{
[junit4] 2> "trigger":".auto_add_replicas",
[junit4] 2> "afterAction":[],
[junit4] 2> "stage":[
[junit4] 2> "STARTED",
[junit4] 2> "ABORTED",
[junit4] 2> "SUCCEEDED",
[junit4] 2> "FAILED",
[junit4] 2> "BEFORE_ACTION",
[junit4] 2> "AFTER_ACTION",
[junit4] 2> "IGNORED"],
[junit4] 2>
"class":"org.apache.solr.cloud.autoscaling.SystemLogListener",
[junit4] 2> "beforeAction":[]}},
[junit4] 2> "properties":{}}
[junit4] 2> 750336 DEBUG (simCloudManagerPool-3738-thread-1) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 750336 DEBUG (simCloudManagerPool-3738-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
1
[junit4] 2> 750336 DEBUG (simCloudManagerPool-3738-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10003_solr]
[junit4] 2> 750336 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 750337 INFO
(TEST-ScheduledMaintenanceTriggerTest.testTriggerDefaults-seed#[848FFD53EE04915F])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testTriggerDefaults
[junit4] 2> 750337 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 750337 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 750343 INFO
(TEST-ScheduledMaintenanceTriggerTest.testInactiveShardCleanup-seed#[848FFD53EE04915F])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testInactiveShardCleanup
[junit4] 2> 750350 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750371 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750391 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750411 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750431 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750451 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750471 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750488 DEBUG (simCloudManagerPool-3738-thread-5) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for
ScheduledMaintenanceTriggerTest_collection1 / shard1: {"core_node1":{
[junit4] 2> "node_name":"127.0.0.1:10003_solr",
[junit4] 2>
"core":"ScheduledMaintenanceTriggerTest_collection1_shard1_replica_n1",
[junit4] 2> "leader":"true",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2> "shard":"shard1",
[junit4] 2>
"collection":"ScheduledMaintenanceTriggerTest_collection1"}}
[junit4] 2> 750489 DEBUG (simCloudManagerPool-3738-thread-6) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for
ScheduledMaintenanceTriggerTest_collection1 / shard1_1: {"core_node5":{
[junit4] 2> "node_name":"127.0.0.1:10003_solr",
[junit4] 2> "base_url":"http://127.0.0.1:10003/solr";,
[junit4] 2> "leader":"true",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2>
"core":"ScheduledMaintenanceTriggerTest_collection1_shard1_1_replica0",
[junit4] 2> "shard":"shard1_1",
[junit4] 2>
"collection":"ScheduledMaintenanceTriggerTest_collection1"}}
[junit4] 2> 750489 DEBUG (simCloudManagerPool-3738-thread-7) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for
ScheduledMaintenanceTriggerTest_collection1 / shard1_0: {"core_node4":{
[junit4] 2> "node_name":"127.0.0.1:10003_solr",
[junit4] 2> "base_url":"http://127.0.0.1:10003/solr";,
[junit4] 2> "leader":"true",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2>
"core":"ScheduledMaintenanceTriggerTest_collection1_shard1_0_replica0",
[junit4] 2> "shard":"shard1_0",
[junit4] 2>
"collection":"ScheduledMaintenanceTriggerTest_collection1"}}
[junit4] 2> 750492 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750493 DEBUG (simCloudManagerPool-3738-thread-8) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 750493 DEBUG (simCloudManagerPool-3738-thread-8) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
2
[junit4] 2> 750493 DEBUG (simCloudManagerPool-3738-thread-8) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10003_solr]
[junit4] 2> 750493 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 750494 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 750494 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 750494 DEBUG (simCloudManagerPool-3738-thread-9) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 750495 DEBUG (simCloudManagerPool-3738-thread-9) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
3
[junit4] 2> 750495 DEBUG (simCloudManagerPool-3738-thread-9) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10003_solr]
[junit4] 2> 750495 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 750495 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 750495 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 750495 WARN (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:12:50.989Z. Now: 2018-03-11T22:46:06.983Z
[junit4] 2> 750496 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"162159f192dTb3h5wtmur7qwcqxgaw08u9fta",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781170989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808366983},
[junit4] 2> "ignored":true}
[junit4] 2> 750512 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750517 WARN (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:12:52.989Z. Now: 2018-03-11T22:46:08.050Z
[junit4] 2> 750517 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"162159f20fdTb3h5wtmur7qwcqxgaw08u9ftc",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781172989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808368050},
[junit4] 2> "ignored":true}
[junit4] 2> 750532 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750537 WARN (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:12:54.989Z. Now: 2018-03-11T22:46:09.081Z
[junit4] 2> 750537 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"162159f28cdTb3h5wtmur7qwcqxgaw08u9fte",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781174989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808369081},
[junit4] 2> "ignored":true}
[junit4] 2> 750552 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750558 WARN (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:12:56.989Z. Now: 2018-03-11T22:46:10.110Z
[junit4] 2> 750558 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"162159f309dTb3h5wtmur7qwcqxgaw08u9ftg",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781176989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808370110},
[junit4] 2> "ignored":true}
[junit4] 2> 750572 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750587 WARN (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:12:58.989Z. Now: 2018-03-11T22:46:11.546Z
[junit4] 2> 750587 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"162159f386dTb3h5wtmur7qwcqxgaw08u9fti",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781178989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808371546},
[junit4] 2> "ignored":true}
[junit4] 2> 750593 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750607 WARN (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:13:00.989Z. Now: 2018-03-11T22:46:12.582Z
[junit4] 2> 750607 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"162159f403dTb3h5wtmur7qwcqxgaw08u9ftk",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781180989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808372582},
[junit4] 2> "ignored":true}
[junit4] 2> 750613 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750628 WARN (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:13:02.989Z. Now: 2018-03-11T22:46:13.611Z
[junit4] 2> 750628 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"162159f480dTb3h5wtmur7qwcqxgaw08u9ftm",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781182989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808373611},
[junit4] 2> "ignored":true}
[junit4] 2> 750633 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750648 WARN (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:13:04.989Z. Now: 2018-03-11T22:46:14.640Z
[junit4] 2> 750649 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"162159f4fddTb3h5wtmur7qwcqxgaw08u9fto",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781184989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808374640},
[junit4] 2> "ignored":true}
[junit4] 2> 750653 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750669 WARN (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:13:06.989Z. Now: 2018-03-11T22:46:15.671Z
[junit4] 2> 750669 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"162159f57adTb3h5wtmur7qwcqxgaw08u9ftq",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781186989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808375671},
[junit4] 2> "ignored":true}
[junit4] 2> 750673 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750690 WARN (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:13:08.989Z. Now: 2018-03-11T22:46:16.698Z
[junit4] 2> 750690 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"162159f5f7dTb3h5wtmur7qwcqxgaw08u9fts",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781188989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808376698},
[junit4] 2> "ignored":true}
[junit4] 2> 750694 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750710 WARN (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:13:10.989Z. Now: 2018-03-11T22:46:17.727Z
[junit4] 2> 750710 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"162159f674dTb3h5wtmur7qwcqxgaw08u9ftu",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781190989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808377727},
[junit4] 2> "ignored":true}
[junit4] 2> 750714 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750731 WARN (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:13:12.989Z. Now: 2018-03-11T22:46:18.755Z
[junit4] 2> 750731 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"162159f6f1dTb3h5wtmur7qwcqxgaw08u9ftw",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781192989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808378755},
[junit4] 2> "ignored":true}
[junit4] 2> 750734 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750751 WARN (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:13:14.989Z. Now: 2018-03-11T22:46:19.783Z
[junit4] 2> 750751 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"162159f76edTb3h5wtmur7qwcqxgaw08u9fty",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781194989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808379783},
[junit4] 2> "ignored":true}
[junit4] 2> 750754 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750772 WARN (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:13:16.989Z. Now: 2018-03-11T22:46:20.817Z
[junit4] 2> 750772 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"162159f7ebdTb3h5wtmur7qwcqxgaw08u9fu0",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781196989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808380817},
[junit4] 2> "ignored":true}
[junit4] 2> 750774 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750793 WARN (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:13:18.989Z. Now: 2018-03-11T22:46:21.847Z
[junit4] 2> 750793 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"162159f868dTb3h5wtmur7qwcqxgaw08u9fu2",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781198989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808381847},
[junit4] 2> "ignored":true}
[junit4] 2> 750794 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750813 WARN (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:13:20.989Z. Now: 2018-03-11T22:46:22.873Z
[junit4] 2> 750813 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"162159f8e5dTb3h5wtmur7qwcqxgaw08u9fu4",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781200989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808382873},
[junit4] 2> "ignored":true}
[junit4] 2> 750814 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750834 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750834 WARN (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:13:22.989Z. Now: 2018-03-11T22:46:23.943Z
[junit4] 2> 750835 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"162159f962dTb3h5wtmur7qwcqxgaw08u9fu6",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781202989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808383943},
[junit4] 2> "ignored":true}
[junit4] 2> 750855 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750855 WARN (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:13:24.989Z. Now: 2018-03-11T22:46:24.969Z
[junit4] 2> 750855 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"162159f9dfdTb3h5wtmur7qwcqxgaw08u9fu8",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781204989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808384969},
[junit4] 2> "ignored":true}
[junit4] 2> 750875 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750875 WARN (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:13:26.989Z. Now: 2018-03-11T22:46:25.994Z
[junit4] 2> 750876 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"162159fa5cdTb3h5wtmur7qwcqxgaw08u9fua",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781206989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808385994},
[junit4] 2> "ignored":true}
[junit4] 2> 750895 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750896 WARN (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:13:28.989Z. Now: 2018-03-11T22:46:27.020Z
[junit4] 2> 750896 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"162159fad9dTb3h5wtmur7qwcqxgaw08u9fuc",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781208989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808387020},
[junit4] 2> "ignored":true}
[junit4] 2> 750915 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750916 WARN (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:13:30.989Z. Now: 2018-03-11T22:46:28.045Z
[junit4] 2> 750917 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"162159fb56dTb3h5wtmur7qwcqxgaw08u9fue",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781210989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808388045},
[junit4] 2> "ignored":true}
[junit4] 2> 750935 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750937 WARN (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:13:32.989Z. Now: 2018-03-11T22:46:29.069Z
[junit4] 2> 750937 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"162159fbd3dTb3h5wtmur7qwcqxgaw08u9fug",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781212989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808389069},
[junit4] 2> "ignored":true}
[junit4] 2> 750955 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750957 WARN (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:13:34.989Z. Now: 2018-03-11T22:46:30.093Z
[junit4] 2> 750958 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"162159fc50dTb3h5wtmur7qwcqxgaw08u9fui",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781214989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808390093},
[junit4] 2> "ignored":true}
[junit4] 2> 750976 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750978 WARN (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:13:36.989Z. Now: 2018-03-11T22:46:31.126Z
[junit4] 2> 750978 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"162159fccddTb3h5wtmur7qwcqxgaw08u9fuk",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781216989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808391126},
[junit4] 2> "ignored":true}
[junit4] 2> 750996 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 750999 WARN (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:13:38.989Z. Now: 2018-03-11T22:46:32.164Z
[junit4] 2> 750999 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"162159fd4adTb3h5wtmur7qwcqxgaw08u9fum",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781218989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808392164},
[junit4] 2> "ignored":true}
[junit4] 2> 751016 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751019 WARN (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:13:40.989Z. Now: 2018-03-11T22:46:33.192Z
[junit4] 2> 751020 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"162159fdc7dTb3h5wtmur7qwcqxgaw08u9fuo",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781220989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808393192},
[junit4] 2> "ignored":true}
[junit4] 2> 751036 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751040 WARN (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:13:42.989Z. Now: 2018-03-11T22:46:34.221Z
[junit4] 2> 751040 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"162159fe44dTb3h5wtmur7qwcqxgaw08u9fuq",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781222989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808394221},
[junit4] 2> "ignored":true}
[junit4] 2> 751056 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751061 WARN (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:13:44.989Z. Now: 2018-03-11T22:46:35.253Z
[junit4] 2> 751061 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"162159fec1dTb3h5wtmur7qwcqxgaw08u9fus",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781224989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808395253},
[junit4] 2> "ignored":true}
[junit4] 2> 751077 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751082 WARN (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:13:46.989Z. Now: 2018-03-11T22:46:36.297Z
[junit4] 2> 751082 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"162159ff3edTb3h5wtmur7qwcqxgaw08u9fuu",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781226989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808396297},
[junit4] 2> "ignored":true}
[junit4] 2> 751097 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751102 WARN (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:13:48.989Z. Now: 2018-03-11T22:46:37.335Z
[junit4] 2> 751102 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"162159ffbbdTb3h5wtmur7qwcqxgaw08u9fuw",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781228989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808397335},
[junit4] 2> "ignored":true}
[junit4] 2> 751117 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751123 WARN (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:13:50.989Z. Now: 2018-03-11T22:46:38.368Z
[junit4] 2> 751123 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a0038dTb3h5wtmur7qwcqxgaw08u9fuy",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781230989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808398368},
[junit4] 2> "ignored":true}
[junit4] 2> 751137 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751144 WARN (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:13:52.989Z. Now: 2018-03-11T22:46:39.398Z
[junit4] 2> 751144 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a00b5dTb3h5wtmur7qwcqxgaw08u9fv0",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781232989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808399398},
[junit4] 2> "ignored":true}
[junit4] 2> 751157 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751164 WARN (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:13:54.989Z. Now: 2018-03-11T22:46:40.435Z
[junit4] 2> 751165 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a0132dTb3h5wtmur7qwcqxgaw08u9fv2",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781234989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808400435},
[junit4] 2> "ignored":true}
[junit4] 2> 751178 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751185 WARN (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:13:56.989Z. Now: 2018-03-11T22:46:41.473Z
[junit4] 2> 751185 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a01afdTb3h5wtmur7qwcqxgaw08u9fv4",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781236989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808401473},
[junit4] 2> "ignored":true}
[junit4] 2> 751198 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751206 WARN (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:13:58.989Z. Now: 2018-03-11T22:46:42.510Z
[junit4] 2> 751206 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a022cdTb3h5wtmur7qwcqxgaw08u9fv6",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781238989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808402510},
[junit4] 2> "ignored":true}
[junit4] 2> 751218 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751222 DEBUG (ScheduledTrigger-3732-thread-3)
[n:127.0.0.1:33349_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 1
[junit4] 2> 751226 WARN (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:14:00.989Z. Now: 2018-03-11T22:46:43.545Z
[junit4] 2> 751227 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a02a9dTb3h5wtmur7qwcqxgaw08u9fv8",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781240989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808403545},
[junit4] 2> "ignored":true}
[junit4] 2> 751238 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751247 WARN (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:14:02.989Z. Now: 2018-03-11T22:46:44.574Z
[junit4] 2> 751247 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a0326dTb3h5wtmur7qwcqxgaw08u9fva",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781242989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808404574},
[junit4] 2> "ignored":true}
[junit4] 2> 751258 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751268 WARN (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:14:04.989Z. Now: 2018-03-11T22:46:45.613Z
[junit4] 2> 751268 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a03a3dTb3h5wtmur7qwcqxgaw08u9fvc",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781244989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808405613},
[junit4] 2> "ignored":true}
[junit4] 2> 751279 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751289 WARN (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:14:06.989Z. Now: 2018-03-11T22:46:46.648Z
[junit4] 2> 751289 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a0420dTb3h5wtmur7qwcqxgaw08u9fve",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781246989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808406648},
[junit4] 2> "ignored":true}
[junit4] 2> 751299 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751309 WARN (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:14:08.989Z. Now: 2018-03-11T22:46:47.684Z
[junit4] 2> 751309 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a049ddTb3h5wtmur7qwcqxgaw08u9fvg",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781248989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808407684},
[junit4] 2> "ignored":true}
[junit4] 2> 751319 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751330 WARN (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:14:10.989Z. Now: 2018-03-11T22:46:48.724Z
[junit4] 2> 751330 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a051adTb3h5wtmur7qwcqxgaw08u9fvi",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781250989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808408724},
[junit4] 2> "ignored":true}
[junit4] 2> 751339 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751351 WARN (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:14:12.989Z. Now: 2018-03-11T22:46:49.765Z
[junit4] 2> 751351 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a0597dTb3h5wtmur7qwcqxgaw08u9fvk",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781252989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808409765},
[junit4] 2> "ignored":true}
[junit4] 2> 751359 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751372 WARN (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:14:14.989Z. Now: 2018-03-11T22:46:50.801Z
[junit4] 2> 751372 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a0614dTb3h5wtmur7qwcqxgaw08u9fvm",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781254989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808410801},
[junit4] 2> "ignored":true}
[junit4] 2> 751380 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751392 WARN (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:14:16.989Z. Now: 2018-03-11T22:46:51.838Z
[junit4] 2> 751393 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a0691dTb3h5wtmur7qwcqxgaw08u9fvo",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781256989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808411838},
[junit4] 2> "ignored":true}
[junit4] 2> 751400 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751413 WARN (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:14:18.989Z. Now: 2018-03-11T22:46:52.875Z
[junit4] 2> 751413 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a070edTb3h5wtmur7qwcqxgaw08u9fvq",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781258989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808412875},
[junit4] 2> "ignored":true}
[junit4] 2> 751420 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751434 WARN (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:14:20.989Z. Now: 2018-03-11T22:46:53.910Z
[junit4] 2> 751434 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a078bdTb3h5wtmur7qwcqxgaw08u9fvs",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781260989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808413910},
[junit4] 2> "ignored":true}
[junit4] 2> 751440 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751454 WARN (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:14:22.989Z. Now: 2018-03-11T22:46:54.934Z
[junit4] 2> 751454 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a0808dTb3h5wtmur7qwcqxgaw08u9fvu",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781262989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808414934},
[junit4] 2> "ignored":true}
[junit4] 2> 751460 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751475 WARN (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:14:24.989Z. Now: 2018-03-11T22:46:55.959Z
[junit4] 2> 751475 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a0885dTb3h5wtmur7qwcqxgaw08u9fvw",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781264989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808415959},
[junit4] 2> "ignored":true}
[junit4] 2> 751481 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751495 WARN (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:14:26.989Z. Now: 2018-03-11T22:46:56.991Z
[junit4] 2> 751496 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a0902dTb3h5wtmur7qwcqxgaw08u9fvy",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781266989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808416991},
[junit4] 2> "ignored":true}
[junit4] 2> 751501 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751516 WARN (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:14:28.989Z. Now: 2018-03-11T22:46:58.024Z
[junit4] 2> 751516 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a097fdTb3h5wtmur7qwcqxgaw08u9fw0",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781268989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808418024},
[junit4] 2> "ignored":true}
[junit4] 2> 751521 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751537 WARN (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:14:30.989Z. Now: 2018-03-11T22:46:59.058Z
[junit4] 2> 751537 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a09fcdTb3h5wtmur7qwcqxgaw08u9fw2",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781270989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808419058},
[junit4] 2> "ignored":true}
[junit4] 2> 751541 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751557 WARN (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:14:32.989Z. Now: 2018-03-11T22:47:00.087Z
[junit4] 2> 751558 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a0a79dTb3h5wtmur7qwcqxgaw08u9fw4",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781272989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808420087},
[junit4] 2> "ignored":true}
[junit4] 2> 751561 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751578 WARN (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:14:34.989Z. Now: 2018-03-11T22:47:01.120Z
[junit4] 2> 751578 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a0af6dTb3h5wtmur7qwcqxgaw08u9fw6",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781274989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808421120},
[junit4] 2> "ignored":true}
[junit4] 2> 751581 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751599 WARN (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:14:36.989Z. Now: 2018-03-11T22:47:02.149Z
[junit4] 2> 751599 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a0b73dTb3h5wtmur7qwcqxgaw08u9fw8",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781276989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808422149},
[junit4] 2> "ignored":true}
[junit4] 2> 751602 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751619 WARN (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:14:38.989Z. Now: 2018-03-11T22:47:03.183Z
[junit4] 2> 751619 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a0bf0dTb3h5wtmur7qwcqxgaw08u9fwa",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781278989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808423183},
[junit4] 2> "ignored":true}
[junit4] 2> 751622 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751640 WARN (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:14:40.989Z. Now: 2018-03-11T22:47:04.210Z
[junit4] 2> 751640 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a0c6ddTb3h5wtmur7qwcqxgaw08u9fwc",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781280989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808424210},
[junit4] 2> "ignored":true}
[junit4] 2> 751642 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751660 WARN (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:14:42.989Z. Now: 2018-03-11T22:47:05.242Z
[junit4] 2> 751661 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a0ceadTb3h5wtmur7qwcqxgaw08u9fwe",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781282989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808425242},
[junit4] 2> "ignored":true}
[junit4] 2> 751662 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751681 WARN (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:14:44.989Z. Now: 2018-03-11T22:47:06.270Z
[junit4] 2> 751681 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a0d67dTb3h5wtmur7qwcqxgaw08u9fwg",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781284989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808426270},
[junit4] 2> "ignored":true}
[junit4] 2> 751682 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751702 WARN (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:14:46.989Z. Now: 2018-03-11T22:47:07.298Z
[junit4] 2> 751702 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a0de4dTb3h5wtmur7qwcqxgaw08u9fwi",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781286989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808427298},
[junit4] 2> "ignored":true}
[junit4] 2> 751702 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751722 WARN (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:14:48.989Z. Now: 2018-03-11T22:47:08.323Z
[junit4] 2> 751722 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a0e61dTb3h5wtmur7qwcqxgaw08u9fwk",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781288989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808428323},
[junit4] 2> "ignored":true}
[junit4] 2> 751722 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751742 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751742 WARN (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:14:50.989Z. Now: 2018-03-11T22:47:09.344Z
[junit4] 2> 751743 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a0ededTb3h5wtmur7qwcqxgaw08u9fwm",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781290989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808429344},
[junit4] 2> "ignored":true}
[junit4] 2> 751763 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751763 WARN (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:14:52.989Z. Now: 2018-03-11T22:47:10.380Z
[junit4] 2> 751763 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a0f5bdTb3h5wtmur7qwcqxgaw08u9fwo",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781292989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808430380},
[junit4] 2> "ignored":true}
[junit4] 2> 751783 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751784 WARN (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:14:54.989Z. Now: 2018-03-11T22:47:11.406Z
[junit4] 2> 751784 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a0fd8dTb3h5wtmur7qwcqxgaw08u9fwq",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781294989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808431406},
[junit4] 2> "ignored":true}
[junit4] 2> 751803 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751804 WARN (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:14:56.989Z. Now: 2018-03-11T22:47:12.430Z
[junit4] 2> 751804 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a1055dTb3h5wtmur7qwcqxgaw08u9fws",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781296989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808432430},
[junit4] 2> "ignored":true}
[junit4] 2> 751823 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751825 WARN (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:14:58.989Z. Now: 2018-03-11T22:47:13.448Z
[junit4] 2> 751825 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a10d2dTb3h5wtmur7qwcqxgaw08u9fwu",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781298989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808433448},
[junit4] 2> "ignored":true}
[junit4] 2> 751843 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751845 WARN (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:15:00.989Z. Now: 2018-03-11T22:47:14.473Z
[junit4] 2> 751845 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a114fdTb3h5wtmur7qwcqxgaw08u9fww",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781300989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808434473},
[junit4] 2> "ignored":true}
[junit4] 2> 751863 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751866 WARN (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:15:02.989Z. Now: 2018-03-11T22:47:15.500Z
[junit4] 2> 751866 DEBUG (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a11ccdTb3h5wtmur7qwcqxgaw08u9fwy",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781302989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808435500},
[junit4] 2> "ignored":true}
[junit4] 2> 751884 DEBUG (ScheduledTrigger-3739-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751886 WARN (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:15:04.989Z. Now: 2018-03-11T22:47:16.535Z
[junit4] 2> 751887 DEBUG (ScheduledTrigger-3739-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"16215a1249dTb3h5wtmur7qwcqxgaw08u9fx0",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520781304989,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520808436535},
[junit4] 2> "ignored":true}
[junit4] 2> 751904 DEBUG (ScheduledTrigger-3739-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 751907 WARN (ScheduledTrigger-3739-thread-1) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-11T15:15:06.989Z. Now: 201
[...truncated too long message...]
AwareThreadPoolExecutor.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> 5199 DEBUG (AutoscalingActionExecutor-29-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Resuming trigger: .auto_add_replicas after 100ms
[junit4] 2> 5199 DEBUG (AutoscalingActionExecutor-29-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -- processing took 84 ms for event
id=3457f1d1a9562Tb3h5wtmur7qwcqxgaw08u9ay9
[junit4] 2> 5199 DEBUG (simCloudManagerPool-27-thread-12) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10001_solr]
[junit4] 2> 5199 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ]
o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 5
[junit4] 2> 5199 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ]
o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 5199 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ]
o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVersion 5
[junit4] 2> 5199 INFO
(TEST-ScheduledMaintenanceTriggerTest.testInactiveShardCleanup-seed#[848FFD53EE04915F])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testInactiveShardCleanup
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=ScheduledMaintenanceTriggerTest
-Dtests.method=testInactiveShardCleanup -Dtests.seed=848FFD53EE04915F
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.badapples=true
-Dtests.locale=fi-FI -Dtests.timezone=Antarctica/Syowa -Dtests.asserts=true
-Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 0.18s J0 |
ScheduledMaintenanceTriggerTest.testInactiveShardCleanup <<<
[junit4] > Throwable #1: java.lang.AssertionError: should be at least one
inactive event
[junit4] > at
__randomizedtesting.SeedInfo.seed([848FFD53EE04915F:99A33D218F47B654]:0)
[junit4] > at
org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest.testInactiveShardCleanup(ScheduledMaintenanceTriggerTest.java:218)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 5203 WARN (Simulated OverseerAutoScalingTriggerThread) [ ]
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are
closed, exiting.
[junit4] 2> 5203 DEBUG
(SUITE-ScheduledMaintenanceTriggerTest-seed#[848FFD53EE04915F]-worker) [ ]
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 5205 INFO (jetty-closer-25-thread-1) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@f13bbc{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 5205 INFO (jetty-closer-25-thread-1) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=14678722
[junit4] 2> 5205 INFO (jetty-closer-25-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 5205 INFO (jetty-closer-25-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@195116d: rootName =
solr_45657, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@1014787
[junit4] 2> 5217 INFO (jetty-closer-25-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 5218 INFO (jetty-closer-25-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@d3a878: rootName =
solr_45657, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@5c71b5
[junit4] 2> 5224 INFO (jetty-closer-25-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 5224 INFO (jetty-closer-25-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1395449: rootName =
solr_45657, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@19f0ad5
[junit4] 2> 5226 INFO (jetty-closer-25-thread-1) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:45657_solr
[junit4] 2> 5226 INFO (jetty-closer-25-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 5227 INFO
(zkCallback-34-thread-1-processing-n:127.0.0.1:45657_solr)
[n:127.0.0.1:45657_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (0)
[junit4] 2> 5227 INFO
(zkCallback-39-thread-1-processing-n:127.0.0.1:45657_solr)
[n:127.0.0.1:45657_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (0)
[junit4] 2> 5227 INFO (jetty-closer-25-thread-1) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 5227 INFO (jetty-closer-25-thread-1) [ ] o.a.s.c.Overseer
Overseer (id=72117934637973507-127.0.0.1:45657_solr-n_0000000000) closing
[junit4] 2> 5227 INFO
(OverseerStateUpdate-72117934637973507-127.0.0.1:45657_solr-n_0000000000)
[n:127.0.0.1:45657_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:45657_solr
[junit4] 2> 5227 WARN
(OverseerAutoScalingTriggerThread-72117934637973507-127.0.0.1:45657_solr-n_0000000000)
[n:127.0.0.1:45657_solr ] o.a.s.c.a.OverseerTriggerThread
OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 5228 DEBUG (jetty-closer-25-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 5229 INFO (jetty-closer-25-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@1bf6490{/solr,null,UNAVAILABLE}
[junit4] 2> 5229 INFO (jetty-closer-25-thread-1) [ ] o.e.j.s.session
Stopped scavenging
[junit4] 2> 5230 ERROR
(SUITE-ScheduledMaintenanceTriggerTest-seed#[848FFD53EE04915F]-worker) [ ]
o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper
server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 5230 INFO
(SUITE-ScheduledMaintenanceTriggerTest-seed#[848FFD53EE04915F]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:36953 36953
[junit4] 2> 6800 INFO (Thread-12) [ ] o.a.s.c.ZkTestServer connecting
to 127.0.0.1:36953 36953
[junit4] 2> 6801 WARN (Thread-12) [ ] o.a.s.c.ZkTestServer Watch limit
violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/aliases.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/live_nodes
[junit4] 2> 3 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest_848FFD53EE04915F-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=138, maxMBSortInHeap=7.960857568821769,
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@11911b7),
locale=fi-FI, timezone=Antarctica/Syowa
[junit4] 2> NOTE: Linux 4.13.0-36-generic i386/Oracle Corporation
1.8.0_162 (32-bit)/cpus=8,threads=1,free=39560992,total=65011712
[junit4] 2> NOTE: All tests run in this JVM:
[ScheduledMaintenanceTriggerTest, ScheduledMaintenanceTriggerTest]
[junit4] Completed [5/5 (2!)] on J0 in 2.33s, 2 tests, 1 failure <<<
FAILURES!
[...truncated 12 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1561:
The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1085:
There were test failures: 5 suites, 10 tests, 2 failures [seed:
848FFD53EE04915F]
Total time: 11 seconds
[repro] Setting last failure code to 256
[repro] Failures:
[repro] 2/5 failed:
org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest
[repro] Exiting with code 256
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting
ANT_1_8_2_HOME=/var/lib/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=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting
ANT_1_8_2_HOME=/var/lib/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=8f1350af20c682df7b7fb9e3af762e03b944fdb9,
workspace=/var/lib/jenkins/workspace/Lucene-Solr-master-Linux
[WARNINGS] Computing warning deltas based on reference build #21617
Recording test results
Setting
ANT_1_8_2_HOME=/var/lib/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=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
