Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/1706/ Java: 64bit/jdk1.8.0_162 -XX:-UseCompressedOops -XX:+UseG1GC
6 tests failed. FAILED: junit.framework.TestSuite.org.apache.lucene.search.TestBooleanScorer Error Message: Suite timeout exceeded (>= 7200000 msec). Stack Trace: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec). at __randomizedtesting.SeedInfo.seed([4EA0082AF0B1CD6F]:0) FAILED: org.apache.lucene.search.TestBooleanScorer.testSparseClauseOptimization Error Message: Test abandoned because suite timeout was reached. Stack Trace: java.lang.Exception: Test abandoned because suite timeout was reached. at __randomizedtesting.SeedInfo.seed([4EA0082AF0B1CD6F]:0) FAILED: org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testMixedBounds Error Message: failed to create testMixedBounds_collection Live Nodes: [127.0.0.1:37827_solr, 127.0.0.1:45053_solr] Last available state: DocCollection(testMixedBounds_collection//collections/testMixedBounds_collection/state.json/21)={ "pullReplicas":"0", "replicationFactor":"2", "shards":{ "shard1":{ "range":"80000000-ffffffff", "state":"active", "replicas":{ "core_node3":{ "core":"testMixedBounds_collection_shard1_replica_n1", "base_url":"https://127.0.0.1:45053/solr", "node_name":"127.0.0.1:45053_solr", "state":"active", "type":"NRT"}, "core_node5":{ "core":"testMixedBounds_collection_shard1_replica_n2", "base_url":"https://127.0.0.1:37827/solr", "node_name":"127.0.0.1:37827_solr", "state":"active", "type":"NRT", "leader":"true"}}}, "shard2":{ "range":"0-7fffffff", "state":"inactive", "replicas":{ "core_node7":{ "core":"testMixedBounds_collection_shard2_replica_n4", "base_url":"https://127.0.0.1:45053/solr", "node_name":"127.0.0.1:45053_solr", "state":"active", "type":"NRT"}, "core_node8":{ "core":"testMixedBounds_collection_shard2_replica_n6", "base_url":"https://127.0.0.1:37827/solr", "node_name":"127.0.0.1:37827_solr", "state":"active", "type":"NRT", "leader":"true"}}, "stateTimestamp":"1523603296399813715"}, "shard2_0":{ "range":"0-3fffffff", "state":"active", "replicas":{ "core_node11":{ "core":"testMixedBounds_collection_shard2_0_replica_n9", "base_url":"https://127.0.0.1:37827/solr", "node_name":"127.0.0.1:37827_solr", "state":"active", "type":"NRT", "leader":"true"}, "core_node13":{ "core":"testMixedBounds_collection_shard2_0_replica0", "base_url":"https://127.0.0.1:37827/solr", "node_name":"127.0.0.1:37827_solr", "state":"active", "type":"NRT"}}, "stateTimestamp":"1523603296399885341"}, "shard2_1":{ "range":"40000000-7fffffff", "state":"active", "replicas":{ "core_node12":{ "core":"testMixedBounds_collection_shard2_1_replica_n10", "base_url":"https://127.0.0.1:37827/solr", "node_name":"127.0.0.1:37827_solr", "state":"active", "type":"NRT", "leader":"true"}, "core_node14":{ "core":"testMixedBounds_collection_shard2_1_replica0", "base_url":"https://127.0.0.1:45053/solr", "node_name":"127.0.0.1:45053_solr", "state":"active", "type":"NRT"}}, "stateTimestamp":"1523603296399852934"}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"2", "autoAddReplicas":"false", "nrtReplicas":"2", "tlogReplicas":"0"} Stack Trace: java.lang.AssertionError: failed to create testMixedBounds_collection Live Nodes: [127.0.0.1:37827_solr, 127.0.0.1:45053_solr] Last available state: DocCollection(testMixedBounds_collection//collections/testMixedBounds_collection/state.json/21)={ "pullReplicas":"0", "replicationFactor":"2", "shards":{ "shard1":{ "range":"80000000-ffffffff", "state":"active", "replicas":{ "core_node3":{ "core":"testMixedBounds_collection_shard1_replica_n1", "base_url":"https://127.0.0.1:45053/solr", "node_name":"127.0.0.1:45053_solr", "state":"active", "type":"NRT"}, "core_node5":{ "core":"testMixedBounds_collection_shard1_replica_n2", "base_url":"https://127.0.0.1:37827/solr", "node_name":"127.0.0.1:37827_solr", "state":"active", "type":"NRT", "leader":"true"}}}, "shard2":{ "range":"0-7fffffff", "state":"inactive", "replicas":{ "core_node7":{ "core":"testMixedBounds_collection_shard2_replica_n4", "base_url":"https://127.0.0.1:45053/solr", "node_name":"127.0.0.1:45053_solr", "state":"active", "type":"NRT"}, "core_node8":{ "core":"testMixedBounds_collection_shard2_replica_n6", "base_url":"https://127.0.0.1:37827/solr", "node_name":"127.0.0.1:37827_solr", "state":"active", "type":"NRT", "leader":"true"}}, "stateTimestamp":"1523603296399813715"}, "shard2_0":{ "range":"0-3fffffff", "state":"active", "replicas":{ "core_node11":{ "core":"testMixedBounds_collection_shard2_0_replica_n9", "base_url":"https://127.0.0.1:37827/solr", "node_name":"127.0.0.1:37827_solr", "state":"active", "type":"NRT", "leader":"true"}, "core_node13":{ "core":"testMixedBounds_collection_shard2_0_replica0", "base_url":"https://127.0.0.1:37827/solr", "node_name":"127.0.0.1:37827_solr", "state":"active", "type":"NRT"}}, "stateTimestamp":"1523603296399885341"}, "shard2_1":{ "range":"40000000-7fffffff", "state":"active", "replicas":{ "core_node12":{ "core":"testMixedBounds_collection_shard2_1_replica_n10", "base_url":"https://127.0.0.1:37827/solr", "node_name":"127.0.0.1:37827_solr", "state":"active", "type":"NRT", "leader":"true"}, "core_node14":{ "core":"testMixedBounds_collection_shard2_1_replica0", "base_url":"https://127.0.0.1:45053/solr", "node_name":"127.0.0.1:45053_solr", "state":"active", "type":"NRT"}}, "stateTimestamp":"1523603296399852934"}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"2", "autoAddReplicas":"false", "nrtReplicas":"2", "tlogReplicas":"0"} at __randomizedtesting.SeedInfo.seed([39F6A5444778DB58:33751AE90AC3D002]:0) at org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:69) at org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testMixedBounds(IndexSizeTriggerTest.java:537) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) Caused by: java.util.concurrent.TimeoutException at org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:109) at org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:63) ... 40 more FAILED: org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testMixedBounds Error Message: failed to create testMixedBounds_collection Live Nodes: [127.0.0.1:35075_solr, 127.0.0.1:43599_solr] Last available state: DocCollection(testMixedBounds_collection//collections/testMixedBounds_collection/state.json/21)={ "pullReplicas":"0", "replicationFactor":"2", "shards":{ "shard1":{ "range":"80000000-ffffffff", "state":"active", "replicas":{ "core_node3":{ "core":"testMixedBounds_collection_shard1_replica_n1", "base_url":"https://127.0.0.1:43599/solr", "node_name":"127.0.0.1:43599_solr", "state":"active", "type":"NRT", "leader":"true"}, "core_node5":{ "core":"testMixedBounds_collection_shard1_replica_n2", "base_url":"https://127.0.0.1:35075/solr", "node_name":"127.0.0.1:35075_solr", "state":"active", "type":"NRT"}}}, "shard2":{ "range":"0-7fffffff", "state":"inactive", "replicas":{ "core_node7":{ "core":"testMixedBounds_collection_shard2_replica_n4", "base_url":"https://127.0.0.1:43599/solr", "node_name":"127.0.0.1:43599_solr", "state":"active", "type":"NRT", "leader":"true"}, "core_node8":{ "core":"testMixedBounds_collection_shard2_replica_n6", "base_url":"https://127.0.0.1:35075/solr", "node_name":"127.0.0.1:35075_solr", "state":"active", "type":"NRT"}}, "stateTimestamp":"1523603407315739921"}, "shard2_0":{ "range":"0-3fffffff", "state":"active", "replicas":{ "core_node11":{ "core":"testMixedBounds_collection_shard2_0_replica_n9", "base_url":"https://127.0.0.1:43599/solr", "node_name":"127.0.0.1:43599_solr", "state":"active", "type":"NRT", "leader":"true"}, "core_node13":{ "core":"testMixedBounds_collection_shard2_0_replica0", "base_url":"https://127.0.0.1:35075/solr", "node_name":"127.0.0.1:35075_solr", "state":"active", "type":"NRT"}}, "stateTimestamp":"1523603407315776654"}, "shard2_1":{ "range":"40000000-7fffffff", "state":"active", "replicas":{ "core_node12":{ "core":"testMixedBounds_collection_shard2_1_replica_n10", "base_url":"https://127.0.0.1:43599/solr", "node_name":"127.0.0.1:43599_solr", "state":"active", "type":"NRT", "leader":"true"}, "core_node14":{ "core":"testMixedBounds_collection_shard2_1_replica0", "base_url":"https://127.0.0.1:43599/solr", "node_name":"127.0.0.1:43599_solr", "state":"active", "type":"NRT"}}, "stateTimestamp":"1523603407315759566"}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"2", "autoAddReplicas":"false", "nrtReplicas":"2", "tlogReplicas":"0"} Stack Trace: java.lang.AssertionError: failed to create testMixedBounds_collection Live Nodes: [127.0.0.1:35075_solr, 127.0.0.1:43599_solr] Last available state: DocCollection(testMixedBounds_collection//collections/testMixedBounds_collection/state.json/21)={ "pullReplicas":"0", "replicationFactor":"2", "shards":{ "shard1":{ "range":"80000000-ffffffff", "state":"active", "replicas":{ "core_node3":{ "core":"testMixedBounds_collection_shard1_replica_n1", "base_url":"https://127.0.0.1:43599/solr", "node_name":"127.0.0.1:43599_solr", "state":"active", "type":"NRT", "leader":"true"}, "core_node5":{ "core":"testMixedBounds_collection_shard1_replica_n2", "base_url":"https://127.0.0.1:35075/solr", "node_name":"127.0.0.1:35075_solr", "state":"active", "type":"NRT"}}}, "shard2":{ "range":"0-7fffffff", "state":"inactive", "replicas":{ "core_node7":{ "core":"testMixedBounds_collection_shard2_replica_n4", "base_url":"https://127.0.0.1:43599/solr", "node_name":"127.0.0.1:43599_solr", "state":"active", "type":"NRT", "leader":"true"}, "core_node8":{ "core":"testMixedBounds_collection_shard2_replica_n6", "base_url":"https://127.0.0.1:35075/solr", "node_name":"127.0.0.1:35075_solr", "state":"active", "type":"NRT"}}, "stateTimestamp":"1523603407315739921"}, "shard2_0":{ "range":"0-3fffffff", "state":"active", "replicas":{ "core_node11":{ "core":"testMixedBounds_collection_shard2_0_replica_n9", "base_url":"https://127.0.0.1:43599/solr", "node_name":"127.0.0.1:43599_solr", "state":"active", "type":"NRT", "leader":"true"}, "core_node13":{ "core":"testMixedBounds_collection_shard2_0_replica0", "base_url":"https://127.0.0.1:35075/solr", "node_name":"127.0.0.1:35075_solr", "state":"active", "type":"NRT"}}, "stateTimestamp":"1523603407315776654"}, "shard2_1":{ "range":"40000000-7fffffff", "state":"active", "replicas":{ "core_node12":{ "core":"testMixedBounds_collection_shard2_1_replica_n10", "base_url":"https://127.0.0.1:43599/solr", "node_name":"127.0.0.1:43599_solr", "state":"active", "type":"NRT", "leader":"true"}, "core_node14":{ "core":"testMixedBounds_collection_shard2_1_replica0", "base_url":"https://127.0.0.1:43599/solr", "node_name":"127.0.0.1:43599_solr", "state":"active", "type":"NRT"}}, "stateTimestamp":"1523603407315759566"}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"2", "autoAddReplicas":"false", "nrtReplicas":"2", "tlogReplicas":"0"} at __randomizedtesting.SeedInfo.seed([39F6A5444778DB58:33751AE90AC3D002]:0) at org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:69) at org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testMixedBounds(IndexSizeTriggerTest.java:537) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) Caused by: java.util.concurrent.TimeoutException at org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:109) at org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:63) ... 40 more FAILED: org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testMixedBounds Error Message: failed to create testMixedBounds_collection Live Nodes: [127.0.0.1:38257_solr, 127.0.0.1:34661_solr] Last available state: DocCollection(testMixedBounds_collection//collections/testMixedBounds_collection/state.json/18)={ "pullReplicas":"0", "replicationFactor":"2", "shards":{ "shard1":{ "range":"80000000-ffffffff", "state":"active", "replicas":{ "core_node3":{ "core":"testMixedBounds_collection_shard1_replica_n1", "base_url":"https://127.0.0.1:34661/solr", "node_name":"127.0.0.1:34661_solr", "state":"active", "type":"NRT"}, "core_node5":{ "core":"testMixedBounds_collection_shard1_replica_n2", "base_url":"https://127.0.0.1:38257/solr", "node_name":"127.0.0.1:38257_solr", "state":"active", "type":"NRT", "leader":"true"}}}, "shard2":{ "range":"0-7fffffff", "state":"inactive", "replicas":{ "core_node7":{ "core":"testMixedBounds_collection_shard2_replica_n4", "base_url":"https://127.0.0.1:34661/solr", "node_name":"127.0.0.1:34661_solr", "state":"active", "type":"NRT", "leader":"true"}, "core_node8":{ "core":"testMixedBounds_collection_shard2_replica_n6", "base_url":"https://127.0.0.1:38257/solr", "node_name":"127.0.0.1:38257_solr", "state":"active", "type":"NRT"}}, "stateTimestamp":"1523603411176225449"}, "shard2_0":{ "range":"0-3fffffff", "state":"active", "replicas":{ "core_node11":{ "core":"testMixedBounds_collection_shard2_0_replica_n9", "base_url":"https://127.0.0.1:34661/solr", "node_name":"127.0.0.1:34661_solr", "state":"active", "type":"NRT", "leader":"true"}, "core_node13":{ "core":"testMixedBounds_collection_shard2_0_replica0", "base_url":"https://127.0.0.1:38257/solr", "node_name":"127.0.0.1:38257_solr", "state":"active", "type":"NRT"}}, "stateTimestamp":"1523603411176276251"}, "shard2_1":{ "range":"40000000-7fffffff", "state":"active", "replicas":{ "core_node12":{ "core":"testMixedBounds_collection_shard2_1_replica_n10", "base_url":"https://127.0.0.1:34661/solr", "node_name":"127.0.0.1:34661_solr", "state":"active", "type":"NRT", "leader":"true"}, "core_node14":{ "core":"testMixedBounds_collection_shard2_1_replica0", "base_url":"https://127.0.0.1:34661/solr", "node_name":"127.0.0.1:34661_solr", "state":"active", "type":"NRT"}}, "stateTimestamp":"1523603411176252692"}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"2", "autoAddReplicas":"false", "nrtReplicas":"2", "tlogReplicas":"0"} Stack Trace: java.lang.AssertionError: failed to create testMixedBounds_collection Live Nodes: [127.0.0.1:38257_solr, 127.0.0.1:34661_solr] Last available state: DocCollection(testMixedBounds_collection//collections/testMixedBounds_collection/state.json/18)={ "pullReplicas":"0", "replicationFactor":"2", "shards":{ "shard1":{ "range":"80000000-ffffffff", "state":"active", "replicas":{ "core_node3":{ "core":"testMixedBounds_collection_shard1_replica_n1", "base_url":"https://127.0.0.1:34661/solr", "node_name":"127.0.0.1:34661_solr", "state":"active", "type":"NRT"}, "core_node5":{ "core":"testMixedBounds_collection_shard1_replica_n2", "base_url":"https://127.0.0.1:38257/solr", "node_name":"127.0.0.1:38257_solr", "state":"active", "type":"NRT", "leader":"true"}}}, "shard2":{ "range":"0-7fffffff", "state":"inactive", "replicas":{ "core_node7":{ "core":"testMixedBounds_collection_shard2_replica_n4", "base_url":"https://127.0.0.1:34661/solr", "node_name":"127.0.0.1:34661_solr", "state":"active", "type":"NRT", "leader":"true"}, "core_node8":{ "core":"testMixedBounds_collection_shard2_replica_n6", "base_url":"https://127.0.0.1:38257/solr", "node_name":"127.0.0.1:38257_solr", "state":"active", "type":"NRT"}}, "stateTimestamp":"1523603411176225449"}, "shard2_0":{ "range":"0-3fffffff", "state":"active", "replicas":{ "core_node11":{ "core":"testMixedBounds_collection_shard2_0_replica_n9", "base_url":"https://127.0.0.1:34661/solr", "node_name":"127.0.0.1:34661_solr", "state":"active", "type":"NRT", "leader":"true"}, "core_node13":{ "core":"testMixedBounds_collection_shard2_0_replica0", "base_url":"https://127.0.0.1:38257/solr", "node_name":"127.0.0.1:38257_solr", "state":"active", "type":"NRT"}}, "stateTimestamp":"1523603411176276251"}, "shard2_1":{ "range":"40000000-7fffffff", "state":"active", "replicas":{ "core_node12":{ "core":"testMixedBounds_collection_shard2_1_replica_n10", "base_url":"https://127.0.0.1:34661/solr", "node_name":"127.0.0.1:34661_solr", "state":"active", "type":"NRT", "leader":"true"}, "core_node14":{ "core":"testMixedBounds_collection_shard2_1_replica0", "base_url":"https://127.0.0.1:34661/solr", "node_name":"127.0.0.1:34661_solr", "state":"active", "type":"NRT"}}, "stateTimestamp":"1523603411176252692"}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"2", "autoAddReplicas":"false", "nrtReplicas":"2", "tlogReplicas":"0"} at __randomizedtesting.SeedInfo.seed([39F6A5444778DB58:33751AE90AC3D002]:0) at org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:69) at org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testMixedBounds(IndexSizeTriggerTest.java:537) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) Caused by: java.util.concurrent.TimeoutException at org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:109) at org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:63) ... 40 more FAILED: org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testMixedBounds Error Message: failed to create testMixedBounds_collection Live Nodes: [127.0.0.1:35611_solr, 127.0.0.1:32925_solr] Last available state: DocCollection(testMixedBounds_collection//collections/testMixedBounds_collection/state.json/20)={ "pullReplicas":"0", "replicationFactor":"2", "shards":{ "shard1":{ "range":"80000000-ffffffff", "state":"active", "replicas":{ "core_node3":{ "core":"testMixedBounds_collection_shard1_replica_n1", "base_url":"https://127.0.0.1:32925/solr", "node_name":"127.0.0.1:32925_solr", "state":"active", "type":"NRT"}, "core_node5":{ "core":"testMixedBounds_collection_shard1_replica_n2", "base_url":"https://127.0.0.1:35611/solr", "node_name":"127.0.0.1:35611_solr", "state":"active", "type":"NRT", "leader":"true"}}}, "shard2":{ "range":"0-7fffffff", "state":"inactive", "replicas":{ "core_node7":{ "core":"testMixedBounds_collection_shard2_replica_n4", "base_url":"https://127.0.0.1:32925/solr", "node_name":"127.0.0.1:32925_solr", "state":"active", "type":"NRT", "leader":"true"}, "core_node8":{ "core":"testMixedBounds_collection_shard2_replica_n6", "base_url":"https://127.0.0.1:35611/solr", "node_name":"127.0.0.1:35611_solr", "state":"active", "type":"NRT"}}, "stateTimestamp":"1523601536737331503"}, "shard2_0":{ "range":"0-3fffffff", "state":"active", "replicas":{ "core_node11":{ "core":"testMixedBounds_collection_shard2_0_replica_n9", "base_url":"https://127.0.0.1:32925/solr", "node_name":"127.0.0.1:32925_solr", "state":"active", "type":"NRT", "leader":"true"}, "core_node13":{ "core":"testMixedBounds_collection_shard2_0_replica0", "base_url":"https://127.0.0.1:35611/solr", "node_name":"127.0.0.1:35611_solr", "state":"active", "type":"NRT"}}, "stateTimestamp":"1523601536737363145"}, "shard2_1":{ "range":"40000000-7fffffff", "state":"active", "replicas":{ "core_node12":{ "core":"testMixedBounds_collection_shard2_1_replica_n10", "base_url":"https://127.0.0.1:32925/solr", "node_name":"127.0.0.1:32925_solr", "state":"active", "type":"NRT", "leader":"true"}, "core_node14":{ "core":"testMixedBounds_collection_shard2_1_replica0", "base_url":"https://127.0.0.1:32925/solr", "node_name":"127.0.0.1:32925_solr", "state":"active", "type":"NRT"}}, "stateTimestamp":"1523601536737347918"}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"2", "autoAddReplicas":"false", "nrtReplicas":"2", "tlogReplicas":"0"} Stack Trace: java.lang.AssertionError: failed to create testMixedBounds_collection Live Nodes: [127.0.0.1:35611_solr, 127.0.0.1:32925_solr] Last available state: DocCollection(testMixedBounds_collection//collections/testMixedBounds_collection/state.json/20)={ "pullReplicas":"0", "replicationFactor":"2", "shards":{ "shard1":{ "range":"80000000-ffffffff", "state":"active", "replicas":{ "core_node3":{ "core":"testMixedBounds_collection_shard1_replica_n1", "base_url":"https://127.0.0.1:32925/solr", "node_name":"127.0.0.1:32925_solr", "state":"active", "type":"NRT"}, "core_node5":{ "core":"testMixedBounds_collection_shard1_replica_n2", "base_url":"https://127.0.0.1:35611/solr", "node_name":"127.0.0.1:35611_solr", "state":"active", "type":"NRT", "leader":"true"}}}, "shard2":{ "range":"0-7fffffff", "state":"inactive", "replicas":{ "core_node7":{ "core":"testMixedBounds_collection_shard2_replica_n4", "base_url":"https://127.0.0.1:32925/solr", "node_name":"127.0.0.1:32925_solr", "state":"active", "type":"NRT", "leader":"true"}, "core_node8":{ "core":"testMixedBounds_collection_shard2_replica_n6", "base_url":"https://127.0.0.1:35611/solr", "node_name":"127.0.0.1:35611_solr", "state":"active", "type":"NRT"}}, "stateTimestamp":"1523601536737331503"}, "shard2_0":{ "range":"0-3fffffff", "state":"active", "replicas":{ "core_node11":{ "core":"testMixedBounds_collection_shard2_0_replica_n9", "base_url":"https://127.0.0.1:32925/solr", "node_name":"127.0.0.1:32925_solr", "state":"active", "type":"NRT", "leader":"true"}, "core_node13":{ "core":"testMixedBounds_collection_shard2_0_replica0", "base_url":"https://127.0.0.1:35611/solr", "node_name":"127.0.0.1:35611_solr", "state":"active", "type":"NRT"}}, "stateTimestamp":"1523601536737363145"}, "shard2_1":{ "range":"40000000-7fffffff", "state":"active", "replicas":{ "core_node12":{ "core":"testMixedBounds_collection_shard2_1_replica_n10", "base_url":"https://127.0.0.1:32925/solr", "node_name":"127.0.0.1:32925_solr", "state":"active", "type":"NRT", "leader":"true"}, "core_node14":{ "core":"testMixedBounds_collection_shard2_1_replica0", "base_url":"https://127.0.0.1:32925/solr", "node_name":"127.0.0.1:32925_solr", "state":"active", "type":"NRT"}}, "stateTimestamp":"1523601536737347918"}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"2", "autoAddReplicas":"false", "nrtReplicas":"2", "tlogReplicas":"0"} at __randomizedtesting.SeedInfo.seed([39F6A5444778DB58:33751AE90AC3D002]:0) at org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:69) at org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testMixedBounds(IndexSizeTriggerTest.java:537) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) Caused by: java.util.concurrent.TimeoutException at org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:109) at org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:63) ... 40 more Build Log: [...truncated 1909 lines...] [junit4] Suite: org.apache.lucene.search.TestBooleanScorer [junit4] 2> Apr 13, 2018 12:53:43 AM com.carrotsearch.randomizedtesting.ThreadLeakControl$2 evaluate [junit4] 2> WARNING: Suite execution timed out: org.apache.lucene.search.TestBooleanScorer [junit4] 2> 1) Thread[id=1, name=main, state=WAITING, group=main] [junit4] 2> at java.lang.Object.wait(Native Method) [junit4] 2> at java.lang.Thread.join(Thread.java:1252) [junit4] 2> at java.lang.Thread.join(Thread.java:1326) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSuite(RandomizedRunner.java:636) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.run(RandomizedRunner.java:493) [junit4] 2> at com.carrotsearch.ant.tasks.junit4.slave.SlaveMain.execute(SlaveMain.java:251) [junit4] 2> at com.carrotsearch.ant.tasks.junit4.slave.SlaveMain.main(SlaveMain.java:368) [junit4] 2> at com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe.main(SlaveMainSafe.java:13) [junit4] 2> 2) Thread[id=11, name=JUnit4-serializer-daemon, state=TIMED_WAITING, group=main] [junit4] 2> at java.lang.Thread.sleep(Native Method) [junit4] 2> at com.carrotsearch.ant.tasks.junit4.events.Serializer$1.run(Serializer.java:50) [junit4] 2> 3) Thread[id=1693, name=TEST-TestBooleanScorer.testSparseClauseOptimization-seed#[4EA0082AF0B1CD6F], state=BLOCKED, group=TGRP-TestBooleanScorer] [junit4] 2> at java.lang.Object.wait(Native Method) [junit4] 2> at org.apache.lucene.index.IndexWriter.doWait(IndexWriter.java:4719) [junit4] 2> at org.apache.lucene.index.IndexWriter.waitForMerges(IndexWriter.java:2724) [junit4] 2> at org.apache.lucene.index.IndexWriter.shutdown(IndexWriter.java:1281) [junit4] 2> at org.apache.lucene.index.IndexWriter.close(IndexWriter.java:1323) [junit4] 2> at org.apache.lucene.util.IOUtils.close(IOUtils.java:88) [junit4] 2> at org.apache.lucene.util.IOUtils.close(IOUtils.java:76) [junit4] 2> at org.apache.lucene.index.RandomIndexWriter.close(RandomIndexWriter.java:434) [junit4] 2> at org.apache.lucene.search.TestBooleanScorer.testSparseClauseOptimization(TestBooleanScorer.java:284) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) [junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) [junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) [junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) [junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) [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 com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) [junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) [junit4] 2> at org.apache.lucene.util.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> 4) Thread[id=1692, name=SUITE-TestBooleanScorer-seed#[4EA0082AF0B1CD6F], state=RUNNABLE, group=TGRP-TestBooleanScorer] [junit4] 2> at java.lang.Thread.getStackTrace(Thread.java:1559) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$4.run(ThreadLeakControl.java:696) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$4.run(ThreadLeakControl.java:693) [junit4] 2> at java.security.AccessController.doPrivileged(Native Method) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.getStackTrace(ThreadLeakControl.java:693) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.getThreadsWithTraces(ThreadLeakControl.java:709) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.formatThreadStacksFull(ThreadLeakControl.java:689) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.access$1000(ThreadLeakControl.java:65) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$2.evaluate(ThreadLeakControl.java:415) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSuite(RandomizedRunner.java:705) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.access$200(RandomizedRunner.java:139) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$2.run(RandomizedRunner.java:626) [junit4] 2> 5) Thread[id=1781, name=Lucene Merge Thread #86, state=WAITING, group=TGRP-TestBooleanScorer] [junit4] 2> at sun.misc.Unsafe.park(Native Method) [junit4] 2> at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) [junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer.parkAndCheckInterrupt(AbstractQueuedSynchronizer.java:836) [junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireQueued(AbstractQueuedSynchronizer.java:870) [junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquire(AbstractQueuedSynchronizer.java:1199) [junit4] 2> at java.util.concurrent.locks.ReentrantLock$NonfairSync.lock(ReentrantLock.java:209) [junit4] 2> at java.util.concurrent.locks.ReentrantLock.lock(ReentrantLock.java:285) [junit4] 2> at org.apache.lucene.search.LRUQueryCache.clearCoreCacheKey(LRUQueryCache.java:350) [junit4] 2> at org.apache.lucene.search.LRUQueryCache$$Lambda$28/858490147.onClose(Unknown Source) [junit4] 2> at org.apache.lucene.index.SegmentCoreReaders.lambda$notifyCoreClosedListeners$0(SegmentCoreReaders.java:196) [junit4] 2> at org.apache.lucene.index.SegmentCoreReaders$$Lambda$18/1769455872.accept(Unknown Source) [junit4] 2> at org.apache.lucene.util.IOUtils.lambda$null$0(IOUtils.java:634) [junit4] 2> at org.apache.lucene.util.IOUtils$$Lambda$16/2048674460.close(Unknown Source) [junit4] 2> at org.apache.lucene.util.IOUtils.close(IOUtils.java:88) [junit4] 2> at org.apache.lucene.util.IOUtils.applyToAll(IOUtils.java:634) [junit4] 2> at org.apache.lucene.index.SegmentCoreReaders.notifyCoreClosedListeners(SegmentCoreReaders.java:196) [junit4] 2> at org.apache.lucene.index.SegmentCoreReaders$$Lambda$17/770218001.close(Unknown Source) [junit4] 2> at org.apache.lucene.index.SegmentCoreReaders.decRef(SegmentCoreReaders.java:172) [junit4] 2> at org.apache.lucene.index.SegmentReader.doClose(SegmentReader.java:204) [junit4] 2> at org.apache.lucene.index.IndexReader.decRef(IndexReader.java:244) [junit4] 2> at org.apache.lucene.index.ReadersAndUpdates.dropReaders(ReadersAndUpdates.java:228) [junit4] 2> at org.apache.lucene.index.IndexWriter$ReaderPool.drop(IndexWriter.java:563) [junit4] 2> at org.apache.lucene.index.IndexWriter.lambda$closeMergeReaders$1(IndexWriter.java:4412) [junit4] 2> at org.apache.lucene.index.IndexWriter$$Lambda$47/524346817.accept(Unknown Source) [junit4] 2> at org.apache.lucene.util.IOUtils.lambda$null$0(IOUtils.java:634) [junit4] 2> at org.apache.lucene.util.IOUtils$$Lambda$16/2048674460.close(Unknown Source) [junit4] 2> at org.apache.lucene.util.IOUtils.close(IOUtils.java:88) [junit4] 2> at org.apache.lucene.util.IOUtils.applyToAll(IOUtils.java:634) [junit4] 2> at org.apache.lucene.index.IndexWriter.closeMergeReaders(IndexWriter.java:4400) [junit4] 2> at org.apache.lucene.index.IndexWriter.commitMerge(IndexWriter.java:4077) [junit4] 2> at org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4639) [junit4] 2> at org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:4144) [junit4] 2> at org.apache.lucene.index.ConcurrentMergeScheduler.doMerge(ConcurrentMergeScheduler.java:625) [junit4] 2> at org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:662) [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestBooleanScorer -Dtests.method=testSparseClauseOptimization -Dtests.seed=4EA0082AF0B1CD6F -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ro -Dtests.timezone=America/Bogota -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] ERROR 7200s J2 | TestBooleanScorer.testSparseClauseOptimization <<< [junit4] > Throwable #1: java.lang.Exception: Test abandoned because suite timeout was reached. [junit4] > at __randomizedtesting.SeedInfo.seed([4EA0082AF0B1CD6F]:0) [junit4] 2> Apr 13, 2018 12:53:43 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thread(s). [junit4] 2> Apr 13, 2018 12:54:03 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> SEVERE: 2 threads leaked from SUITE scope at org.apache.lucene.search.TestBooleanScorer: [junit4] 2> 1) Thread[id=1693, name=TEST-TestBooleanScorer.testSparseClauseOptimization-seed#[4EA0082AF0B1CD6F], state=BLOCKED, group=TGRP-TestBooleanScorer] [junit4] 2> at java.lang.Object.wait(Native Method) [junit4] 2> at org.apache.lucene.index.IndexWriter.doWait(IndexWriter.java:4719) [junit4] 2> at org.apache.lucene.index.IndexWriter.waitForMerges(IndexWriter.java:2724) [junit4] 2> at org.apache.lucene.index.IndexWriter.shutdown(IndexWriter.java:1281) [junit4] 2> at org.apache.lucene.index.IndexWriter.close(IndexWriter.java:1323) [junit4] 2> at org.apache.lucene.util.IOUtils.close(IOUtils.java:88) [junit4] 2> at org.apache.lucene.util.IOUtils.close(IOUtils.java:76) [junit4] 2> at org.apache.lucene.index.RandomIndexWriter.close(RandomIndexWriter.java:434) [junit4] 2> at org.apache.lucene.search.TestBooleanScorer.testSparseClauseOptimization(TestBooleanScorer.java:284) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) [junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) [junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) [junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) [junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) [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 com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) [junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) [junit4] 2> at org.apache.lucene.util.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> 2) Thread[id=1781, name=Lucene Merge Thread #86, state=WAITING, group=TGRP-TestBooleanScorer] [junit4] 2> at sun.misc.Unsafe.park(Native Method) [junit4] 2> at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) [junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer.parkAndCheckInterrupt(AbstractQueuedSynchronizer.java:836) [junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireQueued(AbstractQueuedSynchronizer.java:870) [junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquire(AbstractQueuedSynchronizer.java:1199) [junit4] 2> at java.util.concurrent.locks.ReentrantLock$NonfairSync.lock(ReentrantLock.java:209) [junit4] 2> at java.util.concurrent.locks.ReentrantLock.lock(ReentrantLock.java:285) [junit4] 2> at org.apache.lucene.search.LRUQueryCache.clearCoreCacheKey(LRUQueryCache.java:350) [junit4] 2> at org.apache.lucene.search.LRUQueryCache$$Lambda$28/858490147.onClose(Unknown Source) [junit4] 2> at org.apache.lucene.index.SegmentCoreReaders.lambda$notifyCoreClosedListeners$0(SegmentCoreReaders.java:196) [junit4] 2> at org.apache.lucene.index.SegmentCoreReaders$$Lambda$18/1769455872.accept(Unknown Source) [junit4] 2> at org.apache.lucene.util.IOUtils.lambda$null$0(IOUtils.java:634) [junit4] 2> at org.apache.lucene.util.IOUtils$$Lambda$16/2048674460.close(Unknown Source) [junit4] 2> at org.apache.lucene.util.IOUtils.close(IOUtils.java:88) [junit4] 2> at org.apache.lucene.util.IOUtils.applyToAll(IOUtils.java:634) [junit4] 2> at org.apache.lucene.index.SegmentCoreReaders.notifyCoreClosedListeners(SegmentCoreReaders.java:196) [junit4] 2> at org.apache.lucene.index.SegmentCoreReaders$$Lambda$17/770218001.close(Unknown Source) [junit4] 2> at org.apache.lucene.index.SegmentCoreReaders.decRef(SegmentCoreReaders.java:172) [junit4] 2> at org.apache.lucene.index.SegmentReader.doClose(SegmentReader.java:204) [junit4] 2> at org.apache.lucene.index.IndexReader.decRef(IndexReader.java:244) [junit4] 2> at org.apache.lucene.index.ReadersAndUpdates.dropReaders(ReadersAndUpdates.java:228) [junit4] 2> at org.apache.lucene.index.IndexWriter$ReaderPool.drop(IndexWriter.java:563) [junit4] 2> at org.apache.lucene.index.IndexWriter.lambda$closeMergeReaders$1(IndexWriter.java:4412) [junit4] 2> at org.apache.lucene.index.IndexWriter$$Lambda$47/524346817.accept(Unknown Source) [junit4] 2> at org.apache.lucene.util.IOUtils.lambda$null$0(IOUtils.java:634) [junit4] 2> at org.apache.lucene.util.IOUtils$$Lambda$16/2048674460.close(Unknown Source) [junit4] 2> at org.apache.lucene.util.IOUtils.close(IOUtils.java:88) [junit4] 2> at org.apache.lucene.util.IOUtils.applyToAll(IOUtils.java:634) [junit4] 2> at org.apache.lucene.index.IndexWriter.closeMergeReaders(IndexWriter.java:4400) [junit4] 2> at org.apache.lucene.index.IndexWriter.commitMerge(IndexWriter.java:4077) [junit4] 2> at org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4639) [junit4] 2> at org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:4144) [junit4] 2> at org.apache.lucene.index.ConcurrentMergeScheduler.doMerge(ConcurrentMergeScheduler.java:625) [junit4] 2> at org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:662) [junit4] 2> Apr 13, 2018 12:54:03 AM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll [junit4] 2> INFO: Starting to interrupt leaked threads: [junit4] 2> 1) Thread[id=1693, name=TEST-TestBooleanScorer.testSparseClauseOptimization-seed#[4EA0082AF0B1CD6F], state=BLOCKED, group=TGRP-TestBooleanScorer] [junit4] 2> 2) Thread[id=1781, name=Lucene Merge Thread #86, state=WAITING, group=TGRP-TestBooleanScorer] [junit4] 2> Apr 13, 2018 12:54:06 AM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll [junit4] 2> SEVERE: There are still zombie threads that couldn't be terminated: [junit4] 2> 1) Thread[id=1693, name=TEST-TestBooleanScorer.testSparseClauseOptimization-seed#[4EA0082AF0B1CD6F], state=BLOCKED, group=TGRP-TestBooleanScorer] [junit4] 2> at java.lang.Object.wait(Native Method) [junit4] 2> at org.apache.lucene.index.IndexWriter.doWait(IndexWriter.java:4719) [junit4] 2> at org.apache.lucene.index.IndexWriter.waitForMerges(IndexWriter.java:2724) [junit4] 2> at org.apache.lucene.index.IndexWriter.shutdown(IndexWriter.java:1281) [junit4] 2> at org.apache.lucene.index.IndexWriter.close(IndexWriter.java:1323) [junit4] 2> at org.apache.lucene.util.IOUtils.close(IOUtils.java:88) [junit4] 2> at org.apache.lucene.util.IOUtils.close(IOUtils.java:76) [junit4] 2> at org.apache.lucene.index.RandomIndexWriter.close(RandomIndexWriter.java:434) [junit4] 2> at org.apache.lucene.search.TestBooleanScorer.testSparseClauseOptimization(TestBooleanScorer.java:284) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) [junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) [junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) [junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) [junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) [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 com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) [junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) [junit4] 2> at org.apache.lucene.util.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> 2) Thread[id=1781, name=Lucene Merge Thread #86, state=WAITING, group=TGRP-TestBooleanScorer] [junit4] 2> at sun.misc.Unsafe.park(Native Method) [junit4] 2> at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) [junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer.parkAndCheckInterrupt(AbstractQueuedSynchronizer.java:836) [junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireQueued(AbstractQueuedSynchronizer.java:870) [junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquire(AbstractQueuedSynchronizer.java:1199) [junit4] 2> at java.util.concurrent.locks.ReentrantLock$NonfairSync.lock(ReentrantLock.java:209) [junit4] 2> at java.util.concurrent.locks.ReentrantLock.lock(ReentrantLock.java:285) [junit4] 2> at org.apache.lucene.search.LRUQueryCache.clearCoreCacheKey(LRUQueryCache.java:350) [junit4] 2> at org.apache.lucene.search.LRUQueryCache$$Lambda$28/858490147.onClose(Unknown Source) [junit4] 2> at org.apache.lucene.index.SegmentCoreReaders.lambda$notifyCoreClosedListeners$0(SegmentCoreReaders.java:196) [junit4] 2> at org.apache.lucene.index.SegmentCoreReaders$$Lambda$18/1769455872.accept(Unknown Source) [junit4] 2> at org.apache.lucene.util.IOUtils.lambda$null$0(IOUtils.java:634) [junit4] 2> at org.apache.lucene.util.IOUtils$$Lambda$16/2048674460.close(Unknown Source) [junit4] 2> at org.apache.lucene.util.IOUtils.close(IOUtils.java:88) [junit4] 2> at org.apache.lucene.util.IOUtils.applyToAll(IOUtils.java:634) [junit4] 2> at org.apache.lucene.index.SegmentCoreReaders.notifyCoreClosedListeners(SegmentCoreReaders.java:196) [junit4] 2> at org.apache.lucene.index.SegmentCoreReaders$$Lambda$17/770218001.close(Unknown Source) [junit4] 2> at org.apache.lucene.index.SegmentCoreReaders.decRef(SegmentCoreReaders.java:172) [junit4] 2> at org.apache.lucene.index.SegmentReader.doClose(SegmentReader.java:204) [junit4] 2> at org.apache.lucene.index.IndexReader.decRef(IndexReader.java:244) [junit4] 2> at org.apache.lucene.index.ReadersAndUpdates.dropReaders(ReadersAndUpdates.java:228) [junit4] 2> at org.apache.lucene.index.IndexWriter$ReaderPool.drop(IndexWriter.java:563) [junit4] 2> at org.apache.lucene.index.IndexWriter.lambda$closeMergeReaders$1(IndexWriter.java:4412) [junit4] 2> at org.apache.lucene.index.IndexWriter$$Lambda$47/524346817.accept(Unknown Source) [junit4] 2> at org.apache.lucene.util.IOUtils.lambda$null$0(IOUtils.java:634) [junit4] 2> at org.apache.lucene.util.IOUtils$$Lambda$16/2048674460.close(Unknown Source) [junit4] 2> at org.apache.lucene.util.IOUtils.close(IOUtils.java:88) [junit4] 2> at org.apache.lucene.util.IOUtils.applyToAll(IOUtils.java:634) [junit4] 2> at org.apache.lucene.index.IndexWriter.closeMergeReaders(IndexWriter.java:4400) [junit4] 2> at org.apache.lucene.index.IndexWriter.commitMerge(IndexWriter.java:4077) [junit4] 2> at org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4639) [junit4] 2> at org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:4144) [junit4] 2> at org.apache.lucene.index.ConcurrentMergeScheduler.doMerge(ConcurrentMergeScheduler.java:625) [junit4] 2> at org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:662) [junit4] 2> NOTE: test params are: codec=FastCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST, chunkSize=4, maxDocsPerChunk=1, blockSize=5), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST, chunkSize=4, blockSize=5)), sim=RandomSimilarity(queryNorm=true): {field=DFR G3(800.0), category=DFR I(ne)LZ(0.3)}, locale=ro, timezone=America/Bogota [junit4] 2> NOTE: Linux 4.13.0-37-generic amd64/Oracle Corporation 1.8.0_162 (64-bit)/cpus=8,threads=3,free=102944712,total=536870912 [junit4] 2> NOTE: All tests run in this JVM: [TestSimilarity2, TestRollingBuffer, TestShardSearching, TestPayloads, TestAllFilesCheckIndexHeader, TestIntRangeFieldQueries, TestAttributeSource, TestStopFilter, TestDocsWithFieldSet, TestIntroSelector, TestReaderWrapperDVTypeCheck, TestLongValuesSource, TestSimpleExplanationsOfNonMatches, TestScorerPerf, TestConjunctions, TestIndexWriterOnDiskFull, TestCompiledAutomaton, TestBagOfPostings, TestHugeRamFile, TestSegmentTermDocs, TestBinaryDocValuesUpdates, TestReaderClosed, TestControlledRealTimeReopenThread, TestDocValuesScoring, TestBlockPostingsFormat, TestBooleanRewrites, TestIndexWriterMerging, TestConsistentFieldNumbers, TestSegmentCacheables, TestSloppyPhraseQuery2, TestDirectoryReader, TestBooleanSimilarity, TestDocument, TestCharsRef, TestMultiset, TestAssertions, TestDirectPacked, TestSynonymQuery, TestTermdocPerf, TestStressAdvance, TestDisjunctionMaxQuery, TestFixedLengthBytesRefArray, FiniteStringsIteratorTest, TestDoubleRangeFieldQueries, TestFilterCodecReader, TestHighCompressionMode, TestSleepingLockWrapper, TestForUtil, TestIndexWriterLockRelease, TestTermScorer, TestBytesRefHash, TestFieldsReader, TestSentinelIntSet, TestRamUsageEstimator, TestNRTReaderWithThreads, TestGraphTokenStreamFiniteStrings, TestSpanTermQuery, TestIndexWriterReader, TestIntRange, TestPositiveScoresOnlyCollector, TestComplexExplanationsOfNonMatches, TestMultiMMap, TestLucene50StoredFieldsFormatHighCompression, TestOneMergeWrappingMergePolicy, Test2BPoints, TestPackedTokenAttributeImpl, TestMultiFields, TestFieldCacheRewriteMethod, TestMultiTermsEnum, TestSpanSearchEquivalence, TestSimpleFSDirectory, TestLucene60PointsFormat, TestFloatRangeFieldQueries, TestIndexWriterMaxDocs, TestCollectionUtil, TestBKD, TestUpgradeIndexMergePolicy, TestCloseableThreadLocal, TestFutureObjects, TestPerFieldDocValuesFormat, TestIndexWriterCommit, TestDirectoryReaderReopen, TestSearchAfter, TestConcurrentMergeScheduler, TestTopDocsMerge, TestLongPostings, TestRollingUpdates, TestSnapshotDeletionPolicy, TestIndexWriterForceMerge, TestArrayUtil, TestByteSlices, TestLockFactory, TestSegmentMerger, TestOmitNorms, TestFixedBitSet, TestSegmentReader, TestParallelCompositeReader, TestIndexWriterNRTIsCurrent, Test2BDocs, TestSpans, TestMixedCodecs, TestFastCompressionMode, TestStressIndexing2, TestCharTermAttributeImpl, TestIndexInput, TestDocumentsWriterDeleteQueue, TestMultiTermConstantScore, TestDoc, TestWildcard, TestFieldMaskingSpanQuery, TestExternalCodecs, TestRegexpQuery, TestIntBlockPool, TestSortRandom, TestAutomatonQuery, TestMultiLevelSkipList, TestPayloadsOnVectors, TestSpanMultiTermQueryWrapper, TestIndexFileDeleter, TestSubScorerFreqs, TestRecyclingIntBlockAllocator, TestIndexWriterConfig, TestField, TestDateSort, TestSimilarity, TestVirtualMethod, TestSmallFloat, TestSetOnce, TestSearch, TestCachingTokenFilter, TestSameTokenSamePosition, TestCachingCollector, TestTotalHitCountCollector, TestBooleanScorer] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestBooleanScorer -Dtests.seed=4EA0082AF0B1CD6F -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ro -Dtests.timezone=America/Bogota -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] ERROR 0.00s J2 | TestBooleanScorer (suite) <<< [junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec). [junit4] > at __randomizedtesting.SeedInfo.seed([4EA0082AF0B1CD6F]:0) [junit4] Completed [472/472 (1!)] on J2 in 7223.09s, 3 tests, 2 errors <<< FAILURES! [...truncated 12416 lines...] [junit4] Suite: org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.IndexSizeTriggerTest_39F6A5444778DB58-001/init-core-data-001 [junit4] 2> 1821549 INFO (SUITE-IndexSizeTriggerTest-seed#[39F6A5444778DB58]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 1821550 INFO (SUITE-IndexSizeTriggerTest-seed#[39F6A5444778DB58]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 1821550 INFO (SUITE-IndexSizeTriggerTest-seed#[39F6A5444778DB58]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 1821550 INFO (SUITE-IndexSizeTriggerTest-seed#[39F6A5444778DB58]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.IndexSizeTriggerTest_39F6A5444778DB58-001/tempDir-001 [junit4] 2> 1821551 INFO (SUITE-IndexSizeTriggerTest-seed#[39F6A5444778DB58]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1821551 INFO (Thread-4931) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1821551 INFO (Thread-4931) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1821553 ERROR (Thread-4931) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 1821651 INFO (SUITE-IndexSizeTriggerTest-seed#[39F6A5444778DB58]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:39595 [junit4] 2> 1821655 INFO (zkConnectionManagerCallback-5722-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1821662 INFO (jetty-launcher-5719-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 1821662 INFO (jetty-launcher-5719-thread-2) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 1821663 INFO (jetty-launcher-5719-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1821663 INFO (jetty-launcher-5719-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1821663 INFO (jetty-launcher-5719-thread-1) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 1821664 INFO (jetty-launcher-5719-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1821664 INFO (jetty-launcher-5719-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1821664 INFO (jetty-launcher-5719-thread-2) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 1821664 INFO (jetty-launcher-5719-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4a0d76dd{/solr,null,AVAILABLE} [junit4] 2> 1821664 INFO (jetty-launcher-5719-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4d013a2d{/solr,null,AVAILABLE} [junit4] 2> 1821665 INFO (jetty-launcher-5719-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@79e5aea{SSL,[ssl, http/1.1]}{127.0.0.1:32925} [junit4] 2> 1821665 INFO (jetty-launcher-5719-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@68de0e45{SSL,[ssl, http/1.1]}{127.0.0.1:35611} [junit4] 2> 1821665 INFO (jetty-launcher-5719-thread-1) [ ] o.e.j.s.Server Started @1821696ms [junit4] 2> 1821665 INFO (jetty-launcher-5719-thread-2) [ ] o.e.j.s.Server Started @1821696ms [junit4] 2> 1821665 INFO (jetty-launcher-5719-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=35611} [junit4] 2> 1821665 INFO (jetty-launcher-5719-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=32925} [junit4] 2> 1821666 ERROR (jetty-launcher-5719-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1821666 ERROR (jetty-launcher-5719-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1821666 INFO (jetty-launcher-5719-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1821666 INFO (jetty-launcher-5719-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1821666 INFO (jetty-launcher-5719-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.4.0 [junit4] 2> 1821666 INFO (jetty-launcher-5719-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.4.0 [junit4] 2> 1821666 INFO (jetty-launcher-5719-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1821666 INFO (jetty-launcher-5719-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1821666 INFO (jetty-launcher-5719-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1821666 INFO (jetty-launcher-5719-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1821666 INFO (jetty-launcher-5719-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-13T06:38:22.940Z [junit4] 2> 1821666 INFO (jetty-launcher-5719-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-13T06:38:22.940Z [junit4] 2> 1821667 INFO (zkConnectionManagerCallback-5725-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1821667 INFO (zkConnectionManagerCallback-5726-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1821667 INFO (jetty-launcher-5719-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1821667 INFO (jetty-launcher-5719-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1821671 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x10042b48ae80002, likely client has closed socket [junit4] 2> 1821673 INFO (jetty-launcher-5719-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39595/solr [junit4] 2> 1821673 INFO (jetty-launcher-5719-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39595/solr [junit4] 2> 1821673 INFO (zkConnectionManagerCallback-5732-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1821674 INFO (zkConnectionManagerCallback-5734-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1821674 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x10042b48ae80004, likely client has closed socket [junit4] 2> 1821675 INFO (zkConnectionManagerCallback-5737-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1821675 INFO (zkConnectionManagerCallback-5738-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1821703 INFO (jetty-launcher-5719-thread-2) [n:127.0.0.1:35611_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1821703 INFO (jetty-launcher-5719-thread-1) [n:127.0.0.1:32925_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1821704 INFO (jetty-launcher-5719-thread-2) [n:127.0.0.1:35611_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:35611_solr [junit4] 2> 1821704 INFO (jetty-launcher-5719-thread-1) [n:127.0.0.1:32925_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:32925_solr [junit4] 2> 1821704 INFO (jetty-launcher-5719-thread-2) [n:127.0.0.1:35611_solr ] o.a.s.c.Overseer Overseer (id=72130937229934598-127.0.0.1:35611_solr-n_0000000000) starting [junit4] 2> 1821707 INFO (zkCallback-5735-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1821707 INFO (zkCallback-5736-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1821710 INFO (zkConnectionManagerCallback-5747-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1821711 INFO (jetty-launcher-5719-thread-2) [n:127.0.0.1:35611_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1821711 INFO (jetty-launcher-5719-thread-2) [n:127.0.0.1:35611_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39595/solr ready [junit4] 2> 1821720 DEBUG (OverseerAutoScalingTriggerThread-72130937229934598-127.0.0.1:35611_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers [junit4] 2> 1821720 DEBUG (OverseerAutoScalingTriggerThread-72130937229934598-127.0.0.1:35611_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1 [junit4] 2> 1821720 INFO (jetty-launcher-5719-thread-2) [n:127.0.0.1:35611_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1821720 INFO (jetty-launcher-5719-thread-2) [n:127.0.0.1:35611_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35611_solr [junit4] 2> 1821720 DEBUG (OverseerAutoScalingTriggerThread-72130937229934598-127.0.0.1:35611_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1 [junit4] 2> 1821720 DEBUG (OverseerAutoScalingTriggerThread-72130937229934598-127.0.0.1:35611_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1 [junit4] 2> 1821721 INFO (zkCallback-5735-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1821721 INFO (zkCallback-5736-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1821723 INFO (zkCallback-5746-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1821728 DEBUG (OverseerAutoScalingTriggerThread-72130937229934598-127.0.0.1:35611_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:32925_solr, 127.0.0.1:35611_solr] [junit4] 2> 1821732 DEBUG (ScheduledTrigger-8651-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1821735 DEBUG (OverseerAutoScalingTriggerThread-72130937229934598-127.0.0.1:35611_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 1821735 DEBUG (OverseerAutoScalingTriggerThread-72130937229934598-127.0.0.1:35611_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1 [junit4] 2> 1821790 INFO (jetty-launcher-5719-thread-1) [n:127.0.0.1:32925_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_32925.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2243e224 [junit4] 2> 1821794 INFO (jetty-launcher-5719-thread-1) [n:127.0.0.1:32925_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_32925.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanser [...truncated too long message...] url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@7e64eb33 [junit4] 2> 309775 INFO (jetty-closer-59-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:38257_solr [junit4] 2> 309775 INFO (jetty-closer-59-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 309775 INFO (zkCallback-85-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 309775 INFO (zkCallback-90-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 309775 INFO (zkCallback-76-thread-4) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 309775 INFO (zkCallback-74-thread-6) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 309778 INFO (jetty-closer-59-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 309778 INFO (jetty-closer-59-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@79f9e0e8: rootName = solr_34661, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@3ff1e1f5 [junit4] 2> 309779 INFO (jetty-closer-59-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1eae377c{/solr,null,UNAVAILABLE} [junit4] 2> 309779 INFO (jetty-closer-59-thread-1) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 309779 INFO (jetty-closer-59-thread-2) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:34661_solr [junit4] 2> 309780 INFO (jetty-closer-59-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 309780 INFO (zkCallback-74-thread-6) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 309780 INFO (zkCallback-85-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 309789 INFO (jetty-closer-59-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72131060109606917-127.0.0.1:34661_solr-n_0000000000) closing [junit4] 2> 309789 INFO (OverseerStateUpdate-72131060109606917-127.0.0.1:34661_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:34661_solr [junit4] 2> 309790 DEBUG (jetty-closer-59-thread-2) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now [junit4] 2> 309790 WARN (OverseerAutoScalingTriggerThread-72131060109606917-127.0.0.1:34661_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 309790 DEBUG (jetty-closer-59-thread-2) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now [junit4] 2> 309790 DEBUG (jetty-closer-59-thread-2) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor [junit4] 2> 309790 DEBUG (jetty-closer-59-thread-2) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor [junit4] 2> 309791 DEBUG (jetty-closer-59-thread-2) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely [junit4] 2> 309791 DEBUG (jetty-closer-59-thread-2) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly [junit4] 2> 309794 INFO (jetty-closer-59-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7ac7418f{/solr,null,UNAVAILABLE} [junit4] 2> 309794 INFO (jetty-closer-59-thread-2) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 309794 ERROR (SUITE-IndexSizeTriggerTest-seed#[39F6A5444778DB58]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 309794 INFO (SUITE-IndexSizeTriggerTest-seed#[39F6A5444778DB58]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43993 43993 [junit4] 2> 309918 INFO (Thread-40) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43993 43993 [junit4] 2> 309919 WARN (Thread-40) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 13 /solr/collections/testSplitIntegration_collection/terms/shard1_1 [junit4] 2> 13 /solr/collections/testSplitIntegration_collection/terms/shard2_0 [junit4] 2> 12 /solr/collections/testMixedBounds_collection/terms/shard2_0 [junit4] 2> 10 /solr/collections/testTrigger_collection/terms/shard1 [junit4] 2> 10 /solr/collections/testTrigger_collection/terms/shard2 [junit4] 2> 10 /solr/collections/testMergeIntegration_collection/terms/shard2 [junit4] 2> 10 /solr/collections/testMergeIntegration_collection/terms/shard1 [junit4] 2> 10 /solr/collections/testSplitIntegration_collection/terms/shard1 [junit4] 2> 9 /solr/collections/testSplitIntegration_collection/terms/shard1_0 [junit4] 2> 9 /solr/collections/testMixedBounds_collection/terms/shard2_1 [junit4] 2> 9 /solr/aliases.json [junit4] 2> 9 /solr/collections/testMixedBounds_collection/terms/shard1 [junit4] 2> 9 /solr/collections/testMixedBounds_collection/terms/shard2 [junit4] 2> 8 /solr/collections/testSplitIntegration_collection/terms/shard2_1 [junit4] 2> 8 /solr/configs/conf [junit4] 2> 8 /solr/collections/testSplitIntegration_collection/terms/shard2 [junit4] 2> 2 /solr/security.json [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 74 /solr/collections/testSplitIntegration_collection/state.json [junit4] 2> 38 /solr/collections/testMixedBounds_collection/state.json [junit4] 2> 17 /solr/collections/testTrigger_collection/state.json [junit4] 2> 9 /solr/clusterprops.json [junit4] 2> 9 /solr/clusterstate.json [junit4] 2> 7 /solr/collections/testMergeIntegration_collection/state.json [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 25 /solr/collections [junit4] 2> 9 /solr/live_nodes [junit4] 2> [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.IndexSizeTriggerTest_39F6A5444778DB58-001 [junit4] 2> Apr 13, 2018 7:12:44 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarity(queryNorm=true): {}, locale=it-CH, timezone=Europe/Bucharest [junit4] 2> NOTE: Linux 4.13.0-37-generic amd64/Oracle Corporation 1.8.0_162 (64-bit)/cpus=8,threads=1,free=282004080,total=536870912 [junit4] 2> NOTE: All tests run in this JVM: [IndexSizeTriggerTest, IndexSizeTriggerTest] [junit4] Completed [5/5 (3!)] on J2 in 186.96s, 4 tests, 1 failure <<< FAILURES! [junit4] [junit4] [junit4] Tests with failures [seed: 39F6A5444778DB58]: [junit4] - org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testMixedBounds [junit4] - org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testMixedBounds [junit4] - org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testMixedBounds [junit4] [junit4] [junit4] JVM J0: 0.42 .. 304.45 = 304.02s [junit4] JVM J1: 0.42 .. 196.96 = 196.54s [junit4] JVM J2: 0.42 .. 310.87 = 310.45s [junit4] Execution time total: 5 minutes 10 seconds [junit4] Tests summary: 5 suites, 20 tests, 3 failures BUILD FAILED /home/jenkins/workspace/Lucene-Solr-7.x-Linux/lucene/common-build.xml:1568: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/lucene/common-build.xml:1092: There were test failures: 5 suites, 20 tests, 3 failures [seed: 39F6A5444778DB58] Total time: 5 minutes 12 seconds [repro] Setting last failure code to 256 [repro] Failures: [repro] 0/5 failed: org.apache.lucene.search.TestBooleanScorer [repro] 3/5 failed: org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest [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=e541ed89f3e2968e0a4497035aaa42614d050e8d, workspace=/var/lib/jenkins/workspace/Lucene-Solr-7.x-Linux [WARNINGS] Computing warning deltas based on reference build #1705 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