[JENKINS-EA] Lucene-Solr-master-Linux (64bit/jdk-10-ea+43) - Build # 21633 - Still Unstable!

[Policeman Jenkins Server]

Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/21633/
Java: 64bit/jdk-10-ea+43 -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([E51364AF66639CAA:F83FA4DD0720BBA1]: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([E51364AF66639CAA:F83FA4DD0720BBA1]: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([E51364AF66639CAA:F83FA4DD0720BBA1]: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 1846 lines...]
   [junit4] JVM J0: stdout was not empty, see: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/temp/junit4-J0-20180313_123124_2084194796756965121523.sysout
   [junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
   [junit4] codec: DummyCompressingStoredFields, pf: 
TestBloomFilteredLucenePostings, dvf: Memory
   [junit4] <<< JVM J0: EOF ----

[...truncated 11537 lines...]
   [junit4] Suite: 
org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest
   [junit4]   2> 1012896 INFO  
(SUITE-ScheduledMaintenanceTriggerTest-seed#[E51364AF66639CAA]-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_E51364AF66639CAA-001/init-core-data-001
   [junit4]   2> 1012897 WARN  
(SUITE-ScheduledMaintenanceTriggerTest-seed#[E51364AF66639CAA]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
   [junit4]   2> 1012897 INFO  
(SUITE-ScheduledMaintenanceTriggerTest-seed#[E51364AF66639CAA]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 1012897 INFO  
(SUITE-ScheduledMaintenanceTriggerTest-seed#[E51364AF66639CAA]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, 
clientAuth=0.0/0.0)
   [junit4]   2> 1012898 INFO  
(SUITE-ScheduledMaintenanceTriggerTest-seed#[E51364AF66639CAA]-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_E51364AF66639CAA-001/tempDir-001
   [junit4]   2> 1012899 INFO  
(SUITE-ScheduledMaintenanceTriggerTest-seed#[E51364AF66639CAA]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1012913 INFO  (Thread-6222) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1012913 INFO  (Thread-6222) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 1012915 ERROR (Thread-6222) [    ] 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> 1013013 INFO  
(SUITE-ScheduledMaintenanceTriggerTest-seed#[E51364AF66639CAA]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:46651
   [junit4]   2> 1013017 INFO  (zkConnectionManagerCallback-4830-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1013020 INFO  (jetty-launcher-4827-thread-1) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
٢٠١٧-١١-٢٢T٠٨:٢٧:٣٧+11:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 1013022 INFO  (jetty-launcher-4827-thread-1) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1013022 INFO  (jetty-launcher-4827-thread-1) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1013022 INFO  (jetty-launcher-4827-thread-1) [    ] 
o.e.j.s.session Scavenging every 660000ms
   [junit4]   2> 1013022 INFO  (jetty-launcher-4827-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@5d9bfb56{/solr,null,AVAILABLE}
   [junit4]   2> 1013024 INFO  (jetty-launcher-4827-thread-1) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@4ba01cf4{SSL,[ssl, 
http/1.1]}{127.0.0.1:36553}
   [junit4]   2> 1013024 INFO  (jetty-launcher-4827-thread-1) [    ] 
o.e.j.s.Server Started @1015908ms
   [junit4]   2> 1013024 INFO  (jetty-launcher-4827-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=36553}
   [junit4]   2> 1013024 ERROR (jetty-launcher-4827-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1013024 INFO  (jetty-launcher-4827-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 1013024 INFO  (jetty-launcher-4827-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1013024 INFO  (jetty-launcher-4827-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1013024 INFO  (jetty-launcher-4827-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-03-13T13:14:39.440527Z
   [junit4]   2> 1013025 INFO  (zkConnectionManagerCallback-4832-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1013025 INFO  (jetty-launcher-4827-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1013029 INFO  (jetty-launcher-4827-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46651/solr
   [junit4]   2> 1013030 INFO  (zkConnectionManagerCallback-4836-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1013031 INFO  
(zkConnectionManagerCallback-4838-thread-1-processing-n:127.0.0.1:36553_solr) 
[n:127.0.0.1:36553_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1013063 INFO  (jetty-launcher-4827-thread-1) 
[n:127.0.0.1:36553_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1013063 INFO  (jetty-launcher-4827-thread-1) 
[n:127.0.0.1:36553_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:36553_solr
   [junit4]   2> 1013064 INFO  (jetty-launcher-4827-thread-1) 
[n:127.0.0.1:36553_solr    ] o.a.s.c.Overseer Overseer 
(id=72128666940276739-127.0.0.1:36553_solr-n_0000000000) starting
   [junit4]   2> 1013070 INFO  
(zkConnectionManagerCallback-4843-thread-1-processing-n:127.0.0.1:36553_solr) 
[n:127.0.0.1:36553_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1013071 INFO  (jetty-launcher-4827-thread-1) 
[n:127.0.0.1:36553_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:46651/solr ready
   [junit4]   2> 1013072 INFO  (jetty-launcher-4827-thread-1) 
[n:127.0.0.1:36553_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:36553_solr
   [junit4]   2> 1013072 DEBUG 
(OverseerAutoScalingTriggerThread-72128666940276739-127.0.0.1:36553_solr-n_0000000000)
 [n:127.0.0.1:36553_solr    ] o.a.s.c.a.OverseerTriggerThread Adding 
.auto_add_replicas and .scheduled_maintenance triggers
   [junit4]   2> 1013072 INFO  
(zkCallback-4842-thread-1-processing-n:127.0.0.1:36553_solr) 
[n:127.0.0.1:36553_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1013072 DEBUG 
(OverseerAutoScalingTriggerThread-72128666940276739-127.0.0.1:36553_solr-n_0000000000)
 [n:127.0.0.1:36553_solr    ] o.a.s.c.a.OverseerTriggerThread Refreshing 
/autoscaling.json with znode version 1
   [junit4]   2> 1013073 DEBUG 
(OverseerAutoScalingTriggerThread-72128666940276739-127.0.0.1:36553_solr-n_0000000000)
 [n:127.0.0.1:36553_solr    ] o.a.s.c.a.NodeLostTrigger Initial livenodes: 
[127.0.0.1:36553_solr]
   [junit4]   2> 1013073 DEBUG 
(OverseerAutoScalingTriggerThread-72128666940276739-127.0.0.1:36553_solr-n_0000000000)
 [n:127.0.0.1:36553_solr    ] o.a.s.c.a.OverseerTriggerThread Current 
znodeVersion 1, lastZnodeVersion -1
   [junit4]   2> 1013073 DEBUG 
(OverseerAutoScalingTriggerThread-72128666940276739-127.0.0.1:36553_solr-n_0000000000)
 [n:127.0.0.1:36553_solr    ] o.a.s.c.a.OverseerTriggerThread Processed trigger 
updates upto znodeVersion 1
   [junit4]   2> 1013085 INFO  
(zkCallback-4837-thread-1-processing-n:127.0.0.1:36553_solr) 
[n:127.0.0.1:36553_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1013107 DEBUG (ScheduledTrigger-5598-thread-1) 
[n:127.0.0.1:36553_solr    ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: 
.auto_add_replicas with currently live nodes: 1
   [junit4]   2> 1013115 INFO  (jetty-launcher-4827-thread-1) 
[n:127.0.0.1:36553_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_36553.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@49fc2501
   [junit4]   2> 1013116 DEBUG 
(OverseerAutoScalingTriggerThread-72128666940276739-127.0.0.1:36553_solr-n_0000000000)
 [n:127.0.0.1:36553_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeAdded markers
   [junit4]   2> 1013117 DEBUG 
(OverseerAutoScalingTriggerThread-72128666940276739-127.0.0.1:36553_solr-n_0000000000)
 [n:127.0.0.1:36553_solr    ] o.a.s.c.a.OverseerTriggerThread Current 
znodeVersion 1, lastZnodeVersion 1
   [junit4]   2> 1013123 INFO  (jetty-launcher-4827-thread-1) 
[n:127.0.0.1:36553_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_36553.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@49fc2501
   [junit4]   2> 1013123 INFO  (jetty-launcher-4827-thread-1) 
[n:127.0.0.1:36553_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_36553.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@49fc2501
   [junit4]   2> 1013124 INFO  (jetty-launcher-4827-thread-1) 
[n:127.0.0.1:36553_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_E51364AF66639CAA-001/tempDir-001/node1/.
   [junit4]   2> 1013138 INFO  (zkConnectionManagerCallback-4845-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1013146 INFO  (zkConnectionManagerCallback-4849-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1013146 INFO  
(SUITE-ScheduledMaintenanceTriggerTest-seed#[E51364AF66639CAA]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1013147 INFO  
(SUITE-ScheduledMaintenanceTriggerTest-seed#[E51364AF66639CAA]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46651/solr ready
   [junit4]   2> 1013149 DEBUG (Simulated OverseerAutoScalingTriggerThread) [   
 ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and 
.scheduled_maintenance triggers
   [junit4]   2> 1013149 DEBUG (Simulated OverseerAutoScalingTriggerThread) [   
 ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode 
version 0
   [junit4]   2> 1013151 DEBUG (Simulated OverseerAutoScalingTriggerThread) [   
 ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10009_solr]
   [junit4]   2> 1013151 DEBUG (Simulated OverseerAutoScalingTriggerThread) [   
 ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion -1
   [junit4]   2> 1013151 DEBUG (Simulated OverseerAutoScalingTriggerThread) [   
 ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 0
   [junit4]   2> 1013154 DEBUG (Simulated OverseerAutoScalingTriggerThread) [   
 ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
   [junit4]   2> 1013157 INFO  
(TEST-ScheduledMaintenanceTriggerTest.testTriggerDefaults-seed#[E51364AF66639CAA])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testTriggerDefaults
   [junit4]   2> 1013157 DEBUG (Simulated OverseerAutoScalingTriggerThread) [   
 ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion 0
   [junit4]   2> 1013157 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013157 INFO  
(TEST-ScheduledMaintenanceTriggerTest.testTriggerDefaults-seed#[E51364AF66639CAA])
 [    ] o.a.s.c.a.ScheduledMaintenanceTriggerTest {
   [junit4]   2>   "cluster-preferences":[
   [junit4]   2>     {
   [junit4]   2>       "minimize":"cores",
   [junit4]   2>       "precision":1},
   [junit4]   2>     {"maximize":"freedisk"}],
   [junit4]   2>   "triggers":{
   [junit4]   2>     ".scheduled_maintenance":{
   [junit4]   2>       "name":".scheduled_maintenance",
   [junit4]   2>       "startTime":"NOW",
   [junit4]   2>       "event":"scheduled",
   [junit4]   2>       "every":"+1DAY",
   [junit4]   2>       "actions":[
   [junit4]   2>         {
   [junit4]   2>           "name":"inactive_shard_plan",
   [junit4]   2>           "class":"solr.InactiveShardPlanAction"},
   [junit4]   2>         {
   [junit4]   2>           "name":"execute_plan",
   [junit4]   2>           "class":"solr.ExecutePlanAction"}],
   [junit4]   2>       "enabled":true},
   [junit4]   2>     ".auto_add_replicas":{
   [junit4]   2>       "name":".auto_add_replicas",
   [junit4]   2>       "event":"nodeLost",
   [junit4]   2>       "waitFor":120,
   [junit4]   2>       "actions":[
   [junit4]   2>         {
   [junit4]   2>           "name":"auto_add_replicas_plan",
   [junit4]   2>           "class":"solr.AutoAddReplicasPlanAction"},
   [junit4]   2>         {
   [junit4]   2>           "name":"execute_plan",
   [junit4]   2>           "class":"solr.ExecutePlanAction"}],
   [junit4]   2>       "enabled":true}},
   [junit4]   2>   "listeners":{
   [junit4]   2>     ".scheduled_maintenance.system":{
   [junit4]   2>       "trigger":".scheduled_maintenance",
   [junit4]   2>       "afterAction":[],
   [junit4]   2>       "stage":[
   [junit4]   2>         "STARTED",
   [junit4]   2>         "ABORTED",
   [junit4]   2>         "SUCCEEDED",
   [junit4]   2>         "FAILED",
   [junit4]   2>         "BEFORE_ACTION",
   [junit4]   2>         "AFTER_ACTION",
   [junit4]   2>         "IGNORED"],
   [junit4]   2>       
"class":"org.apache.solr.cloud.autoscaling.SystemLogListener",
   [junit4]   2>       "beforeAction":[]},
   [junit4]   2>     ".auto_add_replicas.system":{
   [junit4]   2>       "trigger":".auto_add_replicas",
   [junit4]   2>       "afterAction":[],
   [junit4]   2>       "stage":[
   [junit4]   2>         "STARTED",
   [junit4]   2>         "ABORTED",
   [junit4]   2>         "SUCCEEDED",
   [junit4]   2>         "FAILED",
   [junit4]   2>         "BEFORE_ACTION",
   [junit4]   2>         "AFTER_ACTION",
   [junit4]   2>         "IGNORED"],
   [junit4]   2>       
"class":"org.apache.solr.cloud.autoscaling.SystemLogListener",
   [junit4]   2>       "beforeAction":[]}},
   [junit4]   2>   "properties":{}}
   [junit4]   2> 1013158 DEBUG (simCloudManagerPool-5604-thread-1) [    ] 
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 1013158 DEBUG (simCloudManagerPool-5604-thread-1) [    ] 
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 
1
   [junit4]   2> 1013158 DEBUG (simCloudManagerPool-5604-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10009_solr]
   [junit4]   2> 1013158 DEBUG (Simulated OverseerAutoScalingTriggerThread) [   
 ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
   [junit4]   2> 1013159 INFO  
(TEST-ScheduledMaintenanceTriggerTest.testTriggerDefaults-seed#[E51364AF66639CAA])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testTriggerDefaults
   [junit4]   2> 1013162 DEBUG (Simulated OverseerAutoScalingTriggerThread) [   
 ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
   [junit4]   2> 1013162 DEBUG (Simulated OverseerAutoScalingTriggerThread) [   
 ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
   [junit4]   2> 1013166 INFO  
(TEST-ScheduledMaintenanceTriggerTest.testInactiveShardCleanup-seed#[E51364AF66639CAA])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testInactiveShardCleanup
   [junit4]   2> 1013177 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013198 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013221 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013241 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013262 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013282 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013302 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013315 DEBUG (simCloudManagerPool-5604-thread-6) [    ] 
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for 
ScheduledMaintenanceTriggerTest_collection1 / shard1_1: {"core_node5":{
   [junit4]   2>     "node_name":"127.0.0.1:10009_solr",
   [junit4]   2>     "base_url":"http://127.0.0.1:10009/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> 1013315 DEBUG (simCloudManagerPool-5604-thread-7) [    ] 
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for 
ScheduledMaintenanceTriggerTest_collection1 / shard1_0: {"core_node4":{
   [junit4]   2>     "node_name":"127.0.0.1:10009_solr",
   [junit4]   2>     "base_url":"http://127.0.0.1:10009/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> 1013315 DEBUG (simCloudManagerPool-5604-thread-5) [    ] 
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for 
ScheduledMaintenanceTriggerTest_collection1 / shard1: {"core_node1":{
   [junit4]   2>     "node_name":"127.0.0.1:10009_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> 1013319 DEBUG (simCloudManagerPool-5604-thread-8) [    ] 
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 1013319 DEBUG (simCloudManagerPool-5604-thread-8) [    ] 
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 
2
   [junit4]   2> 1013319 DEBUG (simCloudManagerPool-5604-thread-8) [    ] 
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10009_solr]
   [junit4]   2> 1013319 DEBUG (Simulated OverseerAutoScalingTriggerThread) [   
 ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
   [junit4]   2> 1013320 DEBUG (Simulated OverseerAutoScalingTriggerThread) [   
 ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
   [junit4]   2> 1013320 DEBUG (Simulated OverseerAutoScalingTriggerThread) [   
 ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
   [junit4]   2> 1013320 DEBUG (simCloudManagerPool-5604-thread-9) [    ] 
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 1013320 DEBUG (simCloudManagerPool-5604-thread-9) [    ] 
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 
3
   [junit4]   2> 1013321 DEBUG (simCloudManagerPool-5604-thread-9) [    ] 
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10009_solr]
   [junit4]   2> 1013321 DEBUG (Simulated OverseerAutoScalingTriggerThread) [   
 ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
   [junit4]   2> 1013321 DEBUG (Simulated OverseerAutoScalingTriggerThread) [   
 ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
   [junit4]   2> 1013321 DEBUG (Simulated OverseerAutoScalingTriggerThread) [   
 ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
   [junit4]   2> 1013321 WARN  (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:14:41.566Z. Now: 2018-03-13T19:34:17.016Z
   [junit4]   2> 1013321 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f7fa41eT74q2rv8c54d1igwhfs3hmphph",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946881566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969657016},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013322 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013342 WARN  (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:14:43.566Z. Now: 2018-03-13T19:34:18.086Z
   [junit4]   2> 1013342 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013343 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f7fabeeT74q2rv8c54d1igwhfs3hmphpj",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946883566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969658086},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013363 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013363 WARN  (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:14:45.566Z. Now: 2018-03-13T19:34:19.117Z
   [junit4]   2> 1013363 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f7fb3beT74q2rv8c54d1igwhfs3hmphpl",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946885566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969659117},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013383 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013384 WARN  (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:14:47.566Z. Now: 2018-03-13T19:34:20.160Z
   [junit4]   2> 1013384 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f7fbb8eT74q2rv8c54d1igwhfs3hmphpn",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946887566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969660160},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013403 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013405 WARN  (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:14:49.566Z. Now: 2018-03-13T19:34:21.200Z
   [junit4]   2> 1013405 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f7fc35eT74q2rv8c54d1igwhfs3hmphpp",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946889566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969661200},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013423 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013426 WARN  (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:14:51.566Z. Now: 2018-03-13T19:34:22.245Z
   [junit4]   2> 1013426 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f7fcb2eT74q2rv8c54d1igwhfs3hmphpr",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946891566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969662245},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013444 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013446 WARN  (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:14:53.566Z. Now: 2018-03-13T19:34:23.282Z
   [junit4]   2> 1013447 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f7fd2feT74q2rv8c54d1igwhfs3hmphpt",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946893566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969663282},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013464 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013467 WARN  (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:14:55.566Z. Now: 2018-03-13T19:34:24.333Z
   [junit4]   2> 1013467 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f7fdaceT74q2rv8c54d1igwhfs3hmphpv",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946895566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969664333},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013484 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013488 WARN  (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:14:57.566Z. Now: 2018-03-13T19:34:25.362Z
   [junit4]   2> 1013488 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f7fe29eT74q2rv8c54d1igwhfs3hmphpx",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946897566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969665362},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013504 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013509 WARN  (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:14:59.566Z. Now: 2018-03-13T19:34:26.395Z
   [junit4]   2> 1013509 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f7fea6eT74q2rv8c54d1igwhfs3hmphpz",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946899566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969666395},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013525 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013529 WARN  (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:15:01.566Z. Now: 2018-03-13T19:34:27.436Z
   [junit4]   2> 1013530 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f7ff23eT74q2rv8c54d1igwhfs3hmphq1",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946901566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969667436},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013545 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013550 WARN  (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:15:03.566Z. Now: 2018-03-13T19:34:28.473Z
   [junit4]   2> 1013550 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f7ffa0eT74q2rv8c54d1igwhfs3hmphq3",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946903566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969668473},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013565 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013571 WARN  (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:15:05.566Z. Now: 2018-03-13T19:34:29.532Z
   [junit4]   2> 1013572 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f8001deT74q2rv8c54d1igwhfs3hmphq5",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946905566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969669532},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013585 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013592 WARN  (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:15:07.566Z. Now: 2018-03-13T19:34:30.574Z
   [junit4]   2> 1013592 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f8009aeT74q2rv8c54d1igwhfs3hmphq7",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946907566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969670574},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013606 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013613 WARN  (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:15:09.566Z. Now: 2018-03-13T19:34:31.624Z
   [junit4]   2> 1013613 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f80117eT74q2rv8c54d1igwhfs3hmphq9",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946909566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969671624},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013626 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013634 WARN  (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:15:11.566Z. Now: 2018-03-13T19:34:32.666Z
   [junit4]   2> 1013634 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f80194eT74q2rv8c54d1igwhfs3hmphqb",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946911566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969672666},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013646 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013655 WARN  (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:15:13.566Z. Now: 2018-03-13T19:34:33.706Z
   [junit4]   2> 1013655 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f80211eT74q2rv8c54d1igwhfs3hmphqd",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946913566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969673706},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013666 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013678 WARN  (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:15:15.566Z. Now: 2018-03-13T19:34:34.847Z
   [junit4]   2> 1013678 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f8028eeT74q2rv8c54d1igwhfs3hmphqf",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946915566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969674847},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013686 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013701 WARN  (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:15:17.566Z. Now: 2018-03-13T19:34:36.019Z
   [junit4]   2> 1013701 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f8030beT74q2rv8c54d1igwhfs3hmphqh",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946917566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969676019},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013707 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013722 WARN  (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:15:19.566Z. Now: 2018-03-13T19:34:37.058Z
   [junit4]   2> 1013722 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f80388eT74q2rv8c54d1igwhfs3hmphqj",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946919566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969677058},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013727 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013745 WARN  (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:15:21.566Z. Now: 2018-03-13T19:34:38.216Z
   [junit4]   2> 1013745 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f80405eT74q2rv8c54d1igwhfs3hmphql",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946921566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969678216},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013747 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013766 WARN  (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:15:23.566Z. Now: 2018-03-13T19:34:39.259Z
   [junit4]   2> 1013766 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f80482eT74q2rv8c54d1igwhfs3hmphqn",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946923566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969679259},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013767 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013787 WARN  (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:15:25.566Z. Now: 2018-03-13T19:34:40.294Z
   [junit4]   2> 1013787 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f804ffeT74q2rv8c54d1igwhfs3hmphqp",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946925566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969680294},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013787 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013809 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013825 WARN  (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:15:27.566Z. Now: 2018-03-13T19:34:42.233Z
   [junit4]   2> 1013826 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f8057ceT74q2rv8c54d1igwhfs3hmphqr",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946927566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969682233},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013833 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013847 WARN  (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:15:29.566Z. Now: 2018-03-13T19:34:43.300Z
   [junit4]   2> 1013847 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f805f9eT74q2rv8c54d1igwhfs3hmphqt",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946929566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969683300},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013853 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013868 WARN  (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:15:31.566Z. Now: 2018-03-13T19:34:44.346Z
   [junit4]   2> 1013868 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f80676eT74q2rv8c54d1igwhfs3hmphqv",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946931566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969684346},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013874 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013888 WARN  (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:15:33.566Z. Now: 2018-03-13T19:34:45.388Z
   [junit4]   2> 1013889 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f806f3eT74q2rv8c54d1igwhfs3hmphqx",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946933566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969685388},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013894 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013909 WARN  (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:15:35.566Z. Now: 2018-03-13T19:34:46.430Z
   [junit4]   2> 1013909 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f80770eT74q2rv8c54d1igwhfs3hmphqz",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946935566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969686430},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013914 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013930 WARN  (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:15:37.566Z. Now: 2018-03-13T19:34:47.473Z
   [junit4]   2> 1013930 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f807edeT74q2rv8c54d1igwhfs3hmphr1",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946937566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969687473},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013934 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013951 WARN  (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:15:39.566Z. Now: 2018-03-13T19:34:48.512Z
   [junit4]   2> 1013951 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f8086aeT74q2rv8c54d1igwhfs3hmphr3",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946939566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969688512},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013955 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013972 WARN  (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:15:41.566Z. Now: 2018-03-13T19:34:49.544Z
   [junit4]   2> 1013972 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f808e7eT74q2rv8c54d1igwhfs3hmphr5",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946941566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969689544},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013975 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1013992 WARN  (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:15:43.566Z. Now: 2018-03-13T19:34:50.587Z
   [junit4]   2> 1013993 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f80964eT74q2rv8c54d1igwhfs3hmphr7",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946943566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969690587},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1013995 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014013 WARN  (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:15:45.566Z. Now: 2018-03-13T19:34:51.623Z
   [junit4]   2> 1014013 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f809e1eT74q2rv8c54d1igwhfs3hmphr9",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946945566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969691623},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014015 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014034 WARN  (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:15:47.566Z. Now: 2018-03-13T19:34:52.660Z
   [junit4]   2> 1014034 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f80a5eeT74q2rv8c54d1igwhfs3hmphrb",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946947566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969692660},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014035 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014056 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014062 WARN  (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:15:49.566Z. Now: 2018-03-13T19:34:54.066Z
   [junit4]   2> 1014062 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f80adbeT74q2rv8c54d1igwhfs3hmphrd",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946949566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969694066},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014076 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014083 WARN  (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:15:51.566Z. Now: 2018-03-13T19:34:55.115Z
   [junit4]   2> 1014083 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f80b58eT74q2rv8c54d1igwhfs3hmphrf",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946951566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969695115},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014096 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014104 WARN  (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:15:53.566Z. Now: 2018-03-13T19:34:56.156Z
   [junit4]   2> 1014104 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f80bd5eT74q2rv8c54d1igwhfs3hmphrh",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946953566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969696156},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014108 DEBUG (ScheduledTrigger-5598-thread-3) 
[n:127.0.0.1:36553_solr    ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: 
.auto_add_replicas with currently live nodes: 1
   [junit4]   2> 1014116 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014126 WARN  (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:15:55.566Z. Now: 2018-03-13T19:34:57.248Z
   [junit4]   2> 1014126 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f80c52eT74q2rv8c54d1igwhfs3hmphrj",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946955566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969697248},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014137 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014146 WARN  (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:15:57.566Z. Now: 2018-03-13T19:34:58.289Z
   [junit4]   2> 1014147 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f80ccfeT74q2rv8c54d1igwhfs3hmphrl",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946957566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969698289},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014157 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014170 WARN  (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:15:59.566Z. Now: 2018-03-13T19:34:59.451Z
   [junit4]   2> 1014170 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f80d4ceT74q2rv8c54d1igwhfs3hmphrn",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946959566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969699451},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014177 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014191 WARN  (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:16:01.566Z. Now: 2018-03-13T19:35:00.494Z
   [junit4]   2> 1014191 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f80dc9eT74q2rv8c54d1igwhfs3hmphrp",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946961566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969700494},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014197 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014211 WARN  (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:16:03.566Z. Now: 2018-03-13T19:35:01.532Z
   [junit4]   2> 1014212 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f80e46eT74q2rv8c54d1igwhfs3hmphrr",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946963566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969701532},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014218 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014232 WARN  (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:16:05.566Z. Now: 2018-03-13T19:35:02.576Z
   [junit4]   2> 1014232 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f80ec3eT74q2rv8c54d1igwhfs3hmphrt",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946965566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969702576},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014238 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014253 WARN  (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:16:07.566Z. Now: 2018-03-13T19:35:03.614Z
   [junit4]   2> 1014253 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f80f40eT74q2rv8c54d1igwhfs3hmphrv",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946967566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969703614},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014258 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014274 WARN  (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:16:09.566Z. Now: 2018-03-13T19:35:04.655Z
   [junit4]   2> 1014274 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f80fbdeT74q2rv8c54d1igwhfs3hmphrx",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946969566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969704655},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014278 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014295 WARN  (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:16:11.566Z. Now: 2018-03-13T19:35:05.699Z
   [junit4]   2> 1014295 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f8103aeT74q2rv8c54d1igwhfs3hmphrz",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946971566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969705699},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014298 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014315 WARN  (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:16:13.566Z. Now: 2018-03-13T19:35:06.742Z
   [junit4]   2> 1014316 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f810b7eT74q2rv8c54d1igwhfs3hmphs1",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946973566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969706742},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014319 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014336 WARN  (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:16:15.566Z. Now: 2018-03-13T19:35:07.783Z
   [junit4]   2> 1014336 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f81134eT74q2rv8c54d1igwhfs3hmphs3",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946975566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969707783},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014339 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014357 WARN  (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:16:17.566Z. Now: 2018-03-13T19:35:08.819Z
   [junit4]   2> 1014357 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f811b1eT74q2rv8c54d1igwhfs3hmphs5",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946977566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969708819},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014359 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014378 WARN  (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:16:19.566Z. Now: 2018-03-13T19:35:09.857Z
   [junit4]   2> 1014378 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f8122eeT74q2rv8c54d1igwhfs3hmphs7",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946979566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969709857},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014379 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014399 WARN  (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:16:21.566Z. Now: 2018-03-13T19:35:10.896Z
   [junit4]   2> 1014399 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f812abeT74q2rv8c54d1igwhfs3hmphs9",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946981566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969710896},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014399 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014419 WARN  (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:16:23.566Z. Now: 2018-03-13T19:35:11.941Z
   [junit4]   2> 1014420 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f81328eT74q2rv8c54d1igwhfs3hmphsb",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946983566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969711941},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014420 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014440 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014440 WARN  (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:16:25.566Z. Now: 2018-03-13T19:35:12.982Z
   [junit4]   2> 1014440 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f813a5eT74q2rv8c54d1igwhfs3hmphsd",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946985566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969712982},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014460 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014462 WARN  (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:16:27.566Z. Now: 2018-03-13T19:35:14.046Z
   [junit4]   2> 1014462 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f81422eT74q2rv8c54d1igwhfs3hmphsf",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946987566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969714046},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014481 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014486 WARN  (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:16:29.566Z. Now: 2018-03-13T19:35:15.248Z
   [junit4]   2> 1014486 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f8149feT74q2rv8c54d1igwhfs3hmphsh",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946989566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969715248},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014501 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014521 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014521 WARN  (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:16:31.566Z. Now: 2018-03-13T19:35:17.040Z
   [junit4]   2> 1014522 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f8151ceT74q2rv8c54d1igwhfs3hmphsj",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946991566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969717040},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014541 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014549 WARN  (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:16:33.566Z. Now: 2018-03-13T19:35:18.417Z
   [junit4]   2> 1014549 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f81599eT74q2rv8c54d1igwhfs3hmphsl",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946993566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969718417},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014561 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014572 WARN  (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:16:35.566Z. Now: 2018-03-13T19:35:19.562Z
   [junit4]   2> 1014572 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f81616eT74q2rv8c54d1igwhfs3hmphsn",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946995566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969719562},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014582 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014598 WARN  (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:16:37.566Z. Now: 2018-03-13T19:35:20.847Z
   [junit4]   2> 1014598 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f81693eT74q2rv8c54d1igwhfs3hmphsp",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946997566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969720847},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014602 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014622 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014626 WARN  (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:16:39.566Z. Now: 2018-03-13T19:35:22.248Z
   [junit4]   2> 1014626 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f81710eT74q2rv8c54d1igwhfs3hmphsr",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520946999566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969722248},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014642 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014646 WARN  (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:16:41.566Z. Now: 2018-03-13T19:35:23.289Z
   [junit4]   2> 1014647 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f8178deT74q2rv8c54d1igwhfs3hmphst",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520947001566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969723289},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014663 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014667 WARN  (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:16:43.566Z. Now: 2018-03-13T19:35:24.328Z
   [junit4]   2> 1014667 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f8180aeT74q2rv8c54d1igwhfs3hmphsv",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520947003566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969724328},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014683 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014688 WARN  (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:16:45.566Z. Now: 2018-03-13T19:35:25.366Z
   [junit4]   2> 1014688 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f81887eT74q2rv8c54d1igwhfs3hmphsx",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520947005566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969725366},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014703 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014709 WARN  (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:16:47.566Z. Now: 2018-03-13T19:35:26.406Z
   [junit4]   2> 1014709 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f81904eT74q2rv8c54d1igwhfs3hmphsz",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520947007566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969726406},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014723 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014730 WARN  (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:16:49.566Z. Now: 2018-03-13T19:35:27.447Z
   [junit4]   2> 1014730 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f81981eT74q2rv8c54d1igwhfs3hmpht1",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520947009566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969727447},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014743 DEBUG (ScheduledTrigger-5605-thread-4) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014750 WARN  (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:16:51.566Z. Now: 2018-03-13T19:35:28.488Z
   [junit4]   2> 1014751 DEBUG (ScheduledTrigger-5605-thread-1) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f819feeT74q2rv8c54d1igwhfs3hmpht3",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520947011566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969728488},
   [junit4]   2>   "ignored":true}
   [junit4]   2> 1014764 DEBUG (ScheduledTrigger-5605-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1
   [junit4]   2> 1014771 WARN  (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.ScheduledTrigger ScheduledTrigger was not able to run event at 
scheduled time: 2018-03-13T13:16:53.566Z. Now: 2018-03-13T19:35:29.530Z
   [junit4]   2> 1014771 DEBUG (ScheduledTrigger-5605-thread-3) [    ] 
o.a.s.c.a.ScheduledTriggers -------- Ignoring event: {
   [junit4]   2>   "id":"1621f81a7beT74q2rv8c54d1igwhfs3hmpht5",
   [junit4]   2>   "source":".scheduled_maintenance",
   [junit4]   2>   "eventTime":1520947013566,
   [junit4]   2>   "eventType":"SCHEDULED",
   [junit4]   2>   "properties":{"actualEventTime":1520969729530},
   [junit4]   2>   "ignored":

[...truncated too long message...]

DD0720BBA1]: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> 7972 WARN  (AutoscalingActionExecutor-29-thread-1) [    ] 
o.a.s.c.a.ScheduledTriggers Exception executing actions
   [junit4]   2> org.apache.lucene.store.AlreadyClosedException: 
ScheduledTrigger .scheduled_maintenance has been closed.
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.ScheduledTriggers$TriggerWrapper.dequeue(ScheduledTriggers.java: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:1135)
   [junit4]   2>        at 
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
   [junit4]   2>        at java.base/java.lang.Thread.run(Thread.java:844)
   [junit4]   2> 7974 DEBUG (AutoscalingActionExecutor-29-thread-1) [    ] 
o.a.s.c.a.ScheduledTriggers Resuming trigger: .auto_add_replicas after 100ms
   [junit4]   2> 7974 DEBUG (AutoscalingActionExecutor-29-thread-1) [    ] 
o.a.s.c.a.ScheduledTriggers -- processing took 160 ms for event 
id=3dbfc04492d69T74q2rv8c54d1igwhfs3hmpeww
   [junit4]   2> 7977 WARN  (Simulated OverseerAutoScalingTriggerThread) [    ] 
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are 
closed, exiting.
   [junit4]   2> 7977 DEBUG 
(SUITE-ScheduledMaintenanceTriggerTest-seed#[E51364AF66639CAA]-worker) [    ] 
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
   [junit4]   2> 7982 INFO  (jetty-closer-25-thread-1) [    ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@15ab7cb4{SSL,[ssl, 
http/1.1]}{127.0.0.1:0}
   [junit4]   2> 7982 INFO  (jetty-closer-25-thread-1) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=196924606
   [junit4]   2> 7982 INFO  (jetty-closer-25-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 7983 INFO  (jetty-closer-25-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@623b7c9: rootName = 
solr_39537, domain = solr.node, service url = null, agent id = null] for 
registry solr.node / com.codahale.metrics.MetricRegistry@6e873f65
   [junit4]   2> 7993 INFO  (jetty-closer-25-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 7993 INFO  (jetty-closer-25-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@131a860e: rootName = 
solr_39537, domain = solr.jvm, service url = null, agent id = null] for 
registry solr.jvm / com.codahale.metrics.MetricRegistry@44779dd2
   [junit4]   2> 8001 INFO  (jetty-closer-25-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 8001 INFO  (jetty-closer-25-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@4ecdec77: rootName = 
solr_39537, domain = solr.jetty, service url = null, agent id = null] for 
registry solr.jetty / com.codahale.metrics.MetricRegistry@13e2122d
   [junit4]   2> 8002 INFO  (jetty-closer-25-thread-1) [    ] 
o.a.s.c.ZkController Remove node as live in 
ZooKeeper:/live_nodes/127.0.0.1:39537_solr
   [junit4]   2> 8003 INFO  (jetty-closer-25-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, 
tag=null
   [junit4]   2> 8003 INFO  
(zkCallback-39-thread-1-processing-n:127.0.0.1:39537_solr) 
[n:127.0.0.1:39537_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (0)
   [junit4]   2> 8003 INFO  
(zkCallback-34-thread-1-processing-n:127.0.0.1:39537_solr) 
[n:127.0.0.1:39537_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (0)
   [junit4]   2> 8003 INFO  (jetty-closer-25-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 8004 INFO  (jetty-closer-25-thread-1) [    ] o.a.s.c.Overseer 
Overseer (id=72128774695550979-127.0.0.1:39537_solr-n_0000000000) closing
   [junit4]   2> 8004 INFO  
(OverseerStateUpdate-72128774695550979-127.0.0.1:39537_solr-n_0000000000) 
[n:127.0.0.1:39537_solr    ] o.a.s.c.Overseer Overseer Loop exiting : 
127.0.0.1:39537_solr
   [junit4]   2> 8004 WARN  
(OverseerAutoScalingTriggerThread-72128774695550979-127.0.0.1:39537_solr-n_0000000000)
 [n:127.0.0.1:39537_solr    ] o.a.s.c.a.OverseerTriggerThread 
OverseerTriggerThread woken up but we are closed, exiting.
   [junit4]   2> 8004 DEBUG (jetty-closer-25-thread-1) [    ] 
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
   [junit4]   2> 8006 INFO  (jetty-closer-25-thread-1) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@5516077b{/solr,null,UNAVAILABLE}
   [junit4]   2> 8006 INFO  (jetty-closer-25-thread-1) [    ] o.e.j.s.session 
Stopped scavenging
   [junit4]   2> 8006 ERROR 
(SUITE-ScheduledMaintenanceTriggerTest-seed#[E51364AF66639CAA]-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> 8007 INFO  
(SUITE-ScheduledMaintenanceTriggerTest-seed#[E51364AF66639CAA]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:37651 37651
   [junit4]   2> 9635 INFO  (Thread-11) [    ] o.a.s.c.ZkTestServer connecting 
to 127.0.0.1:37651 37651
   [junit4]   2> 9635 WARN  (Thread-11) [    ] o.a.s.c.ZkTestServer Watch limit 
violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        3       /solr/aliases.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        3       /solr/clusterstate.json
   [junit4]   2>        3       /solr/clusterprops.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        3       /solr/live_nodes
   [junit4]   2>        3       /solr/collections
   [junit4]   2> 
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest_E51364AF66639CAA-001
   [junit4]   2> NOTE: test params are: 
codec=FastCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST,
 chunkSize=5792, maxDocsPerChunk=3, blockSize=3), 
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST, 
chunkSize=5792, blockSize=3)), 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@4c53f5c7),
 locale=kam-KE, timezone=America/Kralendijk
   [junit4]   2> NOTE: Linux 4.13.0-36-generic amd64/Oracle Corporation 10 
(64-bit)/cpus=8,threads=1,free=353638392,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: 
[ScheduledMaintenanceTriggerTest, ScheduledMaintenanceTriggerTest]
   [junit4] Completed [5/5 (2!)] on J1 in 2.22s, 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: 
E51364AF66639CAA]

Total time: 13 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=cc3c4b61237619798a006217223fb6e78a688a4e, 
workspace=/var/lib/jenkins/workspace/Lucene-Solr-master-Linux
[WARNINGS] Computing warning deltas based on reference build #21632
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: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org