Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/7553/
Java: 32bit/jdk1.8.0_172 -client -XX:+UseParallelGC
5 tests failed.
FAILED:
org.apache.solr.cloud.autoscaling.sim.TestSimGenericDistributedQueue.testDistributedQueue
{#2}
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([CB5AFEB397838297]:0)
FAILED:
junit.framework.TestSuite.org.apache.solr.cloud.autoscaling.sim.TestSimGenericDistributedQueue
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([CB5AFEB397838297]:0)
FAILED:
org.apache.solr.cloud.autoscaling.sim.TestSimGenericDistributedQueue.testDistributedQueue
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([CB5AFEB397838297]:0)
FAILED:
junit.framework.TestSuite.org.apache.solr.cloud.autoscaling.sim.TestSimGenericDistributedQueue
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([CB5AFEB397838297]:0)
FAILED:
org.apache.solr.cloud.autoscaling.sim.TestSimPolicyCloud.testCreateCollectionSplitShard
Error Message:
java.util.concurrent.ExecutionException: org.apache.solr.common.SolrException:
java.util.concurrent.TimeoutException: last ClusterState: znodeVersion: 28 live
nodes:[127.0.0.1:10001_solr, 127.0.0.1:10004_solr, 127.0.0.1:10000_solr,
127.0.0.1:10002_solr, 127.0.0.1:10003_solr]
collections:{testCreateCollectionSplitShard=DocCollection(testCreateCollectionSplitShard//clusterstate.json/27)={
"replicationFactor":"1", "pullReplicas":"0",
"router":{"name":"compositeId"}, "maxShardsPerNode":"1",
"autoAddReplicas":"false", "nrtReplicas":"2", "tlogReplicas":"0",
"autoCreated":"true", "policy":"c1", "shards":{ "shard1":{
"replicas":{ "core_node1":{
"core":"testCreateCollectionSplitShard_shard1_replica_n1",
"leader":"true", "SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10000_solr", "state":"active",
"type":"NRT", "INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0}, "core_node2":{
"core":"testCreateCollectionSplitShard_shard1_replica_n2",
"SEARCHER.searcher.maxDoc":0, "SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240, "node_name":"127.0.0.1:10001_solr",
"state":"active", "type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6, "SEARCHER.searcher.numDocs":0}},
"range":"80000000-7fffffff", "state":"active",
"__buffered_updates__":0}, "shard1_1":{ "parent":"shard1",
"stateTimestamp":"1538775488310740000", "range":"0-7fffffff",
"state":"construction", "replicas":{ "core_node10":{
"leader":"true",
"core":"testCreateCollectionSplitShard_shard1_1_replica_n9",
"SEARCHER.searcher.maxDoc":0, "SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240, "node_name":"127.0.0.1:10001_solr",
"base_url":"http://127.0.0.1:10001/solr";, "state":"active",
"type":"NRT", "INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0}, "core_node12":{
"core":"testCreateCollectionSplitShard_shard1_1_replica_n11",
"SEARCHER.searcher.maxDoc":0, "SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240, "node_name":"127.0.0.1:10000_solr",
"base_url":"http://127.0.0.1:10000/solr";, "state":"active",
"type":"NRT", "INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0}}}, "shard1_0":{ "parent":"shard1",
"stateTimestamp":"1538775488310175000", "range":"80000000-ffffffff",
"state":"construction", "replicas":{ "core_node6":{
"core":"testCreateCollectionSplitShard_shard1_0_replica_n5",
"SEARCHER.searcher.maxDoc":0, "SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":20480, "node_name":"127.0.0.1:10000_solr",
"base_url":"http://127.0.0.1:10000/solr";, "state":"active",
"type":"NRT", "INDEX.sizeInGB":1.9073486328125E-5,
"SEARCHER.searcher.numDocs":0}, "core_node8":{
"core":"testCreateCollectionSplitShard_shard1_0_replica_n7",
"SEARCHER.searcher.maxDoc":0, "SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":20480, "node_name":"127.0.0.1:10001_solr",
"base_url":"http://127.0.0.1:10001/solr";, "state":"active",
"type":"NRT", "INDEX.sizeInGB":1.9073486328125E-5,
"SEARCHER.searcher.numDocs":0}}}}}}, last coll state:
DocCollection(testCreateCollectionSplitShard//clusterstate.json/27)={
"replicationFactor":"1", "pullReplicas":"0",
"router":{"name":"compositeId"}, "maxShardsPerNode":"1",
"autoAddReplicas":"false", "nrtReplicas":"2", "tlogReplicas":"0",
"autoCreated":"true", "policy":"c1", "shards":{ "shard1":{
"replicas":{ "core_node1":{
"core":"testCreateCollectionSplitShard_shard1_replica_n1",
"leader":"true", "SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10000_solr", "state":"active",
"type":"NRT", "INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0}, "core_node2":{
"core":"testCreateCollectionSplitShard_shard1_replica_n2",
"SEARCHER.searcher.maxDoc":0, "SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240, "node_name":"127.0.0.1:10001_solr",
"state":"active", "type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6, "SEARCHER.searcher.numDocs":0}},
"range":"80000000-7fffffff", "state":"active",
"__buffered_updates__":0}, "shard1_1":{ "parent":"shard1",
"stateTimestamp":"1538775488310740000", "range":"0-7fffffff",
"state":"construction", "replicas":{ "core_node10":{
"leader":"true",
"core":"testCreateCollectionSplitShard_shard1_1_replica_n9",
"SEARCHER.searcher.maxDoc":0, "SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240, "node_name":"127.0.0.1:10001_solr",
"base_url":"http://127.0.0.1:10001/solr";, "state":"active",
"type":"NRT", "INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0}, "core_node12":{
"core":"testCreateCollectionSplitShard_shard1_1_replica_n11",
"SEARCHER.searcher.maxDoc":0, "SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240, "node_name":"127.0.0.1:10000_solr",
"base_url":"http://127.0.0.1:10000/solr";, "state":"active",
"type":"NRT", "INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0}}}, "shard1_0":{ "parent":"shard1",
"stateTimestamp":"1538775488310175000", "range":"80000000-ffffffff",
"state":"construction", "replicas":{ "core_node6":{
"core":"testCreateCollectionSplitShard_shard1_0_replica_n5",
"SEARCHER.searcher.maxDoc":0, "SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":20480, "node_name":"127.0.0.1:10000_solr",
"base_url":"http://127.0.0.1:10000/solr";, "state":"active",
"type":"NRT", "INDEX.sizeInGB":1.9073486328125E-5,
"SEARCHER.searcher.numDocs":0}, "core_node8":{
"core":"testCreateCollectionSplitShard_shard1_0_replica_n7",
"SEARCHER.searcher.maxDoc":0, "SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":20480, "node_name":"127.0.0.1:10001_solr",
"base_url":"http://127.0.0.1:10001/solr";, "state":"active",
"type":"NRT", "INDEX.sizeInGB":1.9073486328125E-5,
"SEARCHER.searcher.numDocs":0}}}}}
Stack Trace:
java.io.IOException: java.util.concurrent.ExecutionException:
org.apache.solr.common.SolrException: java.util.concurrent.TimeoutException:
last ClusterState: znodeVersion: 28
live nodes:[127.0.0.1:10001_solr, 127.0.0.1:10004_solr, 127.0.0.1:10000_solr,
127.0.0.1:10002_solr, 127.0.0.1:10003_solr]
collections:{testCreateCollectionSplitShard=DocCollection(testCreateCollectionSplitShard//clusterstate.json/27)={
"replicationFactor":"1",
"pullReplicas":"0",
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"2",
"tlogReplicas":"0",
"autoCreated":"true",
"policy":"c1",
"shards":{
"shard1":{
"replicas":{
"core_node1":{
"core":"testCreateCollectionSplitShard_shard1_replica_n1",
"leader":"true",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10000_solr",
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0},
"core_node2":{
"core":"testCreateCollectionSplitShard_shard1_replica_n2",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10001_solr",
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0}},
"range":"80000000-7fffffff",
"state":"active",
"__buffered_updates__":0},
"shard1_1":{
"parent":"shard1",
"stateTimestamp":"1538775488310740000",
"range":"0-7fffffff",
"state":"construction",
"replicas":{
"core_node10":{
"leader":"true",
"core":"testCreateCollectionSplitShard_shard1_1_replica_n9",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10001_solr",
"base_url":"http://127.0.0.1:10001/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0},
"core_node12":{
"core":"testCreateCollectionSplitShard_shard1_1_replica_n11",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10000_solr",
"base_url":"http://127.0.0.1:10000/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0}}},
"shard1_0":{
"parent":"shard1",
"stateTimestamp":"1538775488310175000",
"range":"80000000-ffffffff",
"state":"construction",
"replicas":{
"core_node6":{
"core":"testCreateCollectionSplitShard_shard1_0_replica_n5",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":20480,
"node_name":"127.0.0.1:10000_solr",
"base_url":"http://127.0.0.1:10000/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":1.9073486328125E-5,
"SEARCHER.searcher.numDocs":0},
"core_node8":{
"core":"testCreateCollectionSplitShard_shard1_0_replica_n7",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":20480,
"node_name":"127.0.0.1:10001_solr",
"base_url":"http://127.0.0.1:10001/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":1.9073486328125E-5,
"SEARCHER.searcher.numDocs":0}}}}}}, last coll state:
DocCollection(testCreateCollectionSplitShard//clusterstate.json/27)={
"replicationFactor":"1",
"pullReplicas":"0",
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"2",
"tlogReplicas":"0",
"autoCreated":"true",
"policy":"c1",
"shards":{
"shard1":{
"replicas":{
"core_node1":{
"core":"testCreateCollectionSplitShard_shard1_replica_n1",
"leader":"true",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10000_solr",
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0},
"core_node2":{
"core":"testCreateCollectionSplitShard_shard1_replica_n2",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10001_solr",
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0}},
"range":"80000000-7fffffff",
"state":"active",
"__buffered_updates__":0},
"shard1_1":{
"parent":"shard1",
"stateTimestamp":"1538775488310740000",
"range":"0-7fffffff",
"state":"construction",
"replicas":{
"core_node10":{
"leader":"true",
"core":"testCreateCollectionSplitShard_shard1_1_replica_n9",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10001_solr",
"base_url":"http://127.0.0.1:10001/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0},
"core_node12":{
"core":"testCreateCollectionSplitShard_shard1_1_replica_n11",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10000_solr",
"base_url":"http://127.0.0.1:10000/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0}}},
"shard1_0":{
"parent":"shard1",
"stateTimestamp":"1538775488310175000",
"range":"80000000-ffffffff",
"state":"construction",
"replicas":{
"core_node6":{
"core":"testCreateCollectionSplitShard_shard1_0_replica_n5",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":20480,
"node_name":"127.0.0.1:10000_solr",
"base_url":"http://127.0.0.1:10000/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":1.9073486328125E-5,
"SEARCHER.searcher.numDocs":0},
"core_node8":{
"core":"testCreateCollectionSplitShard_shard1_0_replica_n7",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":20480,
"node_name":"127.0.0.1:10001_solr",
"base_url":"http://127.0.0.1:10001/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":1.9073486328125E-5,
"SEARCHER.searcher.numDocs":0}}}}}
at
__randomizedtesting.SeedInfo.seed([CB5AFEB397838297:1ED48D4827C90329]:0)
at
org.apache.solr.cloud.autoscaling.sim.SimCloudManager.request(SimCloudManager.java:654)
at
org.apache.solr.cloud.autoscaling.sim.SimCloudManager$1.request(SimCloudManager.java:224)
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.autoscaling.sim.TestSimPolicyCloud.testCreateCollectionSplitShard(TestSimPolicyCloud.java:168)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1742)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985)
at
org.junit.rules.ExpectedException$ExpectedExceptionStatement.evaluate(ExpectedException.java:110)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:944)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Caused by: java.util.concurrent.ExecutionException:
org.apache.solr.common.SolrException: java.util.concurrent.TimeoutException:
last ClusterState: znodeVersion: 28
live nodes:[127.0.0.1:10001_solr, 127.0.0.1:10004_solr, 127.0.0.1:10000_solr,
127.0.0.1:10002_solr, 127.0.0.1:10003_solr]
collections:{testCreateCollectionSplitShard=DocCollection(testCreateCollectionSplitShard//clusterstate.json/27)={
"replicationFactor":"1",
"pullReplicas":"0",
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"2",
"tlogReplicas":"0",
"autoCreated":"true",
"policy":"c1",
"shards":{
"shard1":{
"replicas":{
"core_node1":{
"core":"testCreateCollectionSplitShard_shard1_replica_n1",
"leader":"true",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10000_solr",
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0},
"core_node2":{
"core":"testCreateCollectionSplitShard_shard1_replica_n2",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10001_solr",
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0}},
"range":"80000000-7fffffff",
"state":"active",
"__buffered_updates__":0},
"shard1_1":{
"parent":"shard1",
"stateTimestamp":"1538775488310740000",
"range":"0-7fffffff",
"state":"construction",
"replicas":{
"core_node10":{
"leader":"true",
"core":"testCreateCollectionSplitShard_shard1_1_replica_n9",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10001_solr",
"base_url":"http://127.0.0.1:10001/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0},
"core_node12":{
"core":"testCreateCollectionSplitShard_shard1_1_replica_n11",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10000_solr",
"base_url":"http://127.0.0.1:10000/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0}}},
"shard1_0":{
"parent":"shard1",
"stateTimestamp":"1538775488310175000",
"range":"80000000-ffffffff",
"state":"construction",
"replicas":{
"core_node6":{
"core":"testCreateCollectionSplitShard_shard1_0_replica_n5",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":20480,
"node_name":"127.0.0.1:10000_solr",
"base_url":"http://127.0.0.1:10000/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":1.9073486328125E-5,
"SEARCHER.searcher.numDocs":0},
"core_node8":{
"core":"testCreateCollectionSplitShard_shard1_0_replica_n7",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":20480,
"node_name":"127.0.0.1:10001_solr",
"base_url":"http://127.0.0.1:10001/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":1.9073486328125E-5,
"SEARCHER.searcher.numDocs":0}}}}}}, last coll state:
DocCollection(testCreateCollectionSplitShard//clusterstate.json/27)={
"replicationFactor":"1",
"pullReplicas":"0",
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"2",
"tlogReplicas":"0",
"autoCreated":"true",
"policy":"c1",
"shards":{
"shard1":{
"replicas":{
"core_node1":{
"core":"testCreateCollectionSplitShard_shard1_replica_n1",
"leader":"true",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10000_solr",
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0},
"core_node2":{
"core":"testCreateCollectionSplitShard_shard1_replica_n2",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10001_solr",
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0}},
"range":"80000000-7fffffff",
"state":"active",
"__buffered_updates__":0},
"shard1_1":{
"parent":"shard1",
"stateTimestamp":"1538775488310740000",
"range":"0-7fffffff",
"state":"construction",
"replicas":{
"core_node10":{
"leader":"true",
"core":"testCreateCollectionSplitShard_shard1_1_replica_n9",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10001_solr",
"base_url":"http://127.0.0.1:10001/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0},
"core_node12":{
"core":"testCreateCollectionSplitShard_shard1_1_replica_n11",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10000_solr",
"base_url":"http://127.0.0.1:10000/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0}}},
"shard1_0":{
"parent":"shard1",
"stateTimestamp":"1538775488310175000",
"range":"80000000-ffffffff",
"state":"construction",
"replicas":{
"core_node6":{
"core":"testCreateCollectionSplitShard_shard1_0_replica_n5",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":20480,
"node_name":"127.0.0.1:10000_solr",
"base_url":"http://127.0.0.1:10000/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":1.9073486328125E-5,
"SEARCHER.searcher.numDocs":0},
"core_node8":{
"core":"testCreateCollectionSplitShard_shard1_0_replica_n7",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":20480,
"node_name":"127.0.0.1:10001_solr",
"base_url":"http://127.0.0.1:10001/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":1.9073486328125E-5,
"SEARCHER.searcher.numDocs":0}}}}}
at java.util.concurrent.FutureTask.report(FutureTask.java:122)
at java.util.concurrent.FutureTask.get(FutureTask.java:192)
at
org.apache.solr.cloud.autoscaling.sim.SimCloudManager.request(SimCloudManager.java:652)
... 44 more
Caused by: org.apache.solr.common.SolrException:
java.util.concurrent.TimeoutException: last ClusterState: znodeVersion: 28
live nodes:[127.0.0.1:10001_solr, 127.0.0.1:10004_solr, 127.0.0.1:10000_solr,
127.0.0.1:10002_solr, 127.0.0.1:10003_solr]
collections:{testCreateCollectionSplitShard=DocCollection(testCreateCollectionSplitShard//clusterstate.json/27)={
"replicationFactor":"1",
"pullReplicas":"0",
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"2",
"tlogReplicas":"0",
"autoCreated":"true",
"policy":"c1",
"shards":{
"shard1":{
"replicas":{
"core_node1":{
"core":"testCreateCollectionSplitShard_shard1_replica_n1",
"leader":"true",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10000_solr",
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0},
"core_node2":{
"core":"testCreateCollectionSplitShard_shard1_replica_n2",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10001_solr",
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0}},
"range":"80000000-7fffffff",
"state":"active",
"__buffered_updates__":0},
"shard1_1":{
"parent":"shard1",
"stateTimestamp":"1538775488310740000",
"range":"0-7fffffff",
"state":"construction",
"replicas":{
"core_node10":{
"leader":"true",
"core":"testCreateCollectionSplitShard_shard1_1_replica_n9",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10001_solr",
"base_url":"http://127.0.0.1:10001/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0},
"core_node12":{
"core":"testCreateCollectionSplitShard_shard1_1_replica_n11",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10000_solr",
"base_url":"http://127.0.0.1:10000/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0}}},
"shard1_0":{
"parent":"shard1",
"stateTimestamp":"1538775488310175000",
"range":"80000000-ffffffff",
"state":"construction",
"replicas":{
"core_node6":{
"core":"testCreateCollectionSplitShard_shard1_0_replica_n5",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":20480,
"node_name":"127.0.0.1:10000_solr",
"base_url":"http://127.0.0.1:10000/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":1.9073486328125E-5,
"SEARCHER.searcher.numDocs":0},
"core_node8":{
"core":"testCreateCollectionSplitShard_shard1_0_replica_n7",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":20480,
"node_name":"127.0.0.1:10001_solr",
"base_url":"http://127.0.0.1:10001/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":1.9073486328125E-5,
"SEARCHER.searcher.numDocs":0}}}}}}, last coll state:
DocCollection(testCreateCollectionSplitShard//clusterstate.json/27)={
"replicationFactor":"1",
"pullReplicas":"0",
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"2",
"tlogReplicas":"0",
"autoCreated":"true",
"policy":"c1",
"shards":{
"shard1":{
"replicas":{
"core_node1":{
"core":"testCreateCollectionSplitShard_shard1_replica_n1",
"leader":"true",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10000_solr",
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0},
"core_node2":{
"core":"testCreateCollectionSplitShard_shard1_replica_n2",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10001_solr",
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0}},
"range":"80000000-7fffffff",
"state":"active",
"__buffered_updates__":0},
"shard1_1":{
"parent":"shard1",
"stateTimestamp":"1538775488310740000",
"range":"0-7fffffff",
"state":"construction",
"replicas":{
"core_node10":{
"leader":"true",
"core":"testCreateCollectionSplitShard_shard1_1_replica_n9",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10001_solr",
"base_url":"http://127.0.0.1:10001/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0},
"core_node12":{
"core":"testCreateCollectionSplitShard_shard1_1_replica_n11",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10000_solr",
"base_url":"http://127.0.0.1:10000/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0}}},
"shard1_0":{
"parent":"shard1",
"stateTimestamp":"1538775488310175000",
"range":"80000000-ffffffff",
"state":"construction",
"replicas":{
"core_node6":{
"core":"testCreateCollectionSplitShard_shard1_0_replica_n5",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":20480,
"node_name":"127.0.0.1:10000_solr",
"base_url":"http://127.0.0.1:10000/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":1.9073486328125E-5,
"SEARCHER.searcher.numDocs":0},
"core_node8":{
"core":"testCreateCollectionSplitShard_shard1_0_replica_n7",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":20480,
"node_name":"127.0.0.1:10001_solr",
"base_url":"http://127.0.0.1:10001/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":1.9073486328125E-5,
"SEARCHER.searcher.numDocs":0}}}}}
at
org.apache.solr.cloud.autoscaling.sim.SimCloudManager.simHandleSolrRequest(SimCloudManager.java:854)
at
org.apache.solr.cloud.autoscaling.sim.SimCloudManager.lambda$request$15(SimCloudManager.java:651)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
... 1 more
Caused by: java.util.concurrent.TimeoutException: last ClusterState:
znodeVersion: 28
live nodes:[127.0.0.1:10001_solr, 127.0.0.1:10004_solr, 127.0.0.1:10000_solr,
127.0.0.1:10002_solr, 127.0.0.1:10003_solr]
collections:{testCreateCollectionSplitShard=DocCollection(testCreateCollectionSplitShard//clusterstate.json/27)={
"replicationFactor":"1",
"pullReplicas":"0",
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"2",
"tlogReplicas":"0",
"autoCreated":"true",
"policy":"c1",
"shards":{
"shard1":{
"replicas":{
"core_node1":{
"core":"testCreateCollectionSplitShard_shard1_replica_n1",
"leader":"true",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10000_solr",
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0},
"core_node2":{
"core":"testCreateCollectionSplitShard_shard1_replica_n2",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10001_solr",
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0}},
"range":"80000000-7fffffff",
"state":"active",
"__buffered_updates__":0},
"shard1_1":{
"parent":"shard1",
"stateTimestamp":"1538775488310740000",
"range":"0-7fffffff",
"state":"construction",
"replicas":{
"core_node10":{
"leader":"true",
"core":"testCreateCollectionSplitShard_shard1_1_replica_n9",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10001_solr",
"base_url":"http://127.0.0.1:10001/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0},
"core_node12":{
"core":"testCreateCollectionSplitShard_shard1_1_replica_n11",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10000_solr",
"base_url":"http://127.0.0.1:10000/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0}}},
"shard1_0":{
"parent":"shard1",
"stateTimestamp":"1538775488310175000",
"range":"80000000-ffffffff",
"state":"construction",
"replicas":{
"core_node6":{
"core":"testCreateCollectionSplitShard_shard1_0_replica_n5",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":20480,
"node_name":"127.0.0.1:10000_solr",
"base_url":"http://127.0.0.1:10000/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":1.9073486328125E-5,
"SEARCHER.searcher.numDocs":0},
"core_node8":{
"core":"testCreateCollectionSplitShard_shard1_0_replica_n7",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":20480,
"node_name":"127.0.0.1:10001_solr",
"base_url":"http://127.0.0.1:10001/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":1.9073486328125E-5,
"SEARCHER.searcher.numDocs":0}}}}}}, last coll state:
DocCollection(testCreateCollectionSplitShard//clusterstate.json/27)={
"replicationFactor":"1",
"pullReplicas":"0",
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"2",
"tlogReplicas":"0",
"autoCreated":"true",
"policy":"c1",
"shards":{
"shard1":{
"replicas":{
"core_node1":{
"core":"testCreateCollectionSplitShard_shard1_replica_n1",
"leader":"true",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10000_solr",
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0},
"core_node2":{
"core":"testCreateCollectionSplitShard_shard1_replica_n2",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10001_solr",
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0}},
"range":"80000000-7fffffff",
"state":"active",
"__buffered_updates__":0},
"shard1_1":{
"parent":"shard1",
"stateTimestamp":"1538775488310740000",
"range":"0-7fffffff",
"state":"construction",
"replicas":{
"core_node10":{
"leader":"true",
"core":"testCreateCollectionSplitShard_shard1_1_replica_n9",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10001_solr",
"base_url":"http://127.0.0.1:10001/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0},
"core_node12":{
"core":"testCreateCollectionSplitShard_shard1_1_replica_n11",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10000_solr",
"base_url":"http://127.0.0.1:10000/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0}}},
"shard1_0":{
"parent":"shard1",
"stateTimestamp":"1538775488310175000",
"range":"80000000-ffffffff",
"state":"construction",
"replicas":{
"core_node6":{
"core":"testCreateCollectionSplitShard_shard1_0_replica_n5",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":20480,
"node_name":"127.0.0.1:10000_solr",
"base_url":"http://127.0.0.1:10000/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":1.9073486328125E-5,
"SEARCHER.searcher.numDocs":0},
"core_node8":{
"core":"testCreateCollectionSplitShard_shard1_0_replica_n7",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":20480,
"node_name":"127.0.0.1:10001_solr",
"base_url":"http://127.0.0.1:10001/solr";,
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":1.9073486328125E-5,
"SEARCHER.searcher.numDocs":0}}}}}
at
org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:112)
at
org.apache.solr.cloud.autoscaling.sim.SimClusterStateProvider.simSplitShard(SimClusterStateProvider.java:1241)
at
org.apache.solr.cloud.autoscaling.sim.SimCloudManager.simHandleSolrRequest(SimCloudManager.java:852)
... 6 more
Build Log:
[...truncated 12475 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.sim.TestSimPolicyCloud
[junit4] 2> 309638 INFO
(SUITE-TestSimPolicyCloud-seed#[CB5AFEB397838297]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.sim.TestSimPolicyCloud_CB5AFEB397838297-001\init-core-data-001
[junit4] 2> 309641 INFO
(SUITE-TestSimPolicyCloud-seed#[CB5AFEB397838297]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 309643 INFO
(SUITE-TestSimPolicyCloud-seed#[CB5AFEB397838297]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 309705 DEBUG
(SUITE-TestSimPolicyCloud-seed#[CB5AFEB397838297]-worker) [ ]
o.a.s.c.a.s.SimClusterStateProvider --- new Overseer leader:
127.0.0.1:10000_solr
[junit4] 2> 309706 DEBUG
(SUITE-TestSimPolicyCloud-seed#[CB5AFEB397838297]-worker) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=0
[junit4] 2> 309707 DEBUG
(SUITE-TestSimPolicyCloud-seed#[CB5AFEB397838297]-worker) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 0
[junit4] 2> 309708 INFO
(SUITE-TestSimPolicyCloud-seed#[CB5AFEB397838297]-worker) [ ]
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history
in memory.
[junit4] 2> 309710 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 309711 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 0
[junit4] 2> 309711 INFO
(TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[CB5AFEB397838297])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateCollectionAddReplica
[junit4] 2> 309711 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion -1
[junit4] 2> 309711 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 0
[junit4] 2> 309712 DEBUG
(TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 1
[junit4] 2> 309712 INFO
(TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and
clearing object cache...
[junit4] 2> 309715 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:10004_solr, 127.0.0.1:10000_solr,
127.0.0.1:10002_solr, 127.0.0.1:10003_solr]
[junit4] 2> 309715 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 309715 DEBUG
(TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool
executor now
[junit4] 2> 309715 DEBUG
(TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 309715 DEBUG
(TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 309715 DEBUG
(TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread
pool executor
[junit4] 2> 309715 WARN (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Interrupted
[junit4] 2> java.lang.InterruptedException: null
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireInterruptibly(AbstractQueuedSynchronizer.java:1220)
~[?:1.8.0_172]
[junit4] 2> at
java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:335)
~[?:1.8.0_172]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:177)
[java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
[junit4] 2> 309716 DEBUG
(TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 309716 DEBUG
(TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed
explicitly
[junit4] 2> 309716 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 309716 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 2
[junit4] 2> 309716 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion -1
[junit4] 2> 309716 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 309716 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:10004_solr, 127.0.0.1:10000_solr,
127.0.0.1:10002_solr, 127.0.0.1:10003_solr]
[junit4] 2> 309716 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 309716 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 309721 DEBUG (ScheduledTrigger-909-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 309741 DEBUG (ScheduledTrigger-909-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 309762 DEBUG (ScheduledTrigger-909-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 309783 DEBUG (ScheduledTrigger-909-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 309804 DEBUG (ScheduledTrigger-909-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 309825 DEBUG (ScheduledTrigger-909-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 309847 DEBUG (ScheduledTrigger-909-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 309867 DEBUG (ScheduledTrigger-909-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 309888 DEBUG (ScheduledTrigger-909-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 309912 DEBUG (ScheduledTrigger-909-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 309936 DEBUG (ScheduledTrigger-909-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 309958 DEBUG (simCloudManagerPool-908-thread-1) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=1
[junit4] 2> 309959 DEBUG (simCloudManagerPool-908-thread-1) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 1
[junit4] 2> 309959 DEBUG (ScheduledTrigger-909-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 309961 DEBUG (simCloudManagerPool-908-thread-1) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 309965 DEBUG (simCloudManagerPool-908-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
3
[junit4] 2> 309965 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 309966 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 309966 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 309968 DEBUG (simCloudManagerPool-908-thread-2) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- simCreateCollection
testCreateCollectionAddReplica, currentVersion=2
[junit4] 2> 309969 DEBUG (simCloudManagerPool-908-thread-2) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=2
[junit4] 2> 309969 DEBUG (simCloudManagerPool-908-thread-2) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 2
[junit4] 2> 309981 DEBUG (ScheduledTrigger-909-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 309986 DEBUG (simCloudManagerPool-908-thread-2) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=3
[junit4] 2> 309989 DEBUG (simCloudManagerPool-908-thread-2) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 3
[junit4] 2> 310003 DEBUG (ScheduledTrigger-909-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310023 DEBUG (ScheduledTrigger-909-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310035 DEBUG (simCloudManagerPool-908-thread-2) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- finished createCollection
testCreateCollectionAddReplica, currentVersion=4
[junit4] 2> 310042 DEBUG
(TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=4
[junit4] 2> 310042 DEBUG
(TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 4
[junit4] 2> 310044 DEBUG (ScheduledTrigger-909-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310058 DEBUG (simCloudManagerPool-908-thread-3) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=5
[junit4] 2> 310059 DEBUG (simCloudManagerPool-908-thread-3) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 5
[junit4] 2> 310065 DEBUG (ScheduledTrigger-909-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310063 DEBUG (simCloudManagerPool-908-thread-4) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for
testCreateCollectionAddReplica / shard1 (currentVersion=6): {"core_node1":{
[junit4] 2> "core":"testCreateCollectionAddReplica_shard1_replica_n1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCreateCollectionAddReplica",
[junit4] 2> "node_name":"127.0.0.1:10002_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 310068 DEBUG
(TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=6
[junit4] 2> 310068 DEBUG
(TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 6
[junit4] 2> 310072 INFO (simCloudManagerPool-908-thread-5) [ ]
o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10002_solr for creating new
replica of shard shard1 for collection testCreateCollectionAddReplica
[junit4] 2> 310082 DEBUG (simCloudManagerPool-908-thread-5) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=7
[junit4] 2> 310082 DEBUG (simCloudManagerPool-908-thread-5) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 7
[junit4] 2> 310083 INFO
(TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[CB5AFEB397838297])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testCreateCollectionAddReplica
[junit4] 2> 310084 DEBUG
(TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=8
[junit4] 2> 310084 DEBUG
(TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 8
[junit4] 2> 310087 DEBUG (ScheduledTrigger-909-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310108 DEBUG (ScheduledTrigger-909-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310129 DEBUG (ScheduledTrigger-909-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310151 DEBUG (ScheduledTrigger-909-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310165 INFO
(TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase #######################################
[junit4] 2> ############ CLUSTER STATE ############
[junit4] 2> #######################################
[junit4] 2> ## Live nodes: 5
[junit4] 2> ## Empty nodes: 4
[junit4] 2> ## Dead nodes: 0
[junit4] 2> ## Collections:
[junit4] 2> ## * testCreateCollectionAddReplica
[junit4] 2> ## shardsTotal 1
[junit4] 2> ## shardsState {active=1}
[junit4] 2> ## shardsWithoutLeader 0
[junit4] 2> ## totalReplicas 2
[junit4] 2> ## activeReplicas 2
[junit4] 2> ## inactiveReplicas 0
[junit4] 2> ## totalActiveDocs 0
[junit4] 2> ## bufferedDocs 0
[junit4] 2> ## maxActiveSliceDocs 0
[junit4] 2> ## minActiveSliceDocs 0
[junit4] 2> ## avgActiveSliceDocs 0
[junit4] 2> ## totalInactiveDocs 0
[junit4] 2> ## maxInactiveSliceDocs 0
[junit4] 2> ## minInactiveSliceDocs 0
[junit4] 2> ## avgInactiveSliceDocs NaN
[junit4] 2> ## totalActiveBytes 10,240
[junit4] 2> ## maxActiveSliceBytes 10,240
[junit4] 2> ## minActiveSliceBytes 10,240
[junit4] 2> ## avgActiveSliceBytes 10,240
[junit4] 2> ## totalInactiveBytes 0
[junit4] 2> ## maxInactiveSliceBytes 0
[junit4] 2> ## minInactiveSliceBytes 0
[junit4] 2> ## avgInactiveSliceBytes NaN
[junit4] 2> ## totalActiveDeletedDocs 0
[junit4] 2> ## Max replicas per node: 2
[junit4] 2> ## Min replicas per node: 0
[junit4] 2> ## Total replicas: 2
[junit4] 2> ## * testCreateCollectionAddReplica 2
[junit4] 2> ## - active 2
[junit4] 2> ######### Solr op counts ##########
[junit4] 2> ## - ADDREPLICA 1
[junit4] 2> ## - CREATE 1
[junit4] 2> ## - autoscaling 1
[junit4] 2> ######### Autoscaling event counts ###########
[junit4] 2>
[junit4] 2> 310167 INFO
(TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[CB5AFEB397838297])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testDataProviderPerReplicaDetails
[junit4] 2> 310167 DEBUG
(TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 4
[junit4] 2> 310167 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4
[junit4] 2> 310168 INFO
(TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and
clearing object cache...
[junit4] 2> 310168 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 310168 DEBUG
(TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool
executor now
[junit4] 2> 310168 DEBUG
(TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 310168 DEBUG
(TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 310168 DEBUG
(TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread
pool executor
[junit4] 2> 310168 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 310169 DEBUG
(TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 310169 DEBUG
(TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed
explicitly
[junit4] 2> 310169 WARN (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Interrupted
[junit4] 2> java.lang.InterruptedException: null
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireInterruptibly(AbstractQueuedSynchronizer.java:1220)
~[?:1.8.0_172]
[junit4] 2> at
java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:335)
~[?:1.8.0_172]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:177)
[java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
[junit4] 2> 310172 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 310172 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 5
[junit4] 2> 310172 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVersion -1
[junit4] 2> 310172 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 5
[junit4] 2> 310172 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:10004_solr, 127.0.0.1:10000_solr,
127.0.0.1:10002_solr, 127.0.0.1:10003_solr]
[junit4] 2> 310172 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 310172 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVersion 5
[junit4] 2> 310173 DEBUG (ScheduledTrigger-912-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310195 DEBUG (ScheduledTrigger-912-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310216 DEBUG (ScheduledTrigger-912-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310237 DEBUG (ScheduledTrigger-912-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310258 DEBUG (ScheduledTrigger-912-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310280 DEBUG (ScheduledTrigger-912-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310301 DEBUG (ScheduledTrigger-912-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310322 DEBUG (ScheduledTrigger-912-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310343 DEBUG (ScheduledTrigger-912-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310367 DEBUG (ScheduledTrigger-912-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310371 DEBUG (simCloudManagerPool-911-thread-1) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- simCreateCollection perReplicaDataColl,
currentVersion=9
[junit4] 2> 310371 DEBUG (simCloudManagerPool-911-thread-1) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=9
[junit4] 2> 310371 DEBUG (simCloudManagerPool-911-thread-1) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 9
[junit4] 2> 310381 DEBUG (simCloudManagerPool-911-thread-1) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=10
[junit4] 2> 310381 DEBUG (simCloudManagerPool-911-thread-1) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 10
[junit4] 2> 310382 DEBUG (simCloudManagerPool-911-thread-1) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- finished createCollection
perReplicaDataColl, currentVersion=11
[junit4] 2> 310382 DEBUG
(TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=11
[junit4] 2> 310382 DEBUG
(TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 11
[junit4] 2> 310389 DEBUG (ScheduledTrigger-912-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310394 DEBUG (simCloudManagerPool-911-thread-2) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=12
[junit4] 2> 310394 DEBUG (simCloudManagerPool-911-thread-2) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 12
[junit4] 2> 310394 DEBUG (simCloudManagerPool-911-thread-7) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for
perReplicaDataColl / shard1 (currentVersion=13): {"core_node1":{
[junit4] 2> "core":"perReplicaDataColl_shard1_replica_n1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"perReplicaDataColl",
[junit4] 2> "node_name":"127.0.0.1:10000_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 310405 DEBUG (simCloudManagerPool-911-thread-3) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=13
[junit4] 2> 310405 DEBUG (simCloudManagerPool-911-thread-3) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 13
[junit4] 2> 310410 DEBUG (ScheduledTrigger-912-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310416 DEBUG (simCloudManagerPool-911-thread-4) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=14
[junit4] 2> 310416 DEBUG (simCloudManagerPool-911-thread-4) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 14
[junit4] 2> 310427 DEBUG (simCloudManagerPool-911-thread-5) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=15
[junit4] 2> 310427 DEBUG (simCloudManagerPool-911-thread-5) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 15
[junit4] 2> 310431 DEBUG (ScheduledTrigger-912-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310437 DEBUG (simCloudManagerPool-911-thread-6) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=16
[junit4] 2> 310437 DEBUG (simCloudManagerPool-911-thread-6) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 16
[junit4] 2> 310437 DEBUG
(TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=17
[junit4] 2> 310438 DEBUG
(TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 17
[junit4] 2> 310452 DEBUG (ScheduledTrigger-912-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310477 DEBUG (ScheduledTrigger-912-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310498 DEBUG (ScheduledTrigger-912-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310518 DEBUG (ScheduledTrigger-912-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310539 DEBUG (ScheduledTrigger-912-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310543 INFO
(TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[CB5AFEB397838297])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testDataProviderPerReplicaDetails
[junit4] 2> 310543 DEBUG
(TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=18
[junit4] 2> 310544 DEBUG
(TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 18
[junit4] 2> 310544 INFO
(TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase #######################################
[junit4] 2> ############ CLUSTER STATE ############
[junit4] 2> #######################################
[junit4] 2> ## Live nodes: 5
[junit4] 2> ## Empty nodes: 5
[junit4] 2> ## Dead nodes: 0
[junit4] 2> ## Collections:
[junit4] 2> ## Max replicas per node: 0
[junit4] 2> ## Min replicas per node: 0
[junit4] 2> ## Total replicas: 0
[junit4] 2> ######### Solr op counts ##########
[junit4] 2> ## - CREATE 1
[junit4] 2> ## - DELETE 1
[junit4] 2> ######### Autoscaling event counts ###########
[junit4] 2>
[junit4] 2> 310547 INFO
(TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[CB5AFEB397838297])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateCollectionSplitShard
[junit4] 2> 310547 DEBUG
(TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 6
[junit4] 2> 310547 INFO
(TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and
clearing object cache...
[junit4] 2> 310547 DEBUG
(TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool
executor now
[junit4] 2> 310548 DEBUG
(TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 310548 DEBUG
(TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 310548 DEBUG
(TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread
pool executor
[junit4] 2> 310548 WARN (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are
closed, exiting.
[junit4] 2> 310549 DEBUG
(TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 310549 DEBUG
(TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed
explicitly
[junit4] 2> 310550 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 310550 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 7
[junit4] 2> 310551 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 7, lastZnodeVersion -1
[junit4] 2> 310551 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 7
[junit4] 2> 310552 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:10004_solr, 127.0.0.1:10000_solr,
127.0.0.1:10002_solr, 127.0.0.1:10003_solr]
[junit4] 2> 310552 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 310552 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 7, lastZnodeVersion 7
[junit4] 2> 310553 DEBUG (ScheduledTrigger-915-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310574 DEBUG (ScheduledTrigger-915-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310597 DEBUG (ScheduledTrigger-915-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310617 DEBUG (ScheduledTrigger-915-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310639 DEBUG (ScheduledTrigger-915-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310662 DEBUG (ScheduledTrigger-915-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310684 DEBUG (ScheduledTrigger-915-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310704 DEBUG (ScheduledTrigger-915-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310726 DEBUG (ScheduledTrigger-915-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310746 DEBUG (ScheduledTrigger-915-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310767 DEBUG (ScheduledTrigger-915-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310768 DEBUG (simCloudManagerPool-914-thread-1) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=19
[junit4] 2> 310768 DEBUG (simCloudManagerPool-914-thread-1) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 19
[junit4] 2> 310770 DEBUG (simCloudManagerPool-914-thread-1) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 310771 DEBUG (simCloudManagerPool-914-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
8
[junit4] 2> 310778 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 8
[junit4] 2> 310779 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 310779 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 8, lastZnodeVersion 8
[junit4] 2> 310782 DEBUG (simCloudManagerPool-914-thread-2) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- simCreateCollection
testCreateCollectionSplitShard, currentVersion=20
[junit4] 2> 310782 DEBUG (simCloudManagerPool-914-thread-2) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=20
[junit4] 2> 310783 DEBUG (simCloudManagerPool-914-thread-2) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 20
[junit4] 2> 310788 DEBUG (ScheduledTrigger-915-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310793 DEBUG (simCloudManagerPool-914-thread-2) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=21
[junit4] 2> 310793 DEBUG (simCloudManagerPool-914-thread-2) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 21
[junit4] 2> 310809 DEBUG (ScheduledTrigger-915-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310809 DEBUG (simCloudManagerPool-914-thread-2) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- finished createCollection
testCreateCollectionSplitShard, currentVersion=22
[junit4] 2> 310809 DEBUG
(TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=22
[junit4] 2> 310810 DEBUG
(TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 22
[junit4] 2> 310820 DEBUG (simCloudManagerPool-914-thread-3) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=23
[junit4] 2> 310820 DEBUG (simCloudManagerPool-914-thread-3) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 23
[junit4] 2> 310820 DEBUG (simCloudManagerPool-914-thread-5) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for
testCreateCollectionSplitShard / shard1 (currentVersion=24): {"core_node1":{
[junit4] 2> "core":"testCreateCollectionSplitShard_shard1_replica_n1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCreateCollectionSplitShard",
[junit4] 2> "node_name":"127.0.0.1:10000_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 310830 DEBUG (ScheduledTrigger-915-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310830 DEBUG (simCloudManagerPool-914-thread-4) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=24
[junit4] 2> 310830 DEBUG (simCloudManagerPool-914-thread-4) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 24
[junit4] 2> 310830 DEBUG
(TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=25
[junit4] 2> 310830 DEBUG
(TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[CB5AFEB397838297])
[ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 25
[junit4] 2> 310851 DEBUG (ScheduledTrigger-915-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310872 DEBUG (ScheduledTrigger-915-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310893 DEBUG (ScheduledTrigger-915-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310914 DEBUG (ScheduledTrigger-915-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310935 DEBUG (ScheduledTrigger-915-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310957 DEBUG (ScheduledTrigger-915-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310978 DEBUG (ScheduledTrigger-915-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 310990 DEBUG (simCloudManagerPool-914-thread-6) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=26
[junit4] 2> 310992 DEBUG (simCloudManagerPool-914-thread-6) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 26
[junit4] 2> 310999 DEBUG (simCloudManagerPool-914-thread-7) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for
testCreateCollectionSplitShard / shard1_1 (currentVersion=27): {"core_node10":{
[junit4] 2>
"core":"testCreateCollectionSplitShard_shard1_1_replica_n9",
[junit4] 2> "shard":"shard1_1",
[junit4] 2> "collection":"testCreateCollectionSplitShard",
[junit4] 2> "node_name":"127.0.0.1:10001_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "base_url":"http://127.0.0.1:10001/solr";,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 311000 DEBUG (simCloudManagerPool-914-thread-6) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=27
[junit4] 2> 311000 DEBUG (ScheduledTrigger-915-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 311001 DEBUG (simCloudManagerPool-914-thread-8) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for
testCreateCollectionSplitShard / shard1_0 (currentVersion=27): {"core_node6":{
[junit4] 2>
"core":"testCreateCollectionSplitShard_shard1_0_replica_n5",
[junit4] 2> "shard":"shard1_0",
[junit4] 2> "collection":"testCreateCollectionSplitShard",
[junit4] 2> "node_name":"127.0.0.1:10000_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":20480,
[junit4] 2> "base_url":"http://127.0.0.1:10000/solr";,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":1.9073486328125E-5,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 311001 DEBUG (simCloudManagerPool-914-thread-6) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 27
[junit4] 2> 311001 DEBUG (simCloudManagerPool-914-thread-6) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** no leader in
testCreateCollectionSplitShard / shard1_0:{
[junit4] 2> "parent":"shard1",
[junit4] 2> "stateTimestamp":"1538775488310175000",
[junit4] 2> "range":"80000000-ffffffff",
[junit4] 2> "state":"construction",
[junit4] 2> "replicas":{
[junit4] 2> "core_node6":{
[junit4] 2>
"core":"testCreateCollectionSplitShard_shard1_0_replica_n5",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":20480,
[junit4] 2> "node_name":"127.0.0.1:10000_solr",
[junit4] 2> "base_url":"http://127.0.0.1:10000/solr";,
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInGB":1.9073486328125E-5,
[junit4] 2> "SEARCHER.searcher.numDocs":0},
[junit4] 2> "core_node8":{
[junit4] 2>
"core":"testCreateCollectionSplitShard_shard1_0_replica_n7",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":20480,
[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> "INDEX.sizeInGB":1.9073486328125E-5,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}}
[junit4] 2> 311004 DEBUG (simCloudManagerPool-914-thread-6) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** no leader in
testCreateCollectionSplitShard / shard1_0:{
[junit4] 2> "parent":"shard1",
[junit4] 2> "stateTimestamp":"1538775488310175000",
[junit4] 2> "range":"80000000-ffffffff",
[junit4] 2> "state":"construction",
[junit4] 2> "replicas":{
[junit4] 2> "core_node6":{
[junit4] 2>
"core":"testCreateCollectionSplitShard_shard1_0_replica_n5",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":20480,
[junit4] 2> "node_name":"127.0.0.1:10000_solr",
[junit4] 2> "base_url":"http://127.0.0.1:10000/solr";,
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInGB":1.9073486328125E-5,
[junit4] 2> "SEARCHER.searcher.numDocs":0},
[junit4] 2> "core_node8":{
[junit4] 2>
"core":"testCreateCollectionSplitShard_shard1_0_replica_n7",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":20480,
[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> "INDEX.sizeInGB":1.9073486328125E-5,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}}
[junit4] 2> 311007 DEBUG (simCloudManagerPool-914-thread-6) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** no leader
[...truncated too long message...]
at
org.apache.solr.cloud.autoscaling.sim.GenericDistributedQueue.peek(GenericDistributedQueue.java:194)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.sim.GenericDistributedQueue.peek(GenericDistributedQueue.java:167)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.sim.TestSimDistributedQueue.testDistributedQueue(TestSimDistributedQueue.java:74)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.sim.TestSimGenericDistributedQueue.testDistributedQueue(TestSimGenericDistributedQueue.java:36)
[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:1742)
[junit4] 2> at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935)
[junit4] 2> at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971)
[junit4] 2> at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[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:944)
[junit4] 2> at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830)
[junit4] 2> at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880)
[junit4] 2> at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[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> oct 06, 2018 10:37:32 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFORMACIÓN: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=338,
name=TEST-TestSimGenericDistributedQueue.testDistributedQueue-seed#[CB5AFEB397838297],
state=TIMED_WAITING, group=TGRP-TestSimGenericDistributedQueue]
[junit4] 2> 7307883 INFO
(TEST-TestSimGenericDistributedQueue.testDistributedQueue-seed#[CB5AFEB397838297])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testDistributedQueue {#2}
[junit4] 2> NOTE: leaving temporary files on disk at:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.sim.TestSimGenericDistributedQueue_CB5AFEB397838297-001
[junit4] 2> Oct 06, 2018 2:37:32 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: All leaked threads terminated.
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): {},
docValues:{}, maxPointsInLeafNode=1843, maxMBSortInHeap=6.257355479616999,
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@10a2207),
locale=es, timezone=Australia/West
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_172
(32-bit)/cpus=3,threads=1,free=64949952,total=83361792
[junit4] 2> NOTE: All tests run in this JVM:
[TestSimGenericDistributedQueue, TestSimPolicyCloud, TestSimPolicyCloud,
TestSimPolicyCloud, TestSimGenericDistributedQueue]
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=TestSimGenericDistributedQueue -Dtests.seed=CB5AFEB397838297
-Dtests.slow=true -Dtests.badapples=true -Dtests.locale=es
-Dtests.timezone=Australia/West -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | TestSimGenericDistributedQueue (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>=
7200000 msec).
[junit4] > at
__randomizedtesting.SeedInfo.seed([CB5AFEB397838297]:0)
[junit4] Completed [10/10 (1!)] on J1 in 7280.20s, 5 tests, 2 errors <<<
FAILURES!
[...truncated 11 lines...]
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.xml:1567:
The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.xml:1092:
There were test failures: 10 suites, 60 tests, 1 suite-level error, 1 error
[seed: CB5AFEB397838297]
Total time: 121 minutes 50 seconds
[repro] Setting last failure code to 1
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.cloud.autoscaling.sim.TestSimPolicyCloud
[repro] 1/5 failed:
org.apache.solr.cloud.autoscaling.sim.TestSimGenericDistributedQueue
[repro] Exiting with code 1
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST lucene\build
move /y lucene\build lucene\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST solr\build move
/y solr\build solr\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST
lucene\build.orig move /y lucene\build.orig lucene\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST solr\build.orig
move /y solr\build.orig solr\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>exit 0
Archiving artifacts
Setting
ANT_1_8_2_HOME=C:\Users\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=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting
ANT_1_8_2_HOME=C:\Users\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=eb47099ee22669926e6fdd4fc39cce7d043e3e97,
workspace=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows
[WARNINGS] Computing warning deltas based on reference build #7552
Recording test results
Setting
ANT_1_8_2_HOME=C:\Users\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=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting
ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting
ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting
ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
