Build: https://jenkins.thetaphi.de/job/Lucene-Solr-BadApples-master-Linux/126/ Java: 64bit/jdk1.8.0_172 -XX:+UseCompressedOops -XX:+UseParallelGC
13 tests failed. FAILED: org.apache.solr.cloud.api.collections.CollectionsAPIDistributedZkTest.testCollectionsAPI Error Message: Error from server at https://127.0.0.1:39679/solr/awhollynewcollection_0: No registered leader was found after waiting for 4000ms , collection: awhollynewcollection_0 slice: shard1 saw state=DocCollection(awhollynewcollection_0//collections/awhollynewcollection_0/state.json/9)={ "pullReplicas":"0", "replicationFactor":"2", "shards":{ "shard1":{ "range":"80000000-d554ffff", "state":"active", "replicas":{ "core_node2":{ "core":"awhollynewcollection_0_shard1_replica_n1", "base_url":"https://127.0.0.1:44789/solr", "node_name":"127.0.0.1:44789_solr", "state":"active", "type":"NRT", "force_set_state":"false"}, "core_node4":{ "core":"awhollynewcollection_0_shard1_replica_n3", "base_url":"https://127.0.0.1:42617/solr", "node_name":"127.0.0.1:42617_solr", "state":"down", "type":"NRT", "force_set_state":"false"}}}, "shard2":{ "range":"d5550000-2aa9ffff", "state":"active", "replicas":{ "core_node7":{ "core":"awhollynewcollection_0_shard2_replica_n5", "base_url":"https://127.0.0.1:39679/solr", "node_name":"127.0.0.1:39679_solr", "state":"active", "type":"NRT", "force_set_state":"false", "leader":"true"}, "core_node8":{ "core":"awhollynewcollection_0_shard2_replica_n6", "base_url":"https://127.0.0.1:36039/solr", "node_name":"127.0.0.1:36039_solr", "state":"active", "type":"NRT", "force_set_state":"false"}}}, "shard3":{ "range":"2aaa0000-7fffffff", "state":"active", "replicas":{ "core_node10":{ "core":"awhollynewcollection_0_shard3_replica_n9", "base_url":"https://127.0.0.1:36039/solr", "node_name":"127.0.0.1:36039_solr", "state":"active", "type":"NRT", "force_set_state":"false"}, "core_node12":{ "core":"awhollynewcollection_0_shard3_replica_n11", "base_url":"https://127.0.0.1:39679/solr", "node_name":"127.0.0.1:39679_solr", "state":"active", "type":"NRT", "force_set_state":"false", "leader":"true"}}}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"2", "autoAddReplicas":"false", "nrtReplicas":"2", "tlogReplicas":"0"} with live_nodes=[127.0.0.1:36039_solr, 127.0.0.1:39679_solr, 127.0.0.1:42617_solr, 127.0.0.1:44789_solr] Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:39679/solr/awhollynewcollection_0: No registered leader was found after waiting for 4000ms , collection: awhollynewcollection_0 slice: shard1 saw state=DocCollection(awhollynewcollection_0//collections/awhollynewcollection_0/state.json/9)={ "pullReplicas":"0", "replicationFactor":"2", "shards":{ "shard1":{ "range":"80000000-d554ffff", "state":"active", "replicas":{ "core_node2":{ "core":"awhollynewcollection_0_shard1_replica_n1", "base_url":"https://127.0.0.1:44789/solr", "node_name":"127.0.0.1:44789_solr", "state":"active", "type":"NRT", "force_set_state":"false"}, "core_node4":{ "core":"awhollynewcollection_0_shard1_replica_n3", "base_url":"https://127.0.0.1:42617/solr", "node_name":"127.0.0.1:42617_solr", "state":"down", "type":"NRT", "force_set_state":"false"}}}, "shard2":{ "range":"d5550000-2aa9ffff", "state":"active", "replicas":{ "core_node7":{ "core":"awhollynewcollection_0_shard2_replica_n5", "base_url":"https://127.0.0.1:39679/solr", "node_name":"127.0.0.1:39679_solr", "state":"active", "type":"NRT", "force_set_state":"false", "leader":"true"}, "core_node8":{ "core":"awhollynewcollection_0_shard2_replica_n6", "base_url":"https://127.0.0.1:36039/solr", "node_name":"127.0.0.1:36039_solr", "state":"active", "type":"NRT", "force_set_state":"false"}}}, "shard3":{ "range":"2aaa0000-7fffffff", "state":"active", "replicas":{ "core_node10":{ "core":"awhollynewcollection_0_shard3_replica_n9", "base_url":"https://127.0.0.1:36039/solr", "node_name":"127.0.0.1:36039_solr", "state":"active", "type":"NRT", "force_set_state":"false"}, "core_node12":{ "core":"awhollynewcollection_0_shard3_replica_n11", "base_url":"https://127.0.0.1:39679/solr", "node_name":"127.0.0.1:39679_solr", "state":"active", "type":"NRT", "force_set_state":"false", "leader":"true"}}}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"2", "autoAddReplicas":"false", "nrtReplicas":"2", "tlogReplicas":"0"} with live_nodes=[127.0.0.1:36039_solr, 127.0.0.1:39679_solr, 127.0.0.1:42617_solr, 127.0.0.1:44789_solr] at __randomizedtesting.SeedInfo.seed([416096265997764:4C637DD663AA58F1]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1107) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:884) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:817) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194) at org.apache.solr.client.solrj.request.UpdateRequest.commit(UpdateRequest.java:237) at org.apache.solr.cloud.api.collections.CollectionsAPIDistributedZkTest.testCollectionsAPI(CollectionsAPIDistributedZkTest.java:466) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1742) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985) 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:944) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.cloud.api.collections.CollectionsAPIDistributedZkTest.testCollectionsAPI Error Message: Error from server at https://127.0.0.1:45511/solr/awhollynewcollection_0: No registered leader was found after waiting for 4000ms , collection: awhollynewcollection_0 slice: shard1 saw state=DocCollection(awhollynewcollection_0//collections/awhollynewcollection_0/state.json/8)={ "pullReplicas":"0", "replicationFactor":"2", "shards":{ "shard1":{ "range":"80000000-d554ffff", "state":"active", "replicas":{ "core_node3":{ "core":"awhollynewcollection_0_shard1_replica_n1", "base_url":"https://127.0.0.1:32779/solr", "node_name":"127.0.0.1:32779_solr", "state":"down", "type":"NRT", "force_set_state":"false"}, "core_node5":{ "core":"awhollynewcollection_0_shard1_replica_n2", "base_url":"https://127.0.0.1:41541/solr", "node_name":"127.0.0.1:41541_solr", "state":"active", "type":"NRT", "force_set_state":"false"}}}, "shard2":{ "range":"d5550000-2aa9ffff", "state":"active", "replicas":{ "core_node7":{ "core":"awhollynewcollection_0_shard2_replica_n4", "base_url":"https://127.0.0.1:35513/solr", "node_name":"127.0.0.1:35513_solr", "state":"active", "type":"NRT", "force_set_state":"false", "leader":"true"}, "core_node9":{ "core":"awhollynewcollection_0_shard2_replica_n6", "base_url":"https://127.0.0.1:45511/solr", "node_name":"127.0.0.1:45511_solr", "state":"active", "type":"NRT", "force_set_state":"false"}}}, "shard3":{ "range":"2aaa0000-7fffffff", "state":"active", "replicas":{ "core_node11":{ "core":"awhollynewcollection_0_shard3_replica_n8", "base_url":"https://127.0.0.1:45511/solr", "node_name":"127.0.0.1:45511_solr", "state":"active", "type":"NRT", "force_set_state":"false", "leader":"true"}, "core_node12":{ "core":"awhollynewcollection_0_shard3_replica_n10", "base_url":"https://127.0.0.1:35513/solr", "node_name":"127.0.0.1:35513_solr", "state":"active", "type":"NRT", "force_set_state":"false"}}}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"2", "autoAddReplicas":"false", "nrtReplicas":"2", "tlogReplicas":"0"} with live_nodes=[127.0.0.1:32779_solr, 127.0.0.1:35513_solr, 127.0.0.1:41541_solr, 127.0.0.1:45511_solr] Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:45511/solr/awhollynewcollection_0: No registered leader was found after waiting for 4000ms , collection: awhollynewcollection_0 slice: shard1 saw state=DocCollection(awhollynewcollection_0//collections/awhollynewcollection_0/state.json/8)={ "pullReplicas":"0", "replicationFactor":"2", "shards":{ "shard1":{ "range":"80000000-d554ffff", "state":"active", "replicas":{ "core_node3":{ "core":"awhollynewcollection_0_shard1_replica_n1", "base_url":"https://127.0.0.1:32779/solr", "node_name":"127.0.0.1:32779_solr", "state":"down", "type":"NRT", "force_set_state":"false"}, "core_node5":{ "core":"awhollynewcollection_0_shard1_replica_n2", "base_url":"https://127.0.0.1:41541/solr", "node_name":"127.0.0.1:41541_solr", "state":"active", "type":"NRT", "force_set_state":"false"}}}, "shard2":{ "range":"d5550000-2aa9ffff", "state":"active", "replicas":{ "core_node7":{ "core":"awhollynewcollection_0_shard2_replica_n4", "base_url":"https://127.0.0.1:35513/solr", "node_name":"127.0.0.1:35513_solr", "state":"active", "type":"NRT", "force_set_state":"false", "leader":"true"}, "core_node9":{ "core":"awhollynewcollection_0_shard2_replica_n6", "base_url":"https://127.0.0.1:45511/solr", "node_name":"127.0.0.1:45511_solr", "state":"active", "type":"NRT", "force_set_state":"false"}}}, "shard3":{ "range":"2aaa0000-7fffffff", "state":"active", "replicas":{ "core_node11":{ "core":"awhollynewcollection_0_shard3_replica_n8", "base_url":"https://127.0.0.1:45511/solr", "node_name":"127.0.0.1:45511_solr", "state":"active", "type":"NRT", "force_set_state":"false", "leader":"true"}, "core_node12":{ "core":"awhollynewcollection_0_shard3_replica_n10", "base_url":"https://127.0.0.1:35513/solr", "node_name":"127.0.0.1:35513_solr", "state":"active", "type":"NRT", "force_set_state":"false"}}}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"2", "autoAddReplicas":"false", "nrtReplicas":"2", "tlogReplicas":"0"} with live_nodes=[127.0.0.1:32779_solr, 127.0.0.1:35513_solr, 127.0.0.1:41541_solr, 127.0.0.1:45511_solr] at __randomizedtesting.SeedInfo.seed([416096265997764:4C637DD663AA58F1]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1107) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:884) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:817) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194) at org.apache.solr.client.solrj.request.UpdateRequest.commit(UpdateRequest.java:237) at org.apache.solr.cloud.api.collections.CollectionsAPIDistributedZkTest.testCollectionsAPI(CollectionsAPIDistributedZkTest.java:466) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1742) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985) 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:944) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest.testInactiveShardCleanup Error Message: missing cleanup event: [CapturedEvent{timestamp=473354145811596, stage=SUCCEEDED, actionName='null', event={ "id":"1ae83592357eaT3uldiiwew1kwg63wikjsbymdm", "source":".scheduled_maintenance", "eventTime":473354136147946, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543208990790, "_enqueue_time_":473354136473661}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473358481096134, stage=STARTED, actionName='null', event={ "id":"1ae845c0b7de4T3uldiiwew1kwg63wikjsbymdo", "source":".scheduled_maintenance", "eventTime":473358479883748, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543208995134, "_enqueue_time_":473358480261156}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473358481876288, stage=BEFORE_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae845c0b7de4T3uldiiwew1kwg63wikjsbymdo", "source":".scheduled_maintenance", "eventTime":473358479883748, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543208995134, "_enqueue_time_":473358480261156}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473358483793668, stage=AFTER_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae845c0b7de4T3uldiiwew1kwg63wikjsbymdo", "source":".scheduled_maintenance", "eventTime":473358479883748, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543208995134, "_enqueue_time_":473358480261156}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance, properties.inactive_shard_plan={staleLocks={ScheduledMaintenanceTriggerTest_collection1/staleShard-splitting={stateTimestamp=1543036194114754470, currentTimeNs=1543208995137700787, deltaSec=172801, ttlSec=20}}}, properties.AFTER_ACTION=[inactive_shard_plan, execute_plan, test]}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473358485050365, stage=SUCCEEDED, actionName='null', event={ "id":"1ae845c0b7de4T3uldiiwew1kwg63wikjsbymdo", "source":".scheduled_maintenance", "eventTime":473358479883748, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543208995134, "_enqueue_time_":473358480261156}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473363486574371, stage=STARTED, actionName='null', event={ "id":"1ae85866b2797T3uldiiwew1kwg63wikjsbymdu", "source":".scheduled_maintenance", "eventTime":473363485763479, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209000140, "_enqueue_time_":473363486024014}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473363487121642, stage=BEFORE_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae85866b2797T3uldiiwew1kwg63wikjsbymdu", "source":".scheduled_maintenance", "eventTime":473363485763479, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209000140, "_enqueue_time_":473363486024014}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473363488886579, stage=AFTER_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae85866b2797T3uldiiwew1kwg63wikjsbymdu", "source":".scheduled_maintenance", "eventTime":473363485763479, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209000140, "_enqueue_time_":473363486024014}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance, properties.AFTER_ACTION=[inactive_shard_plan, execute_plan, test]}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473363490090311, stage=SUCCEEDED, actionName='null', event={ "id":"1ae85866b2797T3uldiiwew1kwg63wikjsbymdu", "source":".scheduled_maintenance", "eventTime":473363485763479, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209000140, "_enqueue_time_":473363486024014}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473368491586544, stage=STARTED, actionName='null', event={ "id":"1ae86b0bc994bT3uldiiwew1kwg63wikjsbymdv", "source":".scheduled_maintenance", "eventTime":473368490711371, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209005145, "_enqueue_time_":473368490924991}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473368492263786, stage=BEFORE_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae86b0bc994bT3uldiiwew1kwg63wikjsbymdv", "source":".scheduled_maintenance", "eventTime":473368490711371, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209005145, "_enqueue_time_":473368490924991}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473368494168728, stage=AFTER_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae86b0bc994bT3uldiiwew1kwg63wikjsbymdv", "source":".scheduled_maintenance", "eventTime":473368490711371, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209005145, "_enqueue_time_":473368490924991}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance, properties.AFTER_ACTION=[inactive_shard_plan, execute_plan, test]}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473368495059766, stage=SUCCEEDED, actionName='null', event={ "id":"1ae86b0bc994bT3uldiiwew1kwg63wikjsbymdv", "source":".scheduled_maintenance", "eventTime":473368490711371, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209005145, "_enqueue_time_":473368490924991}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473373496569014, stage=STARTED, actionName='null', event={ "id":"1ae87db0e2e1aT3uldiiwew1kwg63wikjsbymdw", "source":".scheduled_maintenance", "eventTime":473373495668250, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209010150, "_enqueue_time_":473373496031115}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473373497196029, stage=BEFORE_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae87db0e2e1aT3uldiiwew1kwg63wikjsbymdw", "source":".scheduled_maintenance", "eventTime":473373495668250, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209010150, "_enqueue_time_":473373496031115}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473373498955321, stage=AFTER_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae87db0e2e1aT3uldiiwew1kwg63wikjsbymdw", "source":".scheduled_maintenance", "eventTime":473373495668250, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209010150, "_enqueue_time_":473373496031115}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance, properties.AFTER_ACTION=[inactive_shard_plan, execute_plan, test]}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473373500265284, stage=SUCCEEDED, actionName='null', event={ "id":"1ae87db0e2e1aT3uldiiwew1kwg63wikjsbymdw", "source":".scheduled_maintenance", "eventTime":473373495668250, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209010150, "_enqueue_time_":473373496031115}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473378501595583, stage=STARTED, actionName='null', event={ "id":"1ae890563cbefT3uldiiwew1kwg63wikjsbymdx", "source":".scheduled_maintenance", "eventTime":473378500889583, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209015155, "_enqueue_time_":473378501089691}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473378502221016, stage=BEFORE_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae890563cbefT3uldiiwew1kwg63wikjsbymdx", "source":".scheduled_maintenance", "eventTime":473378500889583, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209015155, "_enqueue_time_":473378501089691}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473378503538559, stage=AFTER_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae890563cbefT3uldiiwew1kwg63wikjsbymdx", "source":".scheduled_maintenance", "eventTime":473378500889583, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209015155, "_enqueue_time_":473378501089691}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance, properties.inactive_shard_plan={staleLocks={ScheduledMaintenanceTriggerTest_collection1/shard1-splitting={stateTimestamp=1543208994141933497, currentTimeNs=1543209015157854633, deltaSec=21, ttlSec=20}}}, properties.AFTER_ACTION=[inactive_shard_plan, execute_plan, test]}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}] Stack Trace: java.lang.AssertionError: missing cleanup event: [CapturedEvent{timestamp=473354145811596, stage=SUCCEEDED, actionName='null', event={ "id":"1ae83592357eaT3uldiiwew1kwg63wikjsbymdm", "source":".scheduled_maintenance", "eventTime":473354136147946, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543208990790, "_enqueue_time_":473354136473661}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473358481096134, stage=STARTED, actionName='null', event={ "id":"1ae845c0b7de4T3uldiiwew1kwg63wikjsbymdo", "source":".scheduled_maintenance", "eventTime":473358479883748, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543208995134, "_enqueue_time_":473358480261156}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473358481876288, stage=BEFORE_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae845c0b7de4T3uldiiwew1kwg63wikjsbymdo", "source":".scheduled_maintenance", "eventTime":473358479883748, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543208995134, "_enqueue_time_":473358480261156}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473358483793668, stage=AFTER_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae845c0b7de4T3uldiiwew1kwg63wikjsbymdo", "source":".scheduled_maintenance", "eventTime":473358479883748, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543208995134, "_enqueue_time_":473358480261156}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance, properties.inactive_shard_plan={staleLocks={ScheduledMaintenanceTriggerTest_collection1/staleShard-splitting={stateTimestamp=1543036194114754470, currentTimeNs=1543208995137700787, deltaSec=172801, ttlSec=20}}}, properties.AFTER_ACTION=[inactive_shard_plan, execute_plan, test]}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473358485050365, stage=SUCCEEDED, actionName='null', event={ "id":"1ae845c0b7de4T3uldiiwew1kwg63wikjsbymdo", "source":".scheduled_maintenance", "eventTime":473358479883748, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543208995134, "_enqueue_time_":473358480261156}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473363486574371, stage=STARTED, actionName='null', event={ "id":"1ae85866b2797T3uldiiwew1kwg63wikjsbymdu", "source":".scheduled_maintenance", "eventTime":473363485763479, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209000140, "_enqueue_time_":473363486024014}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473363487121642, stage=BEFORE_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae85866b2797T3uldiiwew1kwg63wikjsbymdu", "source":".scheduled_maintenance", "eventTime":473363485763479, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209000140, "_enqueue_time_":473363486024014}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473363488886579, stage=AFTER_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae85866b2797T3uldiiwew1kwg63wikjsbymdu", "source":".scheduled_maintenance", "eventTime":473363485763479, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209000140, "_enqueue_time_":473363486024014}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance, properties.AFTER_ACTION=[inactive_shard_plan, execute_plan, test]}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473363490090311, stage=SUCCEEDED, actionName='null', event={ "id":"1ae85866b2797T3uldiiwew1kwg63wikjsbymdu", "source":".scheduled_maintenance", "eventTime":473363485763479, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209000140, "_enqueue_time_":473363486024014}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473368491586544, stage=STARTED, actionName='null', event={ "id":"1ae86b0bc994bT3uldiiwew1kwg63wikjsbymdv", "source":".scheduled_maintenance", "eventTime":473368490711371, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209005145, "_enqueue_time_":473368490924991}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473368492263786, stage=BEFORE_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae86b0bc994bT3uldiiwew1kwg63wikjsbymdv", "source":".scheduled_maintenance", "eventTime":473368490711371, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209005145, "_enqueue_time_":473368490924991}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473368494168728, stage=AFTER_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae86b0bc994bT3uldiiwew1kwg63wikjsbymdv", "source":".scheduled_maintenance", "eventTime":473368490711371, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209005145, "_enqueue_time_":473368490924991}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance, properties.AFTER_ACTION=[inactive_shard_plan, execute_plan, test]}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473368495059766, stage=SUCCEEDED, actionName='null', event={ "id":"1ae86b0bc994bT3uldiiwew1kwg63wikjsbymdv", "source":".scheduled_maintenance", "eventTime":473368490711371, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209005145, "_enqueue_time_":473368490924991}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473373496569014, stage=STARTED, actionName='null', event={ "id":"1ae87db0e2e1aT3uldiiwew1kwg63wikjsbymdw", "source":".scheduled_maintenance", "eventTime":473373495668250, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209010150, "_enqueue_time_":473373496031115}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473373497196029, stage=BEFORE_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae87db0e2e1aT3uldiiwew1kwg63wikjsbymdw", "source":".scheduled_maintenance", "eventTime":473373495668250, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209010150, "_enqueue_time_":473373496031115}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473373498955321, stage=AFTER_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae87db0e2e1aT3uldiiwew1kwg63wikjsbymdw", "source":".scheduled_maintenance", "eventTime":473373495668250, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209010150, "_enqueue_time_":473373496031115}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance, properties.AFTER_ACTION=[inactive_shard_plan, execute_plan, test]}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473373500265284, stage=SUCCEEDED, actionName='null', event={ "id":"1ae87db0e2e1aT3uldiiwew1kwg63wikjsbymdw", "source":".scheduled_maintenance", "eventTime":473373495668250, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209010150, "_enqueue_time_":473373496031115}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473378501595583, stage=STARTED, actionName='null', event={ "id":"1ae890563cbefT3uldiiwew1kwg63wikjsbymdx", "source":".scheduled_maintenance", "eventTime":473378500889583, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209015155, "_enqueue_time_":473378501089691}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473378502221016, stage=BEFORE_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae890563cbefT3uldiiwew1kwg63wikjsbymdx", "source":".scheduled_maintenance", "eventTime":473378500889583, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209015155, "_enqueue_time_":473378501089691}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473378503538559, stage=AFTER_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae890563cbefT3uldiiwew1kwg63wikjsbymdx", "source":".scheduled_maintenance", "eventTime":473378500889583, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209015155, "_enqueue_time_":473378501089691}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance, properties.inactive_shard_plan={staleLocks={ScheduledMaintenanceTriggerTest_collection1/shard1-splitting={stateTimestamp=1543208994141933497, currentTimeNs=1543209015157854633, deltaSec=21, ttlSec=20}}}, properties.AFTER_ACTION=[inactive_shard_plan, execute_plan, test]}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}] at __randomizedtesting.SeedInfo.seed([416096265997764:193AC91004DA506F]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertNotNull(Assert.java:526) at org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest.testInactiveShardCleanup(ScheduledMaintenanceTriggerTest.java:253) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1742) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985) 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:944) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest.testInactiveShardCleanup Error Message: missing cleanup event: [CapturedEvent{timestamp=473378504389377, stage=SUCCEEDED, actionName='null', event={ "id":"1ae890563cbefT3uldiiwew1kwg63wikjsbymdx", "source":".scheduled_maintenance", "eventTime":473378500889583, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209015155, "_enqueue_time_":473378501089691}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473383714894086, stage=STARTED, actionName='null', event={ "id":"1ae8a3c1ae9adT3uldiiwew1kwg63wikjsbyme7", "source":".scheduled_maintenance", "eventTime":473383713827245, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209020368, "_enqueue_time_":473383714088074}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473383715642478, stage=BEFORE_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae8a3c1ae9adT3uldiiwew1kwg63wikjsbyme7", "source":".scheduled_maintenance", "eventTime":473383713827245, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209020368, "_enqueue_time_":473383714088074}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473383717500249, stage=AFTER_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae8a3c1ae9adT3uldiiwew1kwg63wikjsbyme7", "source":".scheduled_maintenance", "eventTime":473383713827245, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209020368, "_enqueue_time_":473383714088074}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance, properties.inactive_shard_plan={staleLocks={ScheduledMaintenanceTriggerTest_collection1/staleShard-splitting={stateTimestamp=1543036219350649735, currentTimeNs=1543209020371629854, deltaSec=172801, ttlSec=20}}}, properties.AFTER_ACTION=[inactive_shard_plan, execute_plan, test]}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473383718247817, stage=SUCCEEDED, actionName='null', event={ "id":"1ae8a3c1ae9adT3uldiiwew1kwg63wikjsbyme7", "source":".scheduled_maintenance", "eventTime":473383713827245, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209020368, "_enqueue_time_":473383714088074}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473388719494452, stage=STARTED, actionName='null', event={ "id":"1ae8b666cd583T3uldiiwew1kwg63wikjsbymed", "source":".scheduled_maintenance", "eventTime":473388718806403, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209025373, "_enqueue_time_":473388719063544}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473388720018382, stage=BEFORE_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae8b666cd583T3uldiiwew1kwg63wikjsbymed", "source":".scheduled_maintenance", "eventTime":473388718806403, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209025373, "_enqueue_time_":473388719063544}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473388721746386, stage=AFTER_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae8b666cd583T3uldiiwew1kwg63wikjsbymed", "source":".scheduled_maintenance", "eventTime":473388718806403, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209025373, "_enqueue_time_":473388719063544}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance, properties.AFTER_ACTION=[inactive_shard_plan, execute_plan, test]}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473388722607634, stage=SUCCEEDED, actionName='null', event={ "id":"1ae8b666cd583T3uldiiwew1kwg63wikjsbymed", "source":".scheduled_maintenance", "eventTime":473388718806403, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209025373, "_enqueue_time_":473388719063544}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473393723655669, stage=STARTED, actionName='null', event={ "id":"1ae8c90b2eed3T3uldiiwew1kwg63wikjsbymee", "source":".scheduled_maintenance", "eventTime":473393723010771, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209030377, "_enqueue_time_":473393723144547}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473393724245979, stage=BEFORE_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae8c90b2eed3T3uldiiwew1kwg63wikjsbymee", "source":".scheduled_maintenance", "eventTime":473393723010771, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209030377, "_enqueue_time_":473393723144547}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473393725459438, stage=AFTER_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae8c90b2eed3T3uldiiwew1kwg63wikjsbymee", "source":".scheduled_maintenance", "eventTime":473393723010771, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209030377, "_enqueue_time_":473393723144547}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance, properties.AFTER_ACTION=[inactive_shard_plan, execute_plan, test]}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473393726183648, stage=SUCCEEDED, actionName='null', event={ "id":"1ae8c90b2eed3T3uldiiwew1kwg63wikjsbymee", "source":".scheduled_maintenance", "eventTime":473393723010771, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209030377, "_enqueue_time_":473393723144547}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473398727296082, stage=STARTED, actionName='null', event={ "id":"1ae8dbaf0720eT3uldiiwew1kwg63wikjsbymef", "source":".scheduled_maintenance", "eventTime":473398726652430, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209035381, "_enqueue_time_":473398726861973}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473398727768341, stage=BEFORE_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae8dbaf0720eT3uldiiwew1kwg63wikjsbymef", "source":".scheduled_maintenance", "eventTime":473398726652430, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209035381, "_enqueue_time_":473398726861973}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473398729047140, stage=AFTER_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae8dbaf0720eT3uldiiwew1kwg63wikjsbymef", "source":".scheduled_maintenance", "eventTime":473398726652430, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209035381, "_enqueue_time_":473398726861973}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance, properties.AFTER_ACTION=[inactive_shard_plan, execute_plan, test]}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473398729814481, stage=SUCCEEDED, actionName='null', event={ "id":"1ae8dbaf0720eT3uldiiwew1kwg63wikjsbymef", "source":".scheduled_maintenance", "eventTime":473398726652430, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209035381, "_enqueue_time_":473398726861973}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473403730931796, stage=STARTED, actionName='null', event={ "id":"1ae8ee52dd412T3uldiiwew1kwg63wikjsbymeg", "source":".scheduled_maintenance", "eventTime":473403730285586, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209040385, "_enqueue_time_":473403730442045}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473403731611309, stage=BEFORE_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae8ee52dd412T3uldiiwew1kwg63wikjsbymeg", "source":".scheduled_maintenance", "eventTime":473403730285586, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209040385, "_enqueue_time_":473403730442045}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473403733118685, stage=AFTER_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae8ee52dd412T3uldiiwew1kwg63wikjsbymeg", "source":".scheduled_maintenance", "eventTime":473403730285586, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209040385, "_enqueue_time_":473403730442045}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance, properties.inactive_shard_plan={staleLocks={ScheduledMaintenanceTriggerTest_collection1/shard1-splitting={stateTimestamp=1543209019373526142, currentTimeNs=1543209040387295596, deltaSec=21, ttlSec=20}}}, properties.AFTER_ACTION=[inactive_shard_plan, execute_plan, test]}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}] Stack Trace: java.lang.AssertionError: missing cleanup event: [CapturedEvent{timestamp=473378504389377, stage=SUCCEEDED, actionName='null', event={ "id":"1ae890563cbefT3uldiiwew1kwg63wikjsbymdx", "source":".scheduled_maintenance", "eventTime":473378500889583, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209015155, "_enqueue_time_":473378501089691}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473383714894086, stage=STARTED, actionName='null', event={ "id":"1ae8a3c1ae9adT3uldiiwew1kwg63wikjsbyme7", "source":".scheduled_maintenance", "eventTime":473383713827245, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209020368, "_enqueue_time_":473383714088074}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473383715642478, stage=BEFORE_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae8a3c1ae9adT3uldiiwew1kwg63wikjsbyme7", "source":".scheduled_maintenance", "eventTime":473383713827245, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209020368, "_enqueue_time_":473383714088074}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473383717500249, stage=AFTER_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae8a3c1ae9adT3uldiiwew1kwg63wikjsbyme7", "source":".scheduled_maintenance", "eventTime":473383713827245, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209020368, "_enqueue_time_":473383714088074}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance, properties.inactive_shard_plan={staleLocks={ScheduledMaintenanceTriggerTest_collection1/staleShard-splitting={stateTimestamp=1543036219350649735, currentTimeNs=1543209020371629854, deltaSec=172801, ttlSec=20}}}, properties.AFTER_ACTION=[inactive_shard_plan, execute_plan, test]}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473383718247817, stage=SUCCEEDED, actionName='null', event={ "id":"1ae8a3c1ae9adT3uldiiwew1kwg63wikjsbyme7", "source":".scheduled_maintenance", "eventTime":473383713827245, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209020368, "_enqueue_time_":473383714088074}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473388719494452, stage=STARTED, actionName='null', event={ "id":"1ae8b666cd583T3uldiiwew1kwg63wikjsbymed", "source":".scheduled_maintenance", "eventTime":473388718806403, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209025373, "_enqueue_time_":473388719063544}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473388720018382, stage=BEFORE_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae8b666cd583T3uldiiwew1kwg63wikjsbymed", "source":".scheduled_maintenance", "eventTime":473388718806403, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209025373, "_enqueue_time_":473388719063544}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473388721746386, stage=AFTER_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae8b666cd583T3uldiiwew1kwg63wikjsbymed", "source":".scheduled_maintenance", "eventTime":473388718806403, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209025373, "_enqueue_time_":473388719063544}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance, properties.AFTER_ACTION=[inactive_shard_plan, execute_plan, test]}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473388722607634, stage=SUCCEEDED, actionName='null', event={ "id":"1ae8b666cd583T3uldiiwew1kwg63wikjsbymed", "source":".scheduled_maintenance", "eventTime":473388718806403, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209025373, "_enqueue_time_":473388719063544}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473393723655669, stage=STARTED, actionName='null', event={ "id":"1ae8c90b2eed3T3uldiiwew1kwg63wikjsbymee", "source":".scheduled_maintenance", "eventTime":473393723010771, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209030377, "_enqueue_time_":473393723144547}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473393724245979, stage=BEFORE_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae8c90b2eed3T3uldiiwew1kwg63wikjsbymee", "source":".scheduled_maintenance", "eventTime":473393723010771, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209030377, "_enqueue_time_":473393723144547}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473393725459438, stage=AFTER_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae8c90b2eed3T3uldiiwew1kwg63wikjsbymee", "source":".scheduled_maintenance", "eventTime":473393723010771, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209030377, "_enqueue_time_":473393723144547}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance, properties.AFTER_ACTION=[inactive_shard_plan, execute_plan, test]}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473393726183648, stage=SUCCEEDED, actionName='null', event={ "id":"1ae8c90b2eed3T3uldiiwew1kwg63wikjsbymee", "source":".scheduled_maintenance", "eventTime":473393723010771, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209030377, "_enqueue_time_":473393723144547}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473398727296082, stage=STARTED, actionName='null', event={ "id":"1ae8dbaf0720eT3uldiiwew1kwg63wikjsbymef", "source":".scheduled_maintenance", "eventTime":473398726652430, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209035381, "_enqueue_time_":473398726861973}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473398727768341, stage=BEFORE_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae8dbaf0720eT3uldiiwew1kwg63wikjsbymef", "source":".scheduled_maintenance", "eventTime":473398726652430, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209035381, "_enqueue_time_":473398726861973}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473398729047140, stage=AFTER_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae8dbaf0720eT3uldiiwew1kwg63wikjsbymef", "source":".scheduled_maintenance", "eventTime":473398726652430, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209035381, "_enqueue_time_":473398726861973}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance, properties.AFTER_ACTION=[inactive_shard_plan, execute_plan, test]}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473398729814481, stage=SUCCEEDED, actionName='null', event={ "id":"1ae8dbaf0720eT3uldiiwew1kwg63wikjsbymef", "source":".scheduled_maintenance", "eventTime":473398726652430, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209035381, "_enqueue_time_":473398726861973}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473403730931796, stage=STARTED, actionName='null', event={ "id":"1ae8ee52dd412T3uldiiwew1kwg63wikjsbymeg", "source":".scheduled_maintenance", "eventTime":473403730285586, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209040385, "_enqueue_time_":473403730442045}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473403731611309, stage=BEFORE_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae8ee52dd412T3uldiiwew1kwg63wikjsbymeg", "source":".scheduled_maintenance", "eventTime":473403730285586, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209040385, "_enqueue_time_":473403730442045}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}, CapturedEvent{timestamp=473403733118685, stage=AFTER_ACTION, actionName='inactive_shard_plan', event={ "id":"1ae8ee52dd412T3uldiiwew1kwg63wikjsbymeg", "source":".scheduled_maintenance", "eventTime":473403730285586, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209040385, "_enqueue_time_":473403730442045}}, context={properties.BEFORE_ACTION=[inactive_shard_plan, execute_plan, test], source=.scheduled_maintenance, properties.inactive_shard_plan={staleLocks={ScheduledMaintenanceTriggerTest_collection1/shard1-splitting={stateTimestamp=1543209019373526142, currentTimeNs=1543209040387295596, deltaSec=21, ttlSec=20}}}, properties.AFTER_ACTION=[inactive_shard_plan, execute_plan, test]}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":"inactive_shard_plan", "afterAction":"inactive_shard_plan", "class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"}, message='null'}] at __randomizedtesting.SeedInfo.seed([416096265997764:193AC91004DA506F]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertNotNull(Assert.java:526) at org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest.testInactiveShardCleanup(ScheduledMaintenanceTriggerTest.java:253) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1742) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985) 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:944) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest.testInactiveShardCleanup Error Message: missing cleanup event: [CapturedEvent{timestamp=473403734080288, stage=SUCCEEDED, actionName='null', event={ "id":"1ae8ee52dd412T3uldiiwew1kwg63wikjsbymeg", "source":".scheduled_maintenance", "eventTime":473403730285586, "eventType":"SCHEDULED", "properties":{ "actualEventTime":1543209040385, "_enqueue_time_":473403730442045}}, context={}, config={ "trigger":".scheduled_maintenance", "stage":[ "STARTED", "ABORTED", "S [...truncated too long message...] l.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> [junit4] 2> 284240 INFO (TEST-RootFieldTest.testUpdateWithChildDocs-seed#[416096265997764]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testUpdateWithChildDocs [junit4] OK 0.01s J2 | RootFieldTest.testUpdateWithChildDocs [junit4] 2> 284241 INFO (SUITE-RootFieldTest-seed#[416096265997764]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> 284241 INFO (SUITE-RootFieldTest-seed#[416096265997764]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=194781261 [junit4] 2> 284241 INFO (SUITE-RootFieldTest-seed#[416096265997764]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 284241 INFO (SUITE-RootFieldTest-seed#[416096265997764]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@a373035: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@771b33c1 [junit4] 2> 284245 INFO (SUITE-RootFieldTest-seed#[416096265997764]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 284245 INFO (SUITE-RootFieldTest-seed#[416096265997764]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5e778d94: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1e307c3a [junit4] 2> 284247 INFO (SUITE-RootFieldTest-seed#[416096265997764]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 284247 INFO (SUITE-RootFieldTest-seed#[416096265997764]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@67caa271: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@cf461b7 [junit4] 2> 284252 INFO (coreCloseExecutor-440-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@279905b6 [junit4] 2> 284252 INFO (coreCloseExecutor-440-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=279905b6 [junit4] 2> 284252 INFO (coreCloseExecutor-440-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1b5491f0: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@13f9f7aa [junit4] 2> 284262 INFO (coreCloseExecutor-440-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close. [junit4] 2> 284262 INFO (coreCloseExecutor-440-thread-1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@19019cda commitCommandVersion:0 [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J2/temp/solr.update.RootFieldTest_416096265997764-003 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): {}, docValues:{}, maxPointsInLeafNode=453, maxMBSortInHeap=5.560342439164563, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@5897a3f3), locale=ro-RO, timezone=Australia/North [junit4] 2> NOTE: Linux 4.15.0-39-generic amd64/Oracle Corporation 1.8.0_172 (64-bit)/cpus=8,threads=1,free=213659256,total=452984832 [junit4] 2> NOTE: All tests run in this JVM: [ScheduledMaintenanceTriggerTest, ScheduledMaintenanceTriggerTest, RootFieldTest, ScheduledMaintenanceTriggerTest, ScheduledMaintenanceTriggerTest, RootFieldTest, CollectionsAPIDistributedZkTest, ScheduledMaintenanceTriggerTest, RootFieldTest] [junit4] Completed [14/15 (10!)] on J2 in 0.63s, 2 tests, 1 error <<< FAILURES! [junit4] [junit4] Suite: org.apache.solr.cloud.api.collections.CollectionsAPIDistributedZkTest [junit4] OK 2.58s J1 | CollectionsAPIDistributedZkTest.testDeleteNonExistentCollection [junit4] OK 0.76s J1 | CollectionsAPIDistributedZkTest.testZeroNumShards [junit4] OK 0.02s J1 | CollectionsAPIDistributedZkTest.testMissingRequiredParameters [junit4] OK 0.23s J1 | CollectionsAPIDistributedZkTest.testTooManyReplicas [junit4] OK 11.5s J1 | CollectionsAPIDistributedZkTest.deleteCollectionOnlyInZk [junit4] OK 7.68s J1 | CollectionsAPIDistributedZkTest.testCollectionReload [junit4] OK 2.99s J1 | CollectionsAPIDistributedZkTest.testCreateNodeSet [junit4] OK 4.31s J1 | CollectionsAPIDistributedZkTest.testCoresAreDistributedAcrossNodes [junit4] OK 0.98s J1 | CollectionsAPIDistributedZkTest.testMaxNodesPerShard [junit4] OK 52.6s J1 | CollectionsAPIDistributedZkTest.testCreateShouldFailOnExistingCore [junit4] OK 2.52s J1 | CollectionsAPIDistributedZkTest.testNoConfigSetExist [junit4] OK 5.65s J1 | CollectionsAPIDistributedZkTest.testCollectionsAPI [junit4] OK 4.81s J1 | CollectionsAPIDistributedZkTest.testMissingNumShards [junit4] OK 0.04s J1 | CollectionsAPIDistributedZkTest.testBadActionNames [junit4] OK 13.1s J1 | CollectionsAPIDistributedZkTest.deletePartiallyCreatedCollection [junit4] OK 23.5s J1 | CollectionsAPIDistributedZkTest.addReplicaTest [junit4] OK 0.92s J1 | CollectionsAPIDistributedZkTest.deleteCollectionRemovesStaleZkCollectionsNode [junit4] OK 3.35s J1 | CollectionsAPIDistributedZkTest.testCreationAndDeletion [junit4] OK 11.7s J1 | CollectionsAPIDistributedZkTest.testSpecificConfigsets [junit4] Completed [15/15 (10!)] on J1 in 150.81s, 19 tests [junit4] [junit4] [junit4] Tests with failures [seed: 416096265997764]: [junit4] - org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest.testInactiveShardCleanup [junit4] - org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest.testInactiveShardCleanup [junit4] - org.apache.solr.update.RootFieldTest.testLegacyBlockProcessing [junit4] - org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest.testInactiveShardCleanup [junit4] - org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest.testInactiveShardCleanup [junit4] - org.apache.solr.cloud.api.collections.CollectionsAPIDistributedZkTest.testCollectionsAPI [junit4] - org.apache.solr.update.RootFieldTest.testLegacyBlockProcessing [junit4] - org.apache.solr.update.RootFieldTest.testLegacyBlockProcessing [junit4] - org.apache.solr.update.RootFieldTest.testLegacyBlockProcessing [junit4] - org.apache.solr.update.RootFieldTest.testLegacyBlockProcessing [junit4] [junit4] [junit4] JVM J0: 0.76 .. 284.62 = 283.87s [junit4] JVM J1: 0.52 .. 307.11 = 306.59s [junit4] JVM J2: 0.52 .. 284.93 = 284.41s [junit4] Execution time total: 5 minutes 7 seconds [junit4] Tests summary: 15 suites, 115 tests, 6 errors, 4 failures BUILD FAILED /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/lucene/common-build.xml:1567: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/lucene/common-build.xml:1092: There were test failures: 15 suites, 115 tests, 6 errors, 4 failures [seed: 416096265997764] Total time: 5 minutes 8 seconds [repro] Setting last failure code to 256 [repro] Failures: [repro] 1/5 failed: org.apache.solr.cloud.api.collections.CollectionsAPIDistributedZkTest [repro] 4/5 failed: org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest [repro] 5/5 failed: org.apache.solr.update.RootFieldTest [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=a346ba0d3c371ec9f314c21fe67afeca64846cf0, workspace=/var/lib/jenkins/workspace/Lucene-Solr-BadApples-master-Linux [WARNINGS] Computing warning deltas based on reference build #125 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