You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2018/04/13 07:14:29 UTC
[JENKINS] Lucene-Solr-7.x-Linux (64bit/jdk1.8.0_162) - Build # 1706
- Still Unstable!
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