Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/21601/
Java: 64bit/jdk-9.0.4 -XX:+UseCompressedOops -XX:+UseG1GC
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([123836403BDEB6B7:F14F6325A9D91BC]: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
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
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
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
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([123836403BDEB6B7:F14F6325A9D91BC]: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
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
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
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
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([123836403BDEB6B7:F14F6325A9D91BC]: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
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
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
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 14226 lines...]
[junit4] Suite:
org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest
[junit4] 2> 2127768 INFO
(SUITE-ScheduledMaintenanceTriggerTest-seed#[123836403BDEB6B7]-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_123836403BDEB6B7-001/init-core-data-001
[junit4] 2> 2127768 WARN
(SUITE-ScheduledMaintenanceTriggerTest-seed#[123836403BDEB6B7]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=66 numCloses=66
[junit4] 2> 2127769 INFO
(SUITE-ScheduledMaintenanceTriggerTest-seed#[123836403BDEB6B7]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 2127769 INFO
(SUITE-ScheduledMaintenanceTriggerTest-seed#[123836403BDEB6B7]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0,
clientAuth=0.0/0.0)
[junit4] 2> 2127770 INFO
(SUITE-ScheduledMaintenanceTriggerTest-seed#[123836403BDEB6B7]-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_123836403BDEB6B7-001/tempDir-001
[junit4] 2> 2127770 INFO
(SUITE-ScheduledMaintenanceTriggerTest-seed#[123836403BDEB6B7]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2127770 INFO (Thread-6733) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2127770 INFO (Thread-6733) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 2127771 ERROR (Thread-6733) [ ] 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> 2127870 INFO
(SUITE-ScheduledMaintenanceTriggerTest-seed#[123836403BDEB6B7]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:38819
[junit4] 2> 2127872 INFO (zkConnectionManagerCallback-4844-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2127875 INFO (jetty-launcher-4841-thread-1) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-22T10:27:37+13:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 2127876 INFO (jetty-launcher-4841-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2127876 INFO (jetty-launcher-4841-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2127876 INFO (jetty-launcher-4841-thread-1) [ ]
o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 2127876 INFO (jetty-launcher-4841-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@4edf528{/solr,null,AVAILABLE}
[junit4] 2> 2127878 INFO (jetty-launcher-4841-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@34c0f75f{SSL,[ssl,
http/1.1]}{127.0.0.1:32873}
[junit4] 2> 2127878 INFO (jetty-launcher-4841-thread-1) [ ]
o.e.j.s.Server Started @2130453ms
[junit4] 2> 2127878 INFO (jetty-launcher-4841-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=32873}
[junit4] 2> 2127878 ERROR (jetty-launcher-4841-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2127878 INFO (jetty-launcher-4841-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 2127878 INFO (jetty-launcher-4841-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2127878 INFO (jetty-launcher-4841-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2127878 INFO (jetty-launcher-4841-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-03-09T09:31:49.135558Z
[junit4] 2> 2127879 INFO (zkConnectionManagerCallback-4846-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2127879 INFO (jetty-launcher-4841-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2127883 INFO (jetty-launcher-4841-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38819/solr
[junit4] 2> 2127883 INFO (zkConnectionManagerCallback-4850-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2127885 INFO
(zkConnectionManagerCallback-4852-thread-1-processing-n:127.0.0.1:32873_solr)
[n:127.0.0.1:32873_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2127913 INFO (jetty-launcher-4841-thread-1)
[n:127.0.0.1:32873_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2127913 INFO (jetty-launcher-4841-thread-1)
[n:127.0.0.1:32873_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:32873_solr
[junit4] 2> 2127914 INFO (jetty-launcher-4841-thread-1)
[n:127.0.0.1:32873_solr ] o.a.s.c.Overseer Overseer
(id=72105141462499331-127.0.0.1:32873_solr-n_0000000000) starting
[junit4] 2> 2127916 INFO (jetty-launcher-4841-thread-1)
[n:127.0.0.1:32873_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:32873_solr
[junit4] 2> 2127916 DEBUG
(OverseerAutoScalingTriggerThread-72105141462499331-127.0.0.1:32873_solr-n_0000000000)
[n:127.0.0.1:32873_solr ] o.a.s.c.a.OverseerTriggerThread Adding
.auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 2127916 INFO
(zkCallback-4851-thread-1-processing-n:127.0.0.1:32873_solr)
[n:127.0.0.1:32873_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 2127916 DEBUG
(OverseerAutoScalingTriggerThread-72105141462499331-127.0.0.1:32873_solr-n_0000000000)
[n:127.0.0.1:32873_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 1
[junit4] 2> 2127917 DEBUG
(OverseerAutoScalingTriggerThread-72105141462499331-127.0.0.1:32873_solr-n_0000000000)
[n:127.0.0.1:32873_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:32873_solr]
[junit4] 2> 2127917 DEBUG
(OverseerAutoScalingTriggerThread-72105141462499331-127.0.0.1:32873_solr-n_0000000000)
[n:127.0.0.1:32873_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 2127917 DEBUG
(OverseerAutoScalingTriggerThread-72105141462499331-127.0.0.1:32873_solr-n_0000000000)
[n:127.0.0.1:32873_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 1
[junit4] 2> 2127918 DEBUG
(OverseerAutoScalingTriggerThread-72105141462499331-127.0.0.1:32873_solr-n_0000000000)
[n:127.0.0.1:32873_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 2127918 DEBUG (ScheduledTrigger-9184-thread-1)
[n:127.0.0.1:32873_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 1
[junit4] 2> 2127918 DEBUG
(OverseerAutoScalingTriggerThread-72105141462499331-127.0.0.1:32873_solr-n_0000000000)
[n:127.0.0.1:32873_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 2127950 INFO (jetty-launcher-4841-thread-1)
[n:127.0.0.1:32873_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_32873.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1cf8eaa1
[junit4] 2> 2127955 INFO (jetty-launcher-4841-thread-1)
[n:127.0.0.1:32873_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_32873.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1cf8eaa1
[junit4] 2> 2127955 INFO (jetty-launcher-4841-thread-1)
[n:127.0.0.1:32873_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_32873.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1cf8eaa1
[junit4] 2> 2127956 INFO (jetty-launcher-4841-thread-1)
[n:127.0.0.1:32873_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_123836403BDEB6B7-001/tempDir-001/node1/.
[junit4] 2> 2127964 INFO (zkConnectionManagerCallback-4856-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2127966 INFO (zkConnectionManagerCallback-4860-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2127967 INFO
(SUITE-ScheduledMaintenanceTriggerTest-seed#[123836403BDEB6B7]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2127967 INFO
(SUITE-ScheduledMaintenanceTriggerTest-seed#[123836403BDEB6B7]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38819/solr ready
[junit4] 2> 2127969 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 2127969 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 0
[junit4] 2> 2127969 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10016_solr]
[junit4] 2> 2127969 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion -1
[junit4] 2> 2127969 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 0
[junit4] 2> 2127970 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2127971 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion 0
[junit4] 2> 2127971 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2127971 WARN (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:31:49.226Z. Now: 2018-03-10T11:21:58.849Z
[junit4] 2> 2127972 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f40846aT4wgzf6ais2i1wvyx13mjcs6dj",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674309226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680918849},
[junit4] 2> "ignored":true}
[junit4] 2> 2127974 INFO
(TEST-ScheduledMaintenanceTriggerTest.testTriggerDefaults-seed#[123836403BDEB6B7])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testTriggerDefaults
[junit4] 2> 2127974 INFO
(TEST-ScheduledMaintenanceTriggerTest.testTriggerDefaults-seed#[123836403BDEB6B7])
[ ] 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":30,
[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> 2127975 DEBUG (simCloudManagerPool-9190-thread-2) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2127975 DEBUG (simCloudManagerPool-9190-thread-2) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
1
[junit4] 2> 2127975 DEBUG (simCloudManagerPool-9190-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10016_solr]
[junit4] 2> 2127975 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 2127975 INFO
(TEST-ScheduledMaintenanceTriggerTest.testTriggerDefaults-seed#[123836403BDEB6B7])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testTriggerDefaults
[junit4] 2> 2127975 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2127975 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 2127979 INFO
(TEST-ScheduledMaintenanceTriggerTest.testInactiveShardCleanup-seed#[123836403BDEB6B7])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testInactiveShardCleanup
[junit4] 2> 2127991 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128011 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128032 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128052 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128072 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128092 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128112 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128123 DEBUG (simCloudManagerPool-9190-thread-6) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for
ScheduledMaintenanceTriggerTest_collection1 / shard1: {"core_node1":{
[junit4] 2> "node_name":"127.0.0.1:10016_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> 2128124 DEBUG (simCloudManagerPool-9190-thread-8) [ ]
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:10016_solr",
[junit4] 2> "base_url":"http://127.0.0.1:10016/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> 2128124 DEBUG (simCloudManagerPool-9190-thread-7) [ ]
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:10016_solr",
[junit4] 2> "base_url":"http://127.0.0.1:10016/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> 2128126 DEBUG (simCloudManagerPool-9190-thread-9) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2128127 DEBUG (simCloudManagerPool-9190-thread-9) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
2
[junit4] 2> 2128127 DEBUG (simCloudManagerPool-9190-thread-9) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10016_solr]
[junit4] 2> 2128127 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 2128127 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2128127 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 2128128 DEBUG (simCloudManagerPool-9190-thread-10) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2128128 DEBUG (simCloudManagerPool-9190-thread-10) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
3
[junit4] 2> 2128128 DEBUG (simCloudManagerPool-9190-thread-10) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10016_solr]
[junit4] 2> 2128128 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 2128128 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2128128 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:31:51.226Z. Now: 2018-03-10T11:22:06.732Z
[junit4] 2> 2128128 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 2128128 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f408c3aT4wgzf6ais2i1wvyx13mjcs6dl",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674311226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680926732},
[junit4] 2> "ignored":true}
[junit4] 2> 2128133 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128151 WARN (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:31:53.226Z. Now: 2018-03-10T11:22:07.853Z
[junit4] 2> 2128151 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f40940aT4wgzf6ais2i1wvyx13mjcs6dn",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674313226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680927853},
[junit4] 2> "ignored":true}
[junit4] 2> 2128153 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128171 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:31:55.226Z. Now: 2018-03-10T11:22:08.890Z
[junit4] 2> 2128172 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f409bdaT4wgzf6ais2i1wvyx13mjcs6dp",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674315226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680928890},
[junit4] 2> "ignored":true}
[junit4] 2> 2128173 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128192 WARN (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:31:57.226Z. Now: 2018-03-10T11:22:09.936Z
[junit4] 2> 2128192 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f40a3aaT4wgzf6ais2i1wvyx13mjcs6dr",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674317226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680929936},
[junit4] 2> "ignored":true}
[junit4] 2> 2128193 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128213 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:31:59.226Z. Now: 2018-03-10T11:22:10.977Z
[junit4] 2> 2128213 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f40ab7aT4wgzf6ais2i1wvyx13mjcs6dt",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674319226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680930977},
[junit4] 2> "ignored":true}
[junit4] 2> 2128213 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128234 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128234 WARN (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:32:01.226Z. Now: 2018-03-10T11:22:12.012Z
[junit4] 2> 2128234 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f40b34aT4wgzf6ais2i1wvyx13mjcs6dv",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674321226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680932012},
[junit4] 2> "ignored":true}
[junit4] 2> 2128254 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128254 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:32:03.226Z. Now: 2018-03-10T11:22:13.045Z
[junit4] 2> 2128255 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f40bb1aT4wgzf6ais2i1wvyx13mjcs6dx",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674323226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680933045},
[junit4] 2> "ignored":true}
[junit4] 2> 2128274 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128275 WARN (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:32:05.226Z. Now: 2018-03-10T11:22:14.077Z
[junit4] 2> 2128275 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f40c2eaT4wgzf6ais2i1wvyx13mjcs6dz",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674325226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680934077},
[junit4] 2> "ignored":true}
[junit4] 2> 2128294 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128296 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:32:07.226Z. Now: 2018-03-10T11:22:15.108Z
[junit4] 2> 2128296 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f40cabaT4wgzf6ais2i1wvyx13mjcs6e1",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674327226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680935108},
[junit4] 2> "ignored":true}
[junit4] 2> 2128314 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128316 WARN (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:32:09.226Z. Now: 2018-03-10T11:22:16.140Z
[junit4] 2> 2128316 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f40d28aT4wgzf6ais2i1wvyx13mjcs6e3",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674329226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680936140},
[junit4] 2> "ignored":true}
[junit4] 2> 2128334 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128337 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:32:11.226Z. Now: 2018-03-10T11:22:17.175Z
[junit4] 2> 2128337 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f40da5aT4wgzf6ais2i1wvyx13mjcs6e5",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674331226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680937175},
[junit4] 2> "ignored":true}
[junit4] 2> 2128355 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128358 WARN (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:32:13.226Z. Now: 2018-03-10T11:22:18.218Z
[junit4] 2> 2128358 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f40e22aT4wgzf6ais2i1wvyx13mjcs6e7",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674333226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680938218},
[junit4] 2> "ignored":true}
[junit4] 2> 2128375 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128379 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:32:15.226Z. Now: 2018-03-10T11:22:19.259Z
[junit4] 2> 2128379 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f40e9faT4wgzf6ais2i1wvyx13mjcs6e9",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674335226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680939259},
[junit4] 2> "ignored":true}
[junit4] 2> 2128395 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128399 WARN (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:32:17.226Z. Now: 2018-03-10T11:22:20.298Z
[junit4] 2> 2128400 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f40f1caT4wgzf6ais2i1wvyx13mjcs6eb",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674337226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680940298},
[junit4] 2> "ignored":true}
[junit4] 2> 2128415 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128420 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:32:19.226Z. Now: 2018-03-10T11:22:21.326Z
[junit4] 2> 2128420 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f40f99aT4wgzf6ais2i1wvyx13mjcs6ed",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674339226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680941326},
[junit4] 2> "ignored":true}
[junit4] 2> 2128435 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128441 WARN (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:32:21.226Z. Now: 2018-03-10T11:22:22.355Z
[junit4] 2> 2128441 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f41016aT4wgzf6ais2i1wvyx13mjcs6ef",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674341226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680942355},
[junit4] 2> "ignored":true}
[junit4] 2> 2128456 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128461 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:32:23.226Z. Now: 2018-03-10T11:22:23.391Z
[junit4] 2> 2128462 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f41093aT4wgzf6ais2i1wvyx13mjcs6eh",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674343226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680943391},
[junit4] 2> "ignored":true}
[junit4] 2> 2128476 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128482 WARN (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:32:25.226Z. Now: 2018-03-10T11:22:24.422Z
[junit4] 2> 2128482 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f41110aT4wgzf6ais2i1wvyx13mjcs6ej",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674345226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680944422},
[junit4] 2> "ignored":true}
[junit4] 2> 2128496 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128503 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:32:27.226Z. Now: 2018-03-10T11:22:25.457Z
[junit4] 2> 2128503 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f4118daT4wgzf6ais2i1wvyx13mjcs6el",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674347226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680945457},
[junit4] 2> "ignored":true}
[junit4] 2> 2128516 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128523 WARN (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:32:29.226Z. Now: 2018-03-10T11:22:26.484Z
[junit4] 2> 2128523 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f4120aaT4wgzf6ais2i1wvyx13mjcs6en",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674349226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680946484},
[junit4] 2> "ignored":true}
[junit4] 2> 2128537 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128544 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:32:31.226Z. Now: 2018-03-10T11:22:27.516Z
[junit4] 2> 2128544 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f41287aT4wgzf6ais2i1wvyx13mjcs6ep",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674351226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680947516},
[junit4] 2> "ignored":true}
[junit4] 2> 2128557 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128565 WARN (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:32:33.226Z. Now: 2018-03-10T11:22:28.551Z
[junit4] 2> 2128565 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f41304aT4wgzf6ais2i1wvyx13mjcs6er",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674353226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680948551},
[junit4] 2> "ignored":true}
[junit4] 2> 2128577 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128585 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:32:35.226Z. Now: 2018-03-10T11:22:29.583Z
[junit4] 2> 2128585 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f41381aT4wgzf6ais2i1wvyx13mjcs6et",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674355226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680949583},
[junit4] 2> "ignored":true}
[junit4] 2> 2128597 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128606 WARN (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:32:37.226Z. Now: 2018-03-10T11:22:30.622Z
[junit4] 2> 2128606 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f413feaT4wgzf6ais2i1wvyx13mjcs6ev",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674357226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680950622},
[junit4] 2> "ignored":true}
[junit4] 2> 2128617 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128627 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:32:39.226Z. Now: 2018-03-10T11:22:31.649Z
[junit4] 2> 2128627 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f4147baT4wgzf6ais2i1wvyx13mjcs6ex",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674359226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680951649},
[junit4] 2> "ignored":true}
[junit4] 2> 2128638 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128647 WARN (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:32:41.226Z. Now: 2018-03-10T11:22:32.679Z
[junit4] 2> 2128647 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f414f8aT4wgzf6ais2i1wvyx13mjcs6ez",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674361226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680952679},
[junit4] 2> "ignored":true}
[junit4] 2> 2128658 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128668 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:32:43.226Z. Now: 2018-03-10T11:22:33.715Z
[junit4] 2> 2128668 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f41575aT4wgzf6ais2i1wvyx13mjcs6f1",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674363226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680953715},
[junit4] 2> "ignored":true}
[junit4] 2> 2128678 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128689 WARN (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:32:45.226Z. Now: 2018-03-10T11:22:34.749Z
[junit4] 2> 2128689 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f415f2aT4wgzf6ais2i1wvyx13mjcs6f3",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674365226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680954749},
[junit4] 2> "ignored":true}
[junit4] 2> 2128698 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128709 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:32:47.226Z. Now: 2018-03-10T11:22:35.788Z
[junit4] 2> 2128709 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f4166faT4wgzf6ais2i1wvyx13mjcs6f5",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674367226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680955788},
[junit4] 2> "ignored":true}
[junit4] 2> 2128719 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128730 WARN (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:32:49.226Z. Now: 2018-03-10T11:22:36.829Z
[junit4] 2> 2128730 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f416ecaT4wgzf6ais2i1wvyx13mjcs6f7",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674369226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680956829},
[junit4] 2> "ignored":true}
[junit4] 2> 2128739 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128751 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:32:51.226Z. Now: 2018-03-10T11:22:37.868Z
[junit4] 2> 2128751 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f41769aT4wgzf6ais2i1wvyx13mjcs6f9",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674371226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680957868},
[junit4] 2> "ignored":true}
[junit4] 2> 2128759 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128771 WARN (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:32:53.226Z. Now: 2018-03-10T11:22:38.891Z
[junit4] 2> 2128771 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f417e6aT4wgzf6ais2i1wvyx13mjcs6fb",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674373226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680958891},
[junit4] 2> "ignored":true}
[junit4] 2> 2128779 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128792 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:32:55.226Z. Now: 2018-03-10T11:22:39.913Z
[junit4] 2> 2128792 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f41863aT4wgzf6ais2i1wvyx13mjcs6fd",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674375226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680959913},
[junit4] 2> "ignored":true}
[junit4] 2> 2128799 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128813 WARN (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:32:57.226Z. Now: 2018-03-10T11:22:40.951Z
[junit4] 2> 2128813 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f418e0aT4wgzf6ais2i1wvyx13mjcs6ff",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674377226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680960951},
[junit4] 2> "ignored":true}
[junit4] 2> 2128820 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128833 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:32:59.226Z. Now: 2018-03-10T11:22:41.984Z
[junit4] 2> 2128833 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f4195daT4wgzf6ais2i1wvyx13mjcs6fh",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674379226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680961984},
[junit4] 2> "ignored":true}
[junit4] 2> 2128840 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128854 WARN (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:33:01.226Z. Now: 2018-03-10T11:22:43.021Z
[junit4] 2> 2128854 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f419daaT4wgzf6ais2i1wvyx13mjcs6fj",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674381226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680963021},
[junit4] 2> "ignored":true}
[junit4] 2> 2128860 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128875 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:33:03.226Z. Now: 2018-03-10T11:22:44.054Z
[junit4] 2> 2128875 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f41a57aT4wgzf6ais2i1wvyx13mjcs6fl",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674383226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680964054},
[junit4] 2> "ignored":true}
[junit4] 2> 2128880 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128895 WARN (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:33:05.226Z. Now: 2018-03-10T11:22:45.075Z
[junit4] 2> 2128895 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f41ad4aT4wgzf6ais2i1wvyx13mjcs6fn",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674385226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680965075},
[junit4] 2> "ignored":true}
[junit4] 2> 2128900 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128915 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:33:07.226Z. Now: 2018-03-10T11:22:46.097Z
[junit4] 2> 2128916 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f41b51aT4wgzf6ais2i1wvyx13mjcs6fp",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674387226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680966097},
[junit4] 2> "ignored":true}
[junit4] 2> 2128919 DEBUG (ScheduledTrigger-9184-thread-3)
[n:127.0.0.1:32873_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 1
[junit4] 2> 2128921 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128936 WARN (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:33:09.226Z. Now: 2018-03-10T11:22:47.127Z
[junit4] 2> 2128936 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f41bceaT4wgzf6ais2i1wvyx13mjcs6fr",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674389226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680967127},
[junit4] 2> "ignored":true}
[junit4] 2> 2128941 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128957 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:33:11.226Z. Now: 2018-03-10T11:22:48.165Z
[junit4] 2> 2128957 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f41c4baT4wgzf6ais2i1wvyx13mjcs6ft",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674391226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680968165},
[junit4] 2> "ignored":true}
[junit4] 2> 2128961 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128977 WARN (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:33:13.226Z. Now: 2018-03-10T11:22:49.196Z
[junit4] 2> 2128978 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f41cc8aT4wgzf6ais2i1wvyx13mjcs6fv",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674393226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680969196},
[junit4] 2> "ignored":true}
[junit4] 2> 2128981 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2128998 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:33:15.226Z. Now: 2018-03-10T11:22:50.226Z
[junit4] 2> 2128998 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f41d45aT4wgzf6ais2i1wvyx13mjcs6fx",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674395226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680970226},
[junit4] 2> "ignored":true}
[junit4] 2> 2129001 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2129019 WARN (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:33:17.226Z. Now: 2018-03-10T11:22:51.263Z
[junit4] 2> 2129019 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f41dc2aT4wgzf6ais2i1wvyx13mjcs6fz",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674397226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680971263},
[junit4] 2> "ignored":true}
[junit4] 2> 2129022 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2129039 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:33:19.226Z. Now: 2018-03-10T11:22:52.297Z
[junit4] 2> 2129040 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f41e3faT4wgzf6ais2i1wvyx13mjcs6g1",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674399226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680972297},
[junit4] 2> "ignored":true}
[junit4] 2> 2129042 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2129060 WARN (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:33:21.226Z. Now: 2018-03-10T11:22:53.333Z
[junit4] 2> 2129060 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f41ebcaT4wgzf6ais2i1wvyx13mjcs6g3",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674401226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680973333},
[junit4] 2> "ignored":true}
[junit4] 2> 2129062 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2129081 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:33:23.226Z. Now: 2018-03-10T11:22:54.366Z
[junit4] 2> 2129081 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f41f39aT4wgzf6ais2i1wvyx13mjcs6g5",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674403226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680974366},
[junit4] 2> "ignored":true}
[junit4] 2> 2129082 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2129102 WARN (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:33:25.226Z. Now: 2018-03-10T11:22:55.400Z
[junit4] 2> 2129102 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f41fb6aT4wgzf6ais2i1wvyx13mjcs6g7",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674405226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680975400},
[junit4] 2> "ignored":true}
[junit4] 2> 2129102 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2129122 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:33:27.226Z. Now: 2018-03-10T11:22:56.437Z
[junit4] 2> 2129122 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f42033aT4wgzf6ais2i1wvyx13mjcs6g9",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674407226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680976437},
[junit4] 2> "ignored":true}
[junit4] 2> 2129123 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2129143 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2129143 WARN (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:33:29.226Z. Now: 2018-03-10T11:22:57.468Z
[junit4] 2> 2129143 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f420b0aT4wgzf6ais2i1wvyx13mjcs6gb",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674409226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680977468},
[junit4] 2> "ignored":true}
[junit4] 2> 2129163 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2129164 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:33:31.226Z. Now: 2018-03-10T11:22:58.499Z
[junit4] 2> 2129164 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f4212daT4wgzf6ais2i1wvyx13mjcs6gd",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674411226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680978499},
[junit4] 2> "ignored":true}
[junit4] 2> 2129183 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2129184 WARN (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:33:33.226Z. Now: 2018-03-10T11:22:59.527Z
[junit4] 2> 2129184 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f421aaaT4wgzf6ais2i1wvyx13mjcs6gf",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674413226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680979527},
[junit4] 2> "ignored":true}
[junit4] 2> 2129203 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2129205 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:33:35.226Z. Now: 2018-03-10T11:23:00.565Z
[junit4] 2> 2129205 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f42227aT4wgzf6ais2i1wvyx13mjcs6gh",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674415226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680980565},
[junit4] 2> "ignored":true}
[junit4] 2> 2129224 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2129225 WARN (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:33:37.226Z. Now: 2018-03-10T11:23:01.594Z
[junit4] 2> 2129226 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f422a4aT4wgzf6ais2i1wvyx13mjcs6gj",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674417226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680981594},
[junit4] 2> "ignored":true}
[junit4] 2> 2129244 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2129246 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:33:39.226Z. Now: 2018-03-10T11:23:02.624Z
[junit4] 2> 2129246 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f42321aT4wgzf6ais2i1wvyx13mjcs6gl",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674419226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680982624},
[junit4] 2> "ignored":true}
[junit4] 2> 2129264 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2129267 WARN (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:33:41.226Z. Now: 2018-03-10T11:23:03.661Z
[junit4] 2> 2129267 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f4239eaT4wgzf6ais2i1wvyx13mjcs6gn",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674421226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680983661},
[junit4] 2> "ignored":true}
[junit4] 2> 2129284 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2129287 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:33:43.226Z. Now: 2018-03-10T11:23:04.692Z
[junit4] 2> 2129287 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f4241baT4wgzf6ais2i1wvyx13mjcs6gp",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674423226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680984692},
[junit4] 2> "ignored":true}
[junit4] 2> 2129305 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2129308 WARN (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:33:45.226Z. Now: 2018-03-10T11:23:05.724Z
[junit4] 2> 2129308 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f42498aT4wgzf6ais2i1wvyx13mjcs6gr",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674425226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680985724},
[junit4] 2> "ignored":true}
[junit4] 2> 2129325 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2129329 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:33:47.226Z. Now: 2018-03-10T11:23:06.759Z
[junit4] 2> 2129329 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f42515aT4wgzf6ais2i1wvyx13mjcs6gt",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674427226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680986759},
[junit4] 2> "ignored":true}
[junit4] 2> 2129345 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2129349 WARN (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:33:49.226Z. Now: 2018-03-10T11:23:07.788Z
[junit4] 2> 2129349 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f42592aT4wgzf6ais2i1wvyx13mjcs6gv",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674429226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680987788},
[junit4] 2> "ignored":true}
[junit4] 2> 2129365 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2129370 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:33:51.226Z. Now: 2018-03-10T11:23:08.820Z
[junit4] 2> 2129370 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f4260faT4wgzf6ais2i1wvyx13mjcs6gx",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674431226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680988820},
[junit4] 2> "ignored":true}
[junit4] 2> 2129385 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2129390 WARN (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:33:53.226Z. Now: 2018-03-10T11:23:09.845Z
[junit4] 2> 2129391 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f4268caT4wgzf6ais2i1wvyx13mjcs6gz",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674433226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680989845},
[junit4] 2> "ignored":true}
[junit4] 2> 2129406 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2129411 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:33:55.226Z. Now: 2018-03-10T11:23:10.876Z
[junit4] 2> 2129411 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f42709aT4wgzf6ais2i1wvyx13mjcs6h1",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674435226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680990876},
[junit4] 2> "ignored":true}
[junit4] 2> 2129426 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2129432 WARN (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:33:57.226Z. Now: 2018-03-10T11:23:11.907Z
[junit4] 2> 2129432 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f42786aT4wgzf6ais2i1wvyx13mjcs6h3",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674437226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680991907},
[junit4] 2> "ignored":true}
[junit4] 2> 2129446 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2129452 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:33:59.226Z. Now: 2018-03-10T11:23:12.928Z
[junit4] 2> 2129452 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f42803aT4wgzf6ais2i1wvyx13mjcs6h5",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674439226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680992928},
[junit4] 2> "ignored":true}
[junit4] 2> 2129466 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2129473 WARN (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:34:01.226Z. Now: 2018-03-10T11:23:13.960Z
[junit4] 2> 2129473 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f42880aT4wgzf6ais2i1wvyx13mjcs6h7",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674441226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680993960},
[junit4] 2> "ignored":true}
[junit4] 2> 2129486 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2129494 WARN (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:34:03.226Z. Now: 2018-03-10T11:23:15.004Z
[junit4] 2> 2129494 DEBUG (ScheduledTrigger-9191-thread-3) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f428fdaT4wgzf6ais2i1wvyx13mjcs6h9",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674443226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680995004},
[junit4] 2> "ignored":true}
[junit4] 2> 2129507 DEBUG (ScheduledTrigger-9191-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2129514 WARN (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at
scheduled time: 2018-03-10T09:34:05.226Z. Now: 2018-03-10T11:23:16.035Z
[junit4] 2> 2129514 DEBUG (ScheduledTrigger-9191-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
[junit4] 2> "id":"1620f4297aaT4wgzf6ais2i1wvyx13mjcs6hb",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":1520674445226,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1520680996035},
[junit4] 2> "ignored":true}
[junit4] 2> 2129527 DEBUG (ScheduledTrigger-9191-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently li
[...truncated too long message...]
ava:532)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$add$3(ScheduledTriggers.java:317)
[junit4] 2> at
java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:514)
[junit4] 2> at
java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at
java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
[junit4] 2> at
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 6266 DEBUG (AutoscalingActionExecutor-29-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Resuming trigger: .auto_add_replicas after 100ms
[junit4] 2> 6266 DEBUG (AutoscalingActionExecutor-29-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -- processing took 101 ms for event
id=294ebcbc45d13T4wgzf6ais2i1wvyx13mjcrqk5
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=ScheduledMaintenanceTriggerTest
-Dtests.method=testInactiveShardCleanup -Dtests.seed=123836403BDEB6B7
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=zu
-Dtests.timezone=Africa/Nairobi -Dtests.asserts=true
-Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 0.16s J0 |
ScheduledMaintenanceTriggerTest.testInactiveShardCleanup <<<
[junit4] > Throwable #1: java.lang.AssertionError: should be at least one
inactive event
[junit4] > at
__randomizedtesting.SeedInfo.seed([123836403BDEB6B7:F14F6325A9D91BC]:0)
[junit4] > at
org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest.testInactiveShardCleanup(ScheduledMaintenanceTriggerTest.java:218)
[junit4] > at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at
java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 6267 WARN (Simulated OverseerAutoScalingTriggerThread) [ ]
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are
closed, exiting.
[junit4] 2> 6268 DEBUG
(SUITE-ScheduledMaintenanceTriggerTest-seed#[123836403BDEB6B7]-worker) [ ]
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 6273 INFO (jetty-closer-22-thread-1) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@31aff0df{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 6273 INFO (jetty-closer-22-thread-1) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=2106620055
[junit4] 2> 6273 INFO (jetty-closer-22-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 6274 INFO (jetty-closer-22-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@2e9d5e12: rootName =
solr_40755, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@3193f478
[junit4] 2> 6282 INFO (jetty-closer-22-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 6282 INFO (jetty-closer-22-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@671dc24e: rootName =
solr_40755, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@18e73778
[junit4] 2> 6287 INFO (jetty-closer-22-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 6287 INFO (jetty-closer-22-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@3a247c85: rootName =
solr_40755, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@53ca4f56
[junit4] 2> 6289 INFO (jetty-closer-22-thread-1) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:40755_solr
[junit4] 2> 6289 INFO (jetty-closer-22-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 6290 INFO (jetty-closer-22-thread-1) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 6290 INFO
(zkCallback-31-thread-1-processing-n:127.0.0.1:40755_solr)
[n:127.0.0.1:40755_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (0)
[junit4] 2> 6290 INFO (jetty-closer-22-thread-1) [ ] o.a.s.c.Overseer
Overseer (id=72105211074445315-127.0.0.1:40755_solr-n_0000000000) closing
[junit4] 2> 6291 INFO
(OverseerStateUpdate-72105211074445315-127.0.0.1:40755_solr-n_0000000000)
[n:127.0.0.1:40755_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:40755_solr
[junit4] 2> 6291 WARN
(OverseerAutoScalingTriggerThread-72105211074445315-127.0.0.1:40755_solr-n_0000000000)
[n:127.0.0.1:40755_solr ] o.a.s.c.a.OverseerTriggerThread
OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 6291 DEBUG (jetty-closer-22-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 6292 INFO (jetty-closer-22-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@489b63f4{/solr,null,UNAVAILABLE}
[junit4] 2> 6292 INFO (jetty-closer-22-thread-1) [ ] o.e.j.s.session
Stopped scavenging
[junit4] 2> 6293 ERROR
(SUITE-ScheduledMaintenanceTriggerTest-seed#[123836403BDEB6B7]-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> 6293 INFO
(SUITE-ScheduledMaintenanceTriggerTest-seed#[123836403BDEB6B7]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:45467 45467
[junit4] 2> 6345 INFO (Thread-11) [ ] o.a.s.c.ZkTestServer connecting
to 127.0.0.1:45467 45467
[junit4] 2> 6346 WARN (Thread-11) [ ] o.a.s.c.ZkTestServer Watch limit
violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 2 /solr/aliases.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 2 /solr/clusterstate.json
[junit4] 2> 2 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 2 /solr/live_nodes
[junit4] 2> 2 /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_123836403BDEB6B7-001
[junit4] 2> Mar 09, 2018 9:49:31 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 3 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70),
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@2791b05c),
locale=zu, timezone=Africa/Nairobi
[junit4] 2> NOTE: Linux 4.13.0-36-generic amd64/Oracle Corporation 9.0.4
(64-bit)/cpus=8,threads=1,free=348092856,total=536870912
[junit4] 2> NOTE: All tests run in this JVM:
[ScheduledMaintenanceTriggerTest, ScheduledMaintenanceTriggerTest]
[junit4] Completed [5/5 (2!)] on J0 in 0.88s, 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:
123836403BDEB6B7]
Total time: 10 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=7dfb04ee5e9f973fbad20c529ec091c201743398,
workspace=/var/lib/jenkins/workspace/Lucene-Solr-master-Linux
[WARNINGS] Computing warning deltas based on reference build #21600
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]
