Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/1536/
5 tests failed.
FAILED: org.apache.lucene.search.TestLRUQueryCache.testBulkScorerLocking
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([EDB8471A55F72A0B]:0)
FAILED: junit.framework.TestSuite.org.apache.lucene.search.TestLRUQueryCache
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([EDB8471A55F72A0B]:0)
FAILED:
org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation.testForwarding
Error Message:
Error from server at https://127.0.0.1:52664/solr: KeeperErrorCode = Session
expired for /configs/conf1
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error
from server at https://127.0.0.1:52664/solr: KeeperErrorCode = Session expired
for /configs/conf1
at
__randomizedtesting.SeedInfo.seed([8A414661D5BAFF70:6BC72F9FC88C1999]:0)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1106)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:886)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:819)
at
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
at
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211)
at
org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation.create1ShardCollection(TestSolrCloudWithSecureImpersonation.java:185)
at
org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation.testForwarding(TestSolrCloudWithSecureImpersonation.java:342)
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)
FAILED:
org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testSplitIntegration
Error Message:
last state:
DocCollection(testSplitIntegration_collection//clusterstate.json/32)={
"replicationFactor":"2", "pullReplicas":"0",
"router":{"name":"compositeId"}, "maxShardsPerNode":"2",
"autoAddReplicas":"false", "nrtReplicas":"2", "tlogReplicas":"0",
"autoCreated":"true", "shards":{ "shard2":{ "replicas":{
"core_node3":{
"core":"testSplitIntegration_collection_shard2_replica_n3",
"leader":"true", "SEARCHER.searcher.maxDoc":11,
"SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":1,
"node_name":"127.0.0.1:10000_solr", "state":"active",
"type":"NRT", "SEARCHER.searcher.numDocs":11}, "core_node4":{
"core":"testSplitIntegration_collection_shard2_replica_n4",
"SEARCHER.searcher.maxDoc":11, "SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":1, "node_name":"127.0.0.1:10001_solr",
"state":"active", "type":"NRT",
"SEARCHER.searcher.numDocs":11}}, "range":"0-7fffffff",
"state":"active"}, "shard1":{ "stateTimestamp":"1524912099337607100",
"replicas":{ "core_node1":{
"core":"testSplitIntegration_collection_shard1_replica_n1",
"leader":"true", "SEARCHER.searcher.maxDoc":14,
"SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":1,
"node_name":"127.0.0.1:10000_solr", "state":"active",
"type":"NRT", "SEARCHER.searcher.numDocs":14}, "core_node2":{
"core":"testSplitIntegration_collection_shard1_replica_n2",
"SEARCHER.searcher.maxDoc":14, "SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":1, "node_name":"127.0.0.1:10001_solr",
"state":"active", "type":"NRT",
"SEARCHER.searcher.numDocs":14}}, "range":"80000000-ffffffff",
"state":"inactive"}, "shard1_1":{ "parent":"shard1",
"stateTimestamp":"1524912099362110950", "range":"c0000000-ffffffff",
"state":"active", "replicas":{ "core_node10":{
"leader":"true",
"core":"testSplitIntegration_collection_shard1_1_replica1",
"SEARCHER.searcher.maxDoc":7, "SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":1, "node_name":"127.0.0.1:10000_solr",
"base_url":"http://127.0.0.1:10000/solr";, "state":"active",
"type":"NRT", "SEARCHER.searcher.numDocs":7},
"core_node9":{
"core":"testSplitIntegration_collection_shard1_1_replica0",
"SEARCHER.searcher.maxDoc":7, "SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":1, "node_name":"127.0.0.1:10001_solr",
"base_url":"http://127.0.0.1:10001/solr";, "state":"active",
"type":"NRT", "SEARCHER.searcher.numDocs":7}}}, "shard1_0":{
"parent":"shard1", "stateTimestamp":"1524912099361792450",
"range":"80000000-bfffffff", "state":"active", "replicas":{
"core_node7":{ "leader":"true",
"core":"testSplitIntegration_collection_shard1_0_replica0",
"SEARCHER.searcher.maxDoc":7, "SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":1, "node_name":"127.0.0.1:10001_solr",
"base_url":"http://127.0.0.1:10001/solr";, "state":"active",
"type":"NRT", "SEARCHER.searcher.numDocs":7},
"core_node8":{
"core":"testSplitIntegration_collection_shard1_0_replica1",
"SEARCHER.searcher.maxDoc":7, "SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":1, "node_name":"127.0.0.1:10000_solr",
"base_url":"http://127.0.0.1:10000/solr";, "state":"active",
"type":"NRT", "SEARCHER.searcher.numDocs":7}}}}}
Stack Trace:
java.util.concurrent.TimeoutException: last state:
DocCollection(testSplitIntegration_collection//clusterstate.json/32)={
"replicationFactor":"2",
"pullReplicas":"0",
"router":{"name":"compositeId"},
"maxShardsPerNode":"2",
"autoAddReplicas":"false",
"nrtReplicas":"2",
"tlogReplicas":"0",
"autoCreated":"true",
"shards":{
"shard2":{
"replicas":{
"core_node3":{
"core":"testSplitIntegration_collection_shard2_replica_n3",
"leader":"true",
"SEARCHER.searcher.maxDoc":11,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":1,
"node_name":"127.0.0.1:10000_solr",
"state":"active",
"type":"NRT",
"SEARCHER.searcher.numDocs":11},
"core_node4":{
"core":"testSplitIntegration_collection_shard2_replica_n4",
"SEARCHER.searcher.maxDoc":11,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":1,
"node_name":"127.0.0.1:10001_solr",
"state":"active",
"type":"NRT",
"SEARCHER.searcher.numDocs":11}},
"range":"0-7fffffff",
"state":"active"},
"shard1":{
"stateTimestamp":"1524912099337607100",
"replicas":{
"core_node1":{
"core":"testSplitIntegration_collection_shard1_replica_n1",
"leader":"true",
"SEARCHER.searcher.maxDoc":14,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":1,
"node_name":"127.0.0.1:10000_solr",
"state":"active",
"type":"NRT",
"SEARCHER.searcher.numDocs":14},
"core_node2":{
"core":"testSplitIntegration_collection_shard1_replica_n2",
"SEARCHER.searcher.maxDoc":14,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":1,
"node_name":"127.0.0.1:10001_solr",
"state":"active",
"type":"NRT",
"SEARCHER.searcher.numDocs":14}},
"range":"80000000-ffffffff",
"state":"inactive"},
"shard1_1":{
"parent":"shard1",
"stateTimestamp":"1524912099362110950",
"range":"c0000000-ffffffff",
"state":"active",
"replicas":{
"core_node10":{
"leader":"true",
"core":"testSplitIntegration_collection_shard1_1_replica1",
"SEARCHER.searcher.maxDoc":7,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":1,
"node_name":"127.0.0.1:10000_solr",
"base_url":"http://127.0.0.1:10000/solr";,
"state":"active",
"type":"NRT",
"SEARCHER.searcher.numDocs":7},
"core_node9":{
"core":"testSplitIntegration_collection_shard1_1_replica0",
"SEARCHER.searcher.maxDoc":7,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":1,
"node_name":"127.0.0.1:10001_solr",
"base_url":"http://127.0.0.1:10001/solr";,
"state":"active",
"type":"NRT",
"SEARCHER.searcher.numDocs":7}}},
"shard1_0":{
"parent":"shard1",
"stateTimestamp":"1524912099361792450",
"range":"80000000-bfffffff",
"state":"active",
"replicas":{
"core_node7":{
"leader":"true",
"core":"testSplitIntegration_collection_shard1_0_replica0",
"SEARCHER.searcher.maxDoc":7,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":1,
"node_name":"127.0.0.1:10001_solr",
"base_url":"http://127.0.0.1:10001/solr";,
"state":"active",
"type":"NRT",
"SEARCHER.searcher.numDocs":7},
"core_node8":{
"core":"testSplitIntegration_collection_shard1_0_replica1",
"SEARCHER.searcher.maxDoc":7,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":1,
"node_name":"127.0.0.1:10000_solr",
"base_url":"http://127.0.0.1:10000/solr";,
"state":"active",
"type":"NRT",
"SEARCHER.searcher.numDocs":7}}}}}
at
__randomizedtesting.SeedInfo.seed([8A414661D5BAFF70:B3CFFF21FA45368E]:0)
at
org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:111)
at
org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testSplitIntegration(IndexSizeTriggerTest.java:299)
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)
FAILED:
org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testMergeIntegration
Error Message:
did not finish processing in time
Stack Trace:
java.lang.AssertionError: did not finish processing in time
at
__randomizedtesting.SeedInfo.seed([8A414661D5BAFF70:D9F804D137AB6A8A]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at
org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testMergeIntegration(IndexSizeTriggerTest.java:404)
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)
Build Log:
[...truncated 1899 lines...]
[junit4] Suite: org.apache.lucene.search.TestLRUQueryCache
[junit4] 2> ???. 28, 2018 10:27:54 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl$2 evaluate
[junit4] 2> WARNING: Suite execution timed out:
org.apache.lucene.search.TestLRUQueryCache
[junit4] 2> 1) Thread[id=9, 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> 2) Thread[id=1021,
name=SUITE-TestLRUQueryCache-seed#[EDB8471A55F72A0B], state=RUNNABLE,
group=TGRP-TestLRUQueryCache]
[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> 3) 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> 4) Thread[id=1022,
name=TEST-TestLRUQueryCache.testBulkScorerLocking-seed#[EDB8471A55F72A0B],
state=RUNNABLE, group=TGRP-TestLRUQueryCache]
[junit4] 2> at java.util.HashMap.putVal(HashMap.java:642)
[junit4] 2> at java.util.HashMap.put(HashMap.java:612)
[junit4] 2> at java.util.HashSet.add(HashSet.java:220)
[junit4] 2> at
java.util.AbstractCollection.addAll(AbstractCollection.java:344)
[junit4] 2> at
org.apache.lucene.index.SegmentInfos.files(SegmentInfos.java:768)
[junit4] 2> at
org.apache.lucene.index.IndexFileDeleter.incRef(IndexFileDeleter.java:546)
[junit4] 2> at
org.apache.lucene.index.IndexFileDeleter.checkpoint(IndexFileDeleter.java:513)
[junit4] 2> at
org.apache.lucene.index.IndexWriter.checkpoint(IndexWriter.java:2485)
[junit4] 2> at
org.apache.lucene.index.IndexWriter.publishFlushedSegment(IndexWriter.java:2561)
[junit4] 2> at
org.apache.lucene.index.IndexWriter.lambda$publishFlushedSegments$5(IndexWriter.java:4893)
[junit4] 2> at
org.apache.lucene.index.IndexWriter$$Lambda$10/527365447.accept(Unknown Source)
[junit4] 2> at
org.apache.lucene.index.DocumentsWriterFlushQueue.innerPurge(DocumentsWriterFlushQueue.java:115)
[junit4] 2> at
org.apache.lucene.index.DocumentsWriterFlushQueue.tryPurge(DocumentsWriterFlushQueue.java:146)
[junit4] 2> at
org.apache.lucene.index.DocumentsWriter.purgeFlushTickets(DocumentsWriter.java:199)
[junit4] 2> at
org.apache.lucene.index.IndexWriter.publishFlushedSegments(IndexWriter.java:4873)
[junit4] 2> at
org.apache.lucene.index.IndexWriter$1.afterSegmentsFlushed(IndexWriter.java:362)
[junit4] 2> at
org.apache.lucene.index.DocumentsWriter.doFlush(DocumentsWriter.java:596)
[junit4] 2> at
org.apache.lucene.index.DocumentsWriter.postUpdate(DocumentsWriter.java:411)
[junit4] 2> at
org.apache.lucene.index.DocumentsWriter.updateDocument(DocumentsWriter.java:512)
[junit4] 2> at
org.apache.lucene.index.IndexWriter.updateDocument(IndexWriter.java:1518)
[junit4] 2> at
org.apache.lucene.index.IndexWriter.addDocument(IndexWriter.java:1210)
[junit4] 2> at
org.apache.lucene.search.TestLRUQueryCache.testBulkScorerLocking(TestLRUQueryCache.java:1603)
[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> NOTE: download the large Jenkins line-docs file by running
'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestLRUQueryCache
-Dtests.method=testBulkScorerLocking -Dtests.seed=EDB8471A55F72A0B
-Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt
-Dtests.locale=mk-MK -Dtests.timezone=Europe/Vatican -Dtests.asserts=true
-Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 7130s J0 | TestLRUQueryCache.testBulkScorerLocking <<<
[junit4] > Throwable #1: java.lang.Exception: Test abandoned because
suite timeout was reached.
[junit4] > at
__randomizedtesting.SeedInfo.seed([EDB8471A55F72A0B]:0)
[junit4] 2> ???. 28, 2018 10:27:54 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> ???. 28, 2018 10:28:14 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> SEVERE: 1 thread leaked from SUITE scope at
org.apache.lucene.search.TestLRUQueryCache:
[junit4] 2> 1) Thread[id=1022,
name=TEST-TestLRUQueryCache.testBulkScorerLocking-seed#[EDB8471A55F72A0B],
state=RUNNABLE, group=TGRP-TestLRUQueryCache]
[junit4] 2> at
java.util.Collections$UnmodifiableCollection.size(Collections.java:1030)
[junit4] 2> at java.util.HashSet.<init>(HashSet.java:119)
[junit4] 2> at
org.apache.lucene.index.SegmentCommitInfo.files(SegmentCommitInfo.java:224)
[junit4] 2> at
org.apache.lucene.index.SegmentInfos.files(SegmentInfos.java:768)
[junit4] 2> at
org.apache.lucene.index.IndexFileDeleter.incRef(IndexFileDeleter.java:546)
[junit4] 2> at
org.apache.lucene.index.IndexFileDeleter.checkpoint(IndexFileDeleter.java:513)
[junit4] 2> at
org.apache.lucene.index.IndexWriter.checkpoint(IndexWriter.java:2485)
[junit4] 2> at
org.apache.lucene.index.IndexWriter.publishFlushedSegment(IndexWriter.java:2561)
[junit4] 2> at
org.apache.lucene.index.IndexWriter.lambda$publishFlushedSegments$5(IndexWriter.java:4893)
[junit4] 2> at
org.apache.lucene.index.IndexWriter$$Lambda$10/527365447.accept(Unknown Source)
[junit4] 2> at
org.apache.lucene.index.DocumentsWriterFlushQueue.innerPurge(DocumentsWriterFlushQueue.java:115)
[junit4] 2> at
org.apache.lucene.index.DocumentsWriterFlushQueue.tryPurge(DocumentsWriterFlushQueue.java:146)
[junit4] 2> at
org.apache.lucene.index.DocumentsWriter.purgeFlushTickets(DocumentsWriter.java:199)
[junit4] 2> at
org.apache.lucene.index.IndexWriter.publishFlushedSegments(IndexWriter.java:4873)
[junit4] 2> at
org.apache.lucene.index.IndexWriter$1.afterSegmentsFlushed(IndexWriter.java:362)
[junit4] 2> at
org.apache.lucene.index.DocumentsWriter.doFlush(DocumentsWriter.java:596)
[junit4] 2> at
org.apache.lucene.index.DocumentsWriter.postUpdate(DocumentsWriter.java:411)
[junit4] 2> at
org.apache.lucene.index.DocumentsWriter.updateDocument(DocumentsWriter.java:512)
[junit4] 2> at
org.apache.lucene.index.IndexWriter.updateDocument(IndexWriter.java:1518)
[junit4] 2> at
org.apache.lucene.index.IndexWriter.addDocument(IndexWriter.java:1210)
[junit4] 2> at
org.apache.lucene.search.TestLRUQueryCache.testBulkScorerLocking(TestLRUQueryCache.java:1603)
[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> ???. 28, 2018 10:28:14 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=1022,
name=TEST-TestLRUQueryCache.testBulkScorerLocking-seed#[EDB8471A55F72A0B],
state=RUNNABLE, group=TGRP-TestLRUQueryCache]
[junit4] 2> ???. 28, 2018 10:28:17 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> SEVERE: There are still zombie threads that couldn't be
terminated:
[junit4] 2> 1) Thread[id=1022,
name=TEST-TestLRUQueryCache.testBulkScorerLocking-seed#[EDB8471A55F72A0B],
state=RUNNABLE, group=TGRP-TestLRUQueryCache]
[junit4] 2> at java.util.HashMap.putVal(HashMap.java:631)
[junit4] 2> at java.util.HashMap.put(HashMap.java:612)
[junit4] 2> at java.util.HashSet.add(HashSet.java:220)
[junit4] 2> at
java.util.AbstractCollection.addAll(AbstractCollection.java:344)
[junit4] 2> at java.util.HashSet.<init>(HashSet.java:120)
[junit4] 2> at
org.apache.lucene.index.SegmentCommitInfo.files(SegmentCommitInfo.java:224)
[junit4] 2> at
org.apache.lucene.index.SegmentInfos.files(SegmentInfos.java:768)
[junit4] 2> at
org.apache.lucene.index.IndexFileDeleter.checkpoint(IndexFileDeleter.java:533)
[junit4] 2> at
org.apache.lucene.index.IndexWriter.checkpoint(IndexWriter.java:2485)
[junit4] 2> at
org.apache.lucene.index.IndexWriter.publishFlushedSegment(IndexWriter.java:2561)
[junit4] 2> at
org.apache.lucene.index.IndexWriter.lambda$publishFlushedSegments$5(IndexWriter.java:4893)
[junit4] 2> at
org.apache.lucene.index.IndexWriter$$Lambda$10/527365447.accept(Unknown Source)
[junit4] 2> at
org.apache.lucene.index.DocumentsWriterFlushQueue.innerPurge(DocumentsWriterFlushQueue.java:115)
[junit4] 2> at
org.apache.lucene.index.DocumentsWriterFlushQueue.tryPurge(DocumentsWriterFlushQueue.java:146)
[junit4] 2> at
org.apache.lucene.index.DocumentsWriter.purgeFlushTickets(DocumentsWriter.java:199)
[junit4] 2> at
org.apache.lucene.index.IndexWriter.publishFlushedSegments(IndexWriter.java:4873)
[junit4] 2> at
org.apache.lucene.index.IndexWriter$1.afterSegmentsFlushed(IndexWriter.java:362)
[junit4] 2> at
org.apache.lucene.index.DocumentsWriter.doFlush(DocumentsWriter.java:596)
[junit4] 2> at
org.apache.lucene.index.DocumentsWriter.postUpdate(DocumentsWriter.java:411)
[junit4] 2> at
org.apache.lucene.index.DocumentsWriter.updateDocument(DocumentsWriter.java:512)
[junit4] 2> at
org.apache.lucene.index.IndexWriter.updateDocument(IndexWriter.java:1518)
[junit4] 2> at
org.apache.lucene.index.IndexWriter.addDocument(IndexWriter.java:1210)
[junit4] 2> at
org.apache.lucene.search.TestLRUQueryCache.testBulkScorerLocking(TestLRUQueryCache.java:1603)
[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> NOTE: test params are: codec=Asserting(Lucene70):
{color=PostingsFormat(name=LuceneVarGapFixedInterval),
f=PostingsFormat(name=LuceneFixedGap), foo=PostingsFormat(name=LuceneFixedGap),
id=PostingsFormat(name=LuceneVarGapFixedInterval), text=FSTOrd50},
docValues:{field=DocValuesFormat(name=Memory),
soft_delete=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=1022,
maxMBSortInHeap=6.016918648961516,
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@2dbe0804),
locale=mk-MK, timezone=Europe/Vatican
[junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation
1.8.0_172 (64-bit)/cpus=4,threads=2,free=111504624,total=529006592
[junit4] 2> NOTE: All tests run in this JVM:
[TestSpanMultiTermQueryWrapper, TestIndexWriter, TestMergedIterator,
TestIntroSelector, TestUnicodeUtil, TestTermsEnum,
TestTragicIndexWriterDeadlock, TestFilterDirectory, TestIndexCommit,
TestMaxTermFrequency, TestIndexWriterOnVMError, FiniteStringsIteratorTest,
TestCharArrayMap, TestLMDirichletSimilarity, TestNearSpansOrdered,
TestUsageTrackingFilterCachingPolicy, TestStressIndexing, TestIndexableField,
TestIndexWriterForceMerge, TestExitableDirectoryReader,
TestSortedNumericSortField, TestUniqueTermCount, TestSleepingLockWrapper,
TestIndependenceChiSquared, TestGraphTokenStreamFiniteStrings,
Test2BNumericDocValues, TestOfflineSorter, TestSimpleExplanations,
Test2BBinaryDocValues, TestSparseFixedBitDocIdSet, Test2BPagedBytes,
TestMultiCollector, TestPendingDeletes, TestRoaringDocIdSet, TestSort,
TestCharsRefBuilder, TestIntRange, TestIndexWriterWithThreads,
TestLucene50StoredFieldsFormatHighCompression, TestForUtil,
TestDocsWithFieldSet, TestFlex, TestVirtualMethod, TestLRUQueryCache]
[junit4] 2> NOTE: download the large Jenkins line-docs file by running
'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestLRUQueryCache
-Dtests.seed=EDB8471A55F72A0B -Dtests.multiplier=2 -Dtests.nightly=true
-Dtests.slow=true
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt
-Dtests.locale=mk-MK -Dtests.timezone=Europe/Vatican -Dtests.asserts=true
-Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s J0 | TestLRUQueryCache (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>=
7200000 msec).
[junit4] > at
__randomizedtesting.SeedInfo.seed([EDB8471A55F72A0B]:0)
[junit4] Completed [453/493 (1!)] on J0 in 7223.20s, 19 tests, 2 errors <<<
FAILURES!
[...truncated 11787 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest
[junit4] 2> 1849008 INFO
(SUITE-IndexSizeTriggerTest-seed#[8A414661D5BAFF70]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.IndexSizeTriggerTest_8A414661D5BAFF70-001/init-core-data-001
[junit4] 2> 1849009 WARN
(SUITE-IndexSizeTriggerTest-seed#[8A414661D5BAFF70]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 1849009 INFO
(SUITE-IndexSizeTriggerTest-seed#[8A414661D5BAFF70]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1849010 INFO
(SUITE-IndexSizeTriggerTest-seed#[8A414661D5BAFF70]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 1849011 INFO
(SUITE-IndexSizeTriggerTest-seed#[8A414661D5BAFF70]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.IndexSizeTriggerTest_8A414661D5BAFF70-001/tempDir-001
[junit4] 2> 1849012 INFO
(SUITE-IndexSizeTriggerTest-seed#[8A414661D5BAFF70]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1849012 INFO (Thread-140768) [ ] o.a.s.c.ZkTestServer
client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1849012 INFO (Thread-140768) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1849023 ERROR (Thread-140768) [ ] 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> 1849112 INFO
(SUITE-IndexSizeTriggerTest-seed#[8A414661D5BAFF70]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:38850
[junit4] 2> 1849115 INFO (zkConnectionManagerCallback-1329-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1849122 INFO (jetty-launcher-1326-thread-1) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-22T04:27:37+07:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1849129 INFO (jetty-launcher-1326-thread-2) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-22T04:27:37+07:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1849142 INFO (jetty-launcher-1326-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1849142 INFO (jetty-launcher-1326-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1849142 INFO (jetty-launcher-1326-thread-2) [ ]
o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 1849142 INFO (jetty-launcher-1326-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1849142 INFO (jetty-launcher-1326-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1849142 INFO (jetty-launcher-1326-thread-1) [ ]
o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 1849142 INFO (jetty-launcher-1326-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@2fc31644{/solr,null,AVAILABLE}
[junit4] 2> 1849143 INFO (jetty-launcher-1326-thread-2) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@5a5ebe42{SSL,[ssl,
http/1.1]}{127.0.0.1:54747}
[junit4] 2> 1849143 INFO (jetty-launcher-1326-thread-2) [ ]
o.e.j.s.Server Started @1849190ms
[junit4] 2> 1849143 INFO (jetty-launcher-1326-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=54747}
[junit4] 2> 1849143 ERROR (jetty-launcher-1326-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1849143 INFO (jetty-launcher-1326-thread-2) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1849143 INFO (jetty-launcher-1326-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 1849143 INFO (jetty-launcher-1326-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1849143 INFO (jetty-launcher-1326-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1849143 INFO (jetty-launcher-1326-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-04-28T10:41:05.206Z
[junit4] 2> 1849149 INFO (jetty-launcher-1326-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@2d0a0f1b{/solr,null,AVAILABLE}
[junit4] 2> 1849149 INFO (jetty-launcher-1326-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@1b8500ae{SSL,[ssl,
http/1.1]}{127.0.0.1:52299}
[junit4] 2> 1849149 INFO (jetty-launcher-1326-thread-1) [ ]
o.e.j.s.Server Started @1849196ms
[junit4] 2> 1849149 INFO (jetty-launcher-1326-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=52299}
[junit4] 2> 1849149 ERROR (jetty-launcher-1326-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1849149 INFO (jetty-launcher-1326-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1849149 INFO (jetty-launcher-1326-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 1849149 INFO (jetty-launcher-1326-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1849149 INFO (jetty-launcher-1326-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1849149 INFO (jetty-launcher-1326-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-04-28T10:41:05.212Z
[junit4] 2> 1849150 INFO (zkConnectionManagerCallback-1331-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1849150 INFO (jetty-launcher-1326-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1849154 INFO (zkConnectionManagerCallback-1333-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1849156 INFO (jetty-launcher-1326-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1849158 INFO (jetty-launcher-1326-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38850/solr
[junit4] 2> 1849165 INFO (jetty-launcher-1326-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38850/solr
[junit4] 2> 1849165 INFO (zkConnectionManagerCallback-1337-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1849166 INFO (zkConnectionManagerCallback-1341-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1849168 INFO (zkConnectionManagerCallback-1343-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1849169 INFO (zkConnectionManagerCallback-1345-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1849241 INFO (jetty-launcher-1326-thread-2)
[n:127.0.0.1:54747_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1849242 INFO (jetty-launcher-1326-thread-1)
[n:127.0.0.1:52299_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1849243 INFO (jetty-launcher-1326-thread-2)
[n:127.0.0.1:54747_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:54747_solr
[junit4] 2> 1849243 INFO (jetty-launcher-1326-thread-1)
[n:127.0.0.1:52299_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:52299_solr
[junit4] 2> 1849244 INFO (jetty-launcher-1326-thread-2)
[n:127.0.0.1:54747_solr ] o.a.s.c.Overseer Overseer
(id=73706739427704837-127.0.0.1:54747_solr-n_0000000000) starting
[junit4] 2> 1849247 INFO (zkCallback-1342-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1849247 INFO (zkCallback-1344-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1849262 INFO (zkConnectionManagerCallback-1354-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1849262 INFO (jetty-launcher-1326-thread-2)
[n:127.0.0.1:54747_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1849263 INFO (jetty-launcher-1326-thread-2)
[n:127.0.0.1:54747_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:38850/solr ready
[junit4] 2> 1849267 INFO (jetty-launcher-1326-thread-2)
[n:127.0.0.1:54747_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1849267 INFO (jetty-launcher-1326-thread-2)
[n:127.0.0.1:54747_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:54747_solr
[junit4] 2> 1849271 INFO (zkCallback-1353-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1849275 INFO (zkCallback-1344-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1849278 DEBUG
(OverseerAutoScalingTriggerThread-73706739427704837-127.0.0.1:54747_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 1849278 INFO (zkCallback-1342-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1849292 DEBUG
(OverseerAutoScalingTriggerThread-73706739427704837-127.0.0.1:54747_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 1
[junit4] 2> 1849292 DEBUG
(OverseerAutoScalingTriggerThread-73706739427704837-127.0.0.1:54747_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1,
lastZnodeVersion -1
[junit4] 2> 1849292 DEBUG
(OverseerAutoScalingTriggerThread-73706739427704837-127.0.0.1:54747_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 1
[junit4] 2> 1849305 DEBUG
(OverseerAutoScalingTriggerThread-73706739427704837-127.0.0.1:54747_solr-n_0000000000)
[ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial
livenodes: [127.0.0.1:54747_solr, 127.0.0.1:52299_solr]
[junit4] 2> 1849316 DEBUG (ScheduledTrigger-2007-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 1849336 DEBUG
(OverseerAutoScalingTriggerThread-73706739427704837-127.0.0.1:54747_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 1849337 DEBUG
(OverseerAutoScalingTriggerThread-73706739427704837-127.0.0.1:54747_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1,
lastZnodeVersion 1
[junit4] 2> 1849616 INFO (jetty-launcher-1326-thread-1)
[n:127.0.0.1:52299_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_52299.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6d5d237d
[junit4] 2> 1849623 INFO (jetty-launcher-1326-thread-1)
[n:127.0.0.1:52299_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_52299.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6d5d237d
[junit4] 2> 1849623 INFO (jetty-launcher-1326-thread-1)
[n:127.0.0.1:52299_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_52299.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6d5d237d
[junit4] 2> 1849624 INFO (jetty-launcher-1326-thread-1)
[n:127.0.0.1:52299_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.IndexSizeTriggerTest_8A414661D5BAFF70-001/tempDir-001/node1/.
[junit4] 2> 1849667 INFO (zkConnectionManagerCallback-1359-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1849668 INFO (jetty-launcher-1326-thread-1)
[n:127.0.0.1:52299_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 1849669 INFO (jetty-launcher-1326-thread-1)
[n:127.0.0.1:52299_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:38850/solr ready
[junit4] 2> 1849687 INFO (jetty-launcher-1326-thread-2)
[n:127.0.0.1:54747_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_54747.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6d5d237d
[junit4] 2> 1849693 INFO (jetty-launcher-1326-thread-2)
[n:127.0.0.1:54747_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_54747.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6d5d237d
[junit4] 2> 1849693 INFO (jetty-launcher-1326-thread-2)
[n:127.0.0.1:54747_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_54747.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6d5d237d
[junit4] 2> 1849694 INFO (jetty-launcher-1326-thread-2)
[n:127.0.0.1:54747_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.IndexSizeTriggerTest_8A414661D5BAFF70-001/tempDir-001/node2/.
[junit4] 2> 1849711 INFO (zkConnectionManagerCallback-1361-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1849713 INFO (zkConnectionManagerCallback-1366-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1849714 INFO
(SUITE-IndexSizeTriggerTest-seed#[8A414661D5BAFF70]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1849715 INFO
(SUITE-IndexSizeTriggerTest-seed#[8A414661D5BAFF70]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38850/solr ready
[junit4] 2> 1849736 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 1849736 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 0
[junit4] 2> 1849737 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion -1
[junit4] 2> 1849737 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 0
[junit4] 2> 1849740 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial
livenodes: [127.0.0.1:10001_solr, 127.0.0.1:10000_solr]
[junit4] 2> 1849740 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 1849740 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion 0
[junit4] 2> 1849740 DEBUG (ScheduledTrigger-2016-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 1849760 DEBUG (ScheduledTrigger-2016-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 1849781 DEBUG (ScheduledTrigger-2016-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 1849801 DEBUG (ScheduledTrigger-2016-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 1849822 DEBUG (ScheduledTrigger-2016-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 1849842 DEBUG (ScheduledTrigger-2016-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 1849862 DEBUG (ScheduledTrigger-2016-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 1849882 DEBUG (ScheduledTrigger-2016-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 1849902 DEBUG (ScheduledTrigger-2016-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 1849922 DEBUG (ScheduledTrigger-2016-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 1849942 DEBUG (ScheduledTrigger-2016-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 1849968 DEBUG (ScheduledTrigger-2016-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 1849985 INFO
(TEST-IndexSizeTriggerTest.testSplitIntegration-seed#[8A414661D5BAFF70]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testSplitIntegration
[junit4] 2> 1849988 DEBUG (ScheduledTrigger-2016-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 1850010 DEBUG (ScheduledTrigger-2016-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 1850030 DEBUG (ScheduledTrigger-2016-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 1850043 DEBUG (simCloudManagerPool-2015-thread-6) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for
testSplitIntegration_collection / shard2: {"core_node3":{
[junit4] 2> "core":"testSplitIntegration_collection_shard2_replica_n3",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2> "node_name":"127.0.0.1:10000_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "SEARCHER.searcher.numDocs":0,
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testSplitIntegration_collection"}}
[junit4] 2> 1850045 DEBUG (simCloudManagerPool-2015-thread-7) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for
testSplitIntegration_collection / shard1: {"core_node1":{
[junit4] 2> "core":"testSplitIntegration_collection_shard1_replica_n1",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2> "node_name":"127.0.0.1:10000_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "SEARCHER.searcher.numDocs":0,
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testSplitIntegration_collection"}}
[junit4] 2> 1850050 DEBUG (ScheduledTrigger-2016-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 1850070 DEBUG (ScheduledTrigger-2016-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 1850090 DEBUG (ScheduledTrigger-2016-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 1850092 DEBUG (simCloudManagerPool-2015-thread-8) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1850093 DEBUG (simCloudManagerPool-2015-thread-8) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
1
[junit4] 2> 1850094 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 1850094 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 1850094 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 1850095 DEBUG (simCloudManagerPool-2015-thread-9) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1850095 DEBUG (simCloudManagerPool-2015-thread-9) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
2
[junit4] 2> 1850095 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 1850096 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 1850096 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 1850096 DEBUG (simCloudManagerPool-2015-thread-10) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1850096 DEBUG (simCloudManagerPool-2015-thread-10) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
3
[junit4] 2> 1850096 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 1850097 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 1850097 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 1850110 DEBUG (ScheduledTrigger-2016-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 1850131 DEBUG (ScheduledTrigger-2016-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 1850151 DEBUG (ScheduledTrigger-2016-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 1850171 DEBUG (ScheduledTrigger-2016-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 1850191 DEBUG (ScheduledTrigger-2016-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 1850211 DEBUG (ScheduledTrigger-2016-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 1850217 DEBUG (ScheduledTrigger-2016-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"5966820bd8e2acT6ptaedfv80tb143sd83qockwg",
[junit4] 2> "source":"index_size_trigger",
[junit4] 2> "eventTime":25163981657793196,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2>
"aboveSize":{"testSplitIntegration_collection":["{\"core_node1\":{\n
\"core\":\"testSplitIntegration_collection_shard1_replica_n1\",\n
\"leader\":\"true\",\n \"SEARCHER.searcher.maxDoc\":14,\n
\"SEARCHER.searcher.deletedDocs\":0,\n \"INDEX.sizeInBytes\":1,\n
\"__docs__\":14,\n \"violationType\":\"aboveDocs\",\n
\"node_name\":\"127.0.0.1:10000_solr\",\n \"state\":\"active\",\n
\"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":14,\n
\"__bytes__\":1,\n \"shard\":\"shard1\",\n
\"collection\":\"testSplitIntegration_collection\"}}"]},
[junit4] 2> "belowSize":{},
[junit4] 2> "requestedOps":["Op{action=SPLITSHARD,
hints={COLL_SHARD=[{\n \"first\":\"testSplitIntegration_collection\",\n
\"second\":\"shard1\"}]}}"]}}
[junit4] 2> 1850228 DEBUG (ScheduledTrigger-2016-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers Pausing all triggers: [index_size_trigger,
.auto_add_replicas, .scheduled_maintenance]
[junit4] 2> 1850230 INFO (ScheduledTrigger-2016-thread-4) [ ]
o.a.s.c.a.IndexSizeTriggerTest =======>
CapturedEvent{timestamp=25163987877064196, stage=STARTED, actionName='null',
event={
[junit4] 2> "id":"5966820bd8e2acT6ptaedfv80tb143sd83qockwg",
[junit4] 2> "source":"index_size_trigger",
[junit4] 2> "eventTime":25163981657793196,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2>
"aboveSize":{"testSplitIntegration_collection":["{\"core_node1\":{\n
\"core\":\"testSplitIntegration_collection_shard1_replica_n1\",\n
\"leader\":\"true\",\n \"SEARCHER.searcher.maxDoc\":14,\n
\"SEARCHER.searcher.deletedDocs\":0,\n \"INDEX.sizeInBytes\":1,\n
\"__docs__\":14,\n \"violationType\":\"aboveDocs\",\n
\"node_name\":\"127.0.0.1:10000_solr\",\n \"state\":\"active\",\n
\"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":14,\n
\"__bytes__\":1,\n \"shard\":\"shard1\",\n
\"collection\":\"testSplitIntegration_collection\"}}"]},
[junit4] 2> "belowSize":{},
[junit4] 2> "_enqueue_time_":25163987831282946,
[junit4] 2> "requestedOps":["Op{action=SPLITSHARD,
hints={COLL_SHARD=[{\n \"first\":\"testSplitIntegration_collection\",\n
\"second\":\"shard1\"}]}}"]}}, context={}, config={
[junit4] 2> "trigger":"index_size_trigger",
[junit4] 2> "stage":[
[junit4] 2> "STARTED",
[junit4] 2> "ABORTED",
[junit4] 2> "SUCCEEDED",
[junit4] 2> "FAILED"],
[junit4] 2> "afterAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2>
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener",
[junit4] 2> "beforeAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"]}, message='null'}
[junit4] 2> 1850236 DEBUG (AutoscalingActionExecutor-2017-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"5966820bd8e2acT6ptaedfv80tb143sd83qockwg",
[junit4] 2> "source":"index_size_trigger",
[junit4] 2> "eventTime":25163981657793196,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2>
"aboveSize":{"testSplitIntegration_collection":["{\"core_node1\":{\n
\"core\":\"testSplitIntegration_collection_shard1_replica_n1\",\n
\"leader\":\"true\",\n \"SEARCHER.searcher.maxDoc\":14,\n
\"SEARCHER.searcher.deletedDocs\":0,\n \"INDEX.sizeInBytes\":1,\n
\"__docs__\":14,\n \"violationType\":\"aboveDocs\",\n
\"node_name\":\"127.0.0.1:10000_solr\",\n \"state\":\"active\",\n
\"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":14,\n
\"__bytes__\":1,\n \"shard\":\"shard1\",\n
\"collection\":\"testSplitIntegration_collection\"}}"]},
[junit4] 2> "belowSize":{},
[junit4] 2> "_enqueue_time_":25163987831282946,
[junit4] 2> "requestedOps":["Op{action=SPLITSHARD,
hints={COLL_SHARD=[{\n \"first\":\"testSplitIntegration_collection\",\n
\"second\":\"shard1\"}]}}"]}}
[junit4] 2> 1850238 INFO (AutoscalingActionExecutor-2017-thread-1) [ ]
o.a.s.c.a.IndexSizeTriggerTest =======>
CapturedEvent{timestamp=25163988317670046, stage=BEFORE_ACTION,
actionName='compute_plan', event={
[junit4] 2> "id":"5966820bd8e2acT6ptaedfv80tb143sd83qockwg",
[junit4] 2> "source":"index_size_trigger",
[junit4] 2> "eventTime":25163981657793196,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2>
"aboveSize":{"testSplitIntegration_collection":["{\"core_node1\":{\n
\"core\":\"testSplitIntegration_collection_shard1_replica_n1\",\n
\"leader\":\"true\",\n \"SEARCHER.searcher.maxDoc\":14,\n
\"SEARCHER.searcher.deletedDocs\":0,\n \"INDEX.sizeInBytes\":1,\n
\"__docs__\":14,\n \"violationType\":\"aboveDocs\",\n
\"node_name\":\"127.0.0.1:10000_solr\",\n \"state\":\"active\",\n
\"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":14,\n
\"__bytes__\":1,\n \"shard\":\"shard1\",\n
\"collection\":\"testSplitIntegration_collection\"}}"]},
[junit4] 2> "belowSize":{},
[junit4] 2> "_enqueue_time_":25163987831282946,
[junit4] 2> "requestedOps":["Op{action=SPLITSHARD,
hints={COLL_SHARD=[{\n \"first\":\"testSplitIntegration_collection\",\n
\"second\":\"shard1\"}]}}"]}},
context={properties.BEFORE_ACTION=[compute_plan], source=index_size_trigger},
config={
[junit4] 2> "trigger":"index_size_trigger",
[junit4] 2> "stage":[
[junit4] 2> "STARTED",
[junit4] 2> "ABORTED",
[junit4] 2> "SUCCEEDED",
[junit4] 2> "FAILED"],
[junit4] 2> "afterAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2>
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener",
[junit4] 2> "beforeAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"]}, message='null'}
[junit4] 2> 1850239 DEBUG (AutoscalingActionExecutor-2017-thread-1) [ ]
o.a.s.c.a.ComputePlanAction -- processing event: {
[junit4] 2> "id":"5966820bd8e2acT6ptaedfv80tb143sd83qockwg",
[junit4] 2> "source":"index_size_trigger",
[junit4] 2> "eventTime":25163981657793196,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2>
"aboveSize":{"testSplitIntegration_collection":["{\"core_node1\":{\n
\"core\":\"testSplitIntegration_collection_shard1_replica_n1\",\n
\"leader\":\"true\",\n \"SEARCHER.searcher.maxDoc\":14,\n
\"SEARCHER.searcher.deletedDocs\":0,\n \"INDEX.sizeInBytes\":1,\n
\"__docs__\":14,\n \"violationType\":\"aboveDocs\",\n
\"node_name\":\"127.0.0.1:10000_solr\",\n \"state\":\"active\",\n
\"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":14,\n
\"__bytes__\":1,\n \"shard\":\"shard1\",\n
\"collection\":\"testSplitIntegration_collection\"}}"]},
[junit4] 2> "belowSize":{},
[junit4] 2> "_enqueue_time_":25163987831282946,
[junit4] 2> "requestedOps":["Op{action=SPLITSHARD,
hints={COLL_SHARD=[{\n \"first\":\"testSplitIntegration_collection\",\n
\"second\":\"shard1\"}]}}"]}} with context properties:
{BEFORE_ACTION=[compute_plan]}
[junit4] 2> 1850245 INFO (AutoscalingActionExecutor-2017-thread-1) [ ]
o.a.s.c.a.ComputePlanAction Computed Plan:
action=SPLITSHARD&collection=testSplitIntegration_collection&shard=shard1
[junit4] 2> 1850246 INFO (AutoscalingActionExecutor-2017-thread-1) [ ]
o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 25163988698
sessionWrapper.createTime 25163988501881046, this.sessionWrapper.createTime
25163988501881046
[junit4] 2> 1850246 INFO (AutoscalingActionExecutor-2017-thread-1) [ ]
o.a.s.c.a.IndexSizeTriggerTest =======>
CapturedEvent{timestamp=25163988714184446, stage=AFTER_ACTION,
actionName='compute_plan', event={
[junit4] 2> "id":"5966820bd8e2acT6ptaedfv80tb143sd83qockwg",
[junit4] 2> "source":"index_size_trigger",
[junit4] 2> "eventTime":25163981657793196,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "__start__":1,
[junit4] 2>
"aboveSize":{"testSplitIntegration_collection":["{\"core_node1\":{\n
\"core\":\"testSplitIntegration_collection_shard1_replica_n1\",\n
\"leader\":\"true\",\n \"SEARCHER.searcher.maxDoc\":14,\n
\"SEARCHER.searcher.deletedDocs\":0,\n \"INDEX.sizeInBytes\":1,\n
\"__docs__\":14,\n \"violationType\":\"aboveDocs\",\n
\"node_name\":\"127.0.0.1:10000_solr\",\n \"state\":\"active\",\n
\"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":14,\n
\"__bytes__\":1,\n \"shard\":\"shard1\",\n
\"collection\":\"testSplitIntegration_collection\"}}"]},
[junit4] 2> "belowSize":{},
[junit4] 2> "_enqueue_time_":25163987831282946,
[junit4] 2> "requestedOps":["Op{action=SPLITSHARD,
hints={COLL_SHARD=[{\n \"first\":\"testSplitIntegration_collection\",\n
\"second\":\"shard1\"}]}}"]}},
context={properties.operations=[{class=org.apache.solr.client.solrj.request.CollectionAdminRequest$SplitShard,
method=GET, params.action=SPLITSHARD,
params.collection=testSplitIntegration_collection, params.shard=shard1}],
properties.BEFORE_ACTION=[compute_plan], source=index_size_trigger,
properties.AFTER_ACTION=[compute_plan]}, config={
[junit4] 2> "trigger":"index_size_trigger",
[junit4] 2> "stage":[
[junit4] 2> "STARTED",
[junit4] 2> "ABORTED",
[junit4] 2> "SUCCEEDED",
[junit4] 2> "FAILED"],
[junit4] 2> "afterAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2>
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener",
[junit4] 2> "beforeAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"]}, message='null'}
[junit4] 2> 1850247 INFO (AutoscalingActionExecutor-2017-thread-1) [ ]
o.a.s.c.a.IndexSizeTriggerTest =======>
CapturedEvent{timestamp=25163988735067946, stage=BEFORE_ACTION,
actionName='execute_plan', event={
[junit4] 2> "id":"5966820bd8e2acT6ptaedfv80tb143sd83qockwg",
[junit4] 2> "source":"index_size_trigger",
[junit4] 2> "eventTime":25163981657793196,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "__start__":1,
[junit4] 2>
"aboveSize":{"testSplitIntegration_collection":["{\"core_node1\":{\n
\"core\":\"testSplitIntegration_collection_shard1_replica_n1\",\n
\"leader\":\"true\",\n \"SEARCHER.searcher.maxDoc\":14,\n
\"SEARCHER.searcher.deletedDocs\":0,\n \"INDEX.sizeInBytes\":1,\n
\"__docs__\":14,\n \"violationType\":\"aboveDocs\",\n
\"node_name\":\"127.0.0.1:10000_solr\",\n \"state\":\"active\",\n
\"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":14,\n
\"__bytes__\":1,\n \"shard\":\"shard1\",\n
\"collection\":\"testSplitIntegration_collection\"}}"]},
[junit4] 2> "belowSize":{},
[junit4] 2> "_enqueue_time_":25163987831282946,
[junit4] 2> "requestedOps":["Op{action=SPLITSHARD,
hints={COLL_SHARD=[{\n \"first\":\"testSplitIntegration_collection\",\n
\"second\":\"shard1\"}]}}"]}},
context={properties.operations=[{class=org.apache.solr.client.solrj.request.CollectionAdminRequest$SplitShard,
method=GET, params.action=SPLITSHARD,
params.collection=testSplitIntegration_collection, params.shard=shard1}],
properties.BEFORE_ACTION=[compute_plan, execute_plan],
source=index_size_trigger, properties.AFTER_ACTION=[compute_plan]}, config={
[junit4] 2> "trigger":"index_size_trigger",
[junit4] 2> "stage":[
[junit4] 2> "STARTED",
[junit4] 2> "ABORTED",
[junit4] 2> "SUCCEEDED",
[junit4] 2> "FAILED"],
[junit4] 2> "afterAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2>
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener",
[junit4] 2> "beforeAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"]}, message='null'}
[junit4] 2> 1850247 DEBUG (AutoscalingActionExecutor-2017-thread-1) [ ]
o.a.s.c.a.ExecutePlanAction -- processing event: {
[junit4] 2> "id":"5966820bd8e2acT6ptaedfv80tb143sd83qockwg",
[junit4] 2> "source":"index_size_trigger",
[junit4] 2> "eventTime":25163981657793196,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "__start__":1,
[junit4] 2>
"aboveSize":{"testSplitIntegration_collection":["{\"core_node1\":{\n
\"core\":\"testSplitIntegration_collection_shard1_replica_n1\",\n
\"leader\":\"true\",\n \"SEARCHER.searcher.maxDoc\":14,\n
\"SEARCHER.searcher.deletedDocs\":0,\n \"INDEX.sizeInBytes\":1,\n
\"__docs__\":14,\n \"violationType\":\"aboveDocs\",\n
\"node_name\":\"127.0.0.1:10000_solr\",\n \"state\":\"active\",\n
\"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":14,\n
\"__bytes__\":1,\n \"shard\":\"shard1\",\n
\"collection\":\"testSplitIntegration_collection\"}}"]},
[junit4] 2> "belowSize":{},
[junit4] 2> "_enqueue_time_":25163987831282946,
[junit4] 2> "requestedOps":["Op{action=SPLITSHARD,
hints={COLL_SHARD=[{\n \"first\":\"testSplitIntegration_collection\",\n
\"second\":\"shard1\"}]}}"]}} with context properties:
{operations=[org.apache.solr.client.solrj.request.CollectionAdminRequest$SplitShard@6630f161],
AFTER_ACTION=[compute_plan], BEFORE_ACTION=[compute_plan, execute_plan]}
[junit4] 2> 1850247 DEBUG (AutoscalingActionExecutor-2017-thread-1) [ ]
o.a.s.c.a.ExecutePlanAction Executing operation:
action=SPLITSHARD&collection=testSplitIntegration_collection&shard=shard1
[junit4] 2> 1850317 DEBUG (ScheduledTrigger-2007-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 1850393 DEBUG (simCloudManagerPool-2015-thread-42) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for
testSplitIntegration_collection / shard1_0: {"core_node7":{
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":7,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2> "node_name":"127.0.0.1:10001_solr",
[junit4] 2> "base_url":"http://127.0.0.1:10001/solr";,
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "SEARCHER.searcher.numDocs":7,
[junit4] 2> "core":"testSplitIntegration_collection_shard1_0_replica0",
[junit4] 2> "shard":"shard1_0",
[junit4] 2> "collection":"testSplitIntegration_collection"}}
[junit4] 2> 1850393 DEBUG (simCloudManagerPool-2015-thread-41) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for
testSplitIntegration_collection / shard1_1: {"core_node10":{
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":7,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2> "node_name":"127.0.0.1:10000_solr",
[junit4] 2> "base_url"
[...truncated too long message...]
Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
jar-checksums:
[mkdir] Created dir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/null889633863
[copy] Copying 40 files to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/null889633863
[delete] Deleting directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/null889633863
resolve-example:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
resolve-server:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
jar-checksums:
[mkdir] Created dir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/null1665443917
[copy] Copying 246 files to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/null1665443917
[delete] Deleting directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/null1665443917
check-working-copy:
[ivy:cachepath] :: resolving dependencies ::
org.eclipse.jgit#org.eclipse.jgit-caller;working
[ivy:cachepath] confs: [default]
[ivy:cachepath] found
org.eclipse.jgit#org.eclipse.jgit;4.6.0.201612231935-r in public
[ivy:cachepath] found com.jcraft#jsch;0.1.53 in public
[ivy:cachepath] found com.googlecode.javaewah#JavaEWAH;1.1.6 in public
[ivy:cachepath] found org.apache.httpcomponents#httpclient;4.3.6 in
public
[ivy:cachepath] found org.apache.httpcomponents#httpcore;4.3.3 in public
[ivy:cachepath] found commons-logging#commons-logging;1.1.3 in public
[ivy:cachepath] found commons-codec#commons-codec;1.6 in public
[ivy:cachepath] found org.slf4j#slf4j-api;1.7.2 in public
[ivy:cachepath] :: resolution report :: resolve 58ms :: artifacts dl 9ms
---------------------------------------------------------------------
| | modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| default | 8 | 0 | 0 | 0 || 8 | 0 |
---------------------------------------------------------------------
[wc-checker] Initializing working copy...
[wc-checker] SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
[wc-checker] SLF4J: Defaulting to no-operation (NOP) logger implementation
[wc-checker] SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for
further details.
[wc-checker] Checking working copy status...
-jenkins-base:
BUILD SUCCESSFUL
Total time: 351 minutes 13 seconds
Archiving artifacts
WARN: No artifacts found that match the file pattern
"**/*.events,heapdumps/**,**/hs_err_pid*". Configuration error?
WARN: java.lang.InterruptedException: no matches found within 10000
Recording test results
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)
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
