Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/21710/
Java: 64bit/jdk-9.0.4 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED:
org.apache.solr.cloud.autoscaling.sim.TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy
Error Message:
Stack Trace:
java.lang.NullPointerException
at
__randomizedtesting.SeedInfo.seed([F4A7D64BE8079D4D:64F5C3D8E5CB5CB1]:0)
at
org.apache.solr.cloud.autoscaling.sim.SimClusterStateProvider.lambda$getCollectionStates$52(SimClusterStateProvider.java:1236)
at java.base/java.util.ArrayList.forEach(ArrayList.java:1380)
at
org.apache.solr.cloud.autoscaling.sim.SimClusterStateProvider.lambda$getCollectionStates$53(SimClusterStateProvider.java:1228)
at
java.base/java.util.concurrent.ConcurrentHashMap.forEach(ConcurrentHashMap.java:1617)
at
org.apache.solr.cloud.autoscaling.sim.SimClusterStateProvider.getCollectionStates(SimClusterStateProvider.java:1227)
at
org.apache.solr.cloud.autoscaling.sim.SimClusterStateProvider.getClusterState(SimClusterStateProvider.java:1209)
at
org.apache.solr.cloud.autoscaling.sim.SimSolrCloudTestCase.getCollectionState(SimSolrCloudTestCase.java:190)
at
org.apache.solr.cloud.autoscaling.sim.TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy(TestPolicyCloud.java:286)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
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
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: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
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.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 1847 lines...]
[junit4] JVM J1: stdout was not empty, see:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/temp/junit4-J1-20180327_203124_64811671650229847093039.sysout
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] codec: CheapBastard, pf: RAMOnly, dvf: Direct
[junit4] <<< JVM J1: EOF ----
[...truncated 12394 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.sim.TestPolicyCloud
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.sim.TestPolicyCloud_F4A7D64BE8079D4D-001/init-core-data-001
[junit4] 2> 2191132 INFO
(SUITE-TestPolicyCloud-seed#[F4A7D64BE8079D4D]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 2191132 INFO
(SUITE-TestPolicyCloud-seed#[F4A7D64BE8079D4D]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0,
clientAuth=0.0/0.0)
[junit4] 2> 2191133 INFO
(SUITE-TestPolicyCloud-seed#[F4A7D64BE8079D4D]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2191134 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 2191134 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 0
[junit4] 2> 2191134 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10010_solr,
127.0.0.1:10009_solr, 127.0.0.1:10013_solr, 127.0.0.1:10012_solr,
127.0.0.1:10011_solr]
[junit4] 2> 2191134 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion -1
[junit4] 2> 2191134 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 0
[junit4] 2> 2191135 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2191135 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion 0
[junit4] 2> 2191135 DEBUG (ScheduledTrigger-7871-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191135 INFO
(TEST-TestPolicyCloud.testCreateCollectionSplitShard-seed#[F4A7D64BE8079D4D]) [
] o.a.s.SolrTestCaseJ4 ###Starting testCreateCollectionSplitShard
[junit4] 2> 2191135 DEBUG
(TEST-TestPolicyCloud.testCreateCollectionSplitShard-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 1
[junit4] 2> 2191135 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 2191135 INFO
(TEST-TestPolicyCloud.testCreateCollectionSplitShard-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and
clearing object cache...
[junit4] 2> 2191135 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 2191135 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2191135 DEBUG
(TEST-TestPolicyCloud.testCreateCollectionSplitShard-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed
explicitly
[junit4] 2> 2191135 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:1247)
~[?:?]
[junit4] 2> at
java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:317)
~[?:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:165)
[java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:844) [?:?]
[junit4] 2> 2191135 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 2191136 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 2
[junit4] 2> 2191136 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10010_solr,
127.0.0.1:10009_solr, 127.0.0.1:10013_solr, 127.0.0.1:10012_solr,
127.0.0.1:10011_solr]
[junit4] 2> 2191136 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion -1
[junit4] 2> 2191136 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 2191136 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2191136 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 2191136 DEBUG (ScheduledTrigger-7874-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191156 DEBUG (ScheduledTrigger-7874-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191177 DEBUG (ScheduledTrigger-7874-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191197 DEBUG (ScheduledTrigger-7874-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191217 DEBUG (ScheduledTrigger-7874-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191236 DEBUG (simCloudManagerPool-7873-thread-1) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2191236 DEBUG (simCloudManagerPool-7873-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
3
[junit4] 2> 2191237 DEBUG (simCloudManagerPool-7873-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10010_solr,
127.0.0.1:10009_solr, 127.0.0.1:10013_solr, 127.0.0.1:10012_solr,
127.0.0.1:10011_solr]
[junit4] 2> 2191237 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 2191237 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2191237 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 2191237 DEBUG (ScheduledTrigger-7874-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191249 INFO (simCloudManagerPool-7873-thread-2) [ ]
o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 2425046101
sessionWrapper.createTime 2425046038586867, this.sessionWrapper.createTime
2425046038586867
[junit4] 2> 2191257 DEBUG (ScheduledTrigger-7874-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191278 DEBUG (ScheduledTrigger-7874-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191298 DEBUG (ScheduledTrigger-7874-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191318 DEBUG (ScheduledTrigger-7874-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191338 DEBUG (ScheduledTrigger-7874-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191358 DEBUG (ScheduledTrigger-7874-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191374 INFO (simCloudManagerPool-7873-thread-5) [ ]
o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 2425052367
sessionWrapper.createTime 2425052206377817, this.sessionWrapper.createTime
2425052206377817
[junit4] 2> 2191378 DEBUG (ScheduledTrigger-7874-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191398 DEBUG (ScheduledTrigger-7874-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191416 DEBUG (simCloudManagerPool-7873-thread-6) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for
testCreateCollectionSplitShard / shard1: {"core_node1":{
[junit4] 2> "node_name":"127.0.0.1:10009_solr",
[junit4] 2> "core":"testCreateCollectionSplitShard_shard1_replica_n1",
[junit4] 2> "leader":"true",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCreateCollectionSplitShard"}}
[junit4] 2> 2191416 DEBUG (simCloudManagerPool-7873-thread-7) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for
testCreateCollectionSplitShard / shard1_1: {"core_node7":{
[junit4] 2> "node_name":"127.0.0.1:10010_solr",
[junit4] 2> "base_url":"http://127.0.0.1:10010/solr";,
[junit4] 2> "leader":"true",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2> "core":"testCreateCollectionSplitShard_shard1_1_replica1",
[junit4] 2> "shard":"shard1_1",
[junit4] 2> "collection":"testCreateCollectionSplitShard"}}
[junit4] 2> 2191417 DEBUG (simCloudManagerPool-7873-thread-8) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for
testCreateCollectionSplitShard / shard1_0: {"core_node6":{
[junit4] 2> "node_name":"127.0.0.1:10010_solr",
[junit4] 2> "base_url":"http://127.0.0.1:10010/solr";,
[junit4] 2> "leader":"true",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2> "core":"testCreateCollectionSplitShard_shard1_0_replica2",
[junit4] 2> "shard":"shard1_0",
[junit4] 2> "collection":"testCreateCollectionSplitShard"}}
[junit4] 2> 2191418 DEBUG (ScheduledTrigger-7874-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191439 DEBUG (ScheduledTrigger-7874-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191459 DEBUG (ScheduledTrigger-7874-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191479 DEBUG (ScheduledTrigger-7874-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191499 DEBUG (ScheduledTrigger-7874-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191518 INFO
(TEST-TestPolicyCloud.testCreateCollectionSplitShard-seed#[F4A7D64BE8079D4D]) [
] o.a.s.SolrTestCaseJ4 ###Ending testCreateCollectionSplitShard
[junit4] 2> 2191518 INFO
(TEST-TestPolicyCloud.testCreateCollectionSplitShard-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase
[junit4] 2>
[junit4] 2> 2191518 INFO
(TEST-TestPolicyCloud.testCreateCollectionSplitShard-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase
#############################################
[junit4] 2> 2191518 INFO
(TEST-TestPolicyCloud.testCreateCollectionSplitShard-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ############ FINAL CLUSTER STATS
############
[junit4] 2> 2191518 INFO
(TEST-TestPolicyCloud.testCreateCollectionSplitShard-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase
#############################################
[junit4] 2>
[junit4] 2> 2191518 INFO
(TEST-TestPolicyCloud.testCreateCollectionSplitShard-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Live nodes: 5
[junit4] 2> 2191518 INFO
(TEST-TestPolicyCloud.testCreateCollectionSplitShard-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Empty nodes: 5
[junit4] 2> 2191518 INFO
(TEST-TestPolicyCloud.testCreateCollectionSplitShard-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Dead nodes: 0
[junit4] 2> 2191518 INFO
(TEST-TestPolicyCloud.testCreateCollectionSplitShard-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Collections: []
[junit4] 2> 2191518 INFO
(TEST-TestPolicyCloud.testCreateCollectionSplitShard-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Max replicas per node: 0
[junit4] 2> 2191518 INFO
(TEST-TestPolicyCloud.testCreateCollectionSplitShard-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Min replicas per node: 0
[junit4] 2> 2191518 INFO
(TEST-TestPolicyCloud.testCreateCollectionSplitShard-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Total replicas: 0
[junit4] 2> 2191518 INFO
(TEST-TestPolicyCloud.testCreateCollectionSplitShard-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ######### Final Solr op counts ##########
[junit4] 2> 2191518 INFO
(TEST-TestPolicyCloud.testCreateCollectionSplitShard-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## - CREATE 1
[junit4] 2> 2191518 INFO
(TEST-TestPolicyCloud.testCreateCollectionSplitShard-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## - DELETE 1
[junit4] 2> 2191518 INFO
(TEST-TestPolicyCloud.testCreateCollectionSplitShard-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## - SPLITSHARD 1
[junit4] 2> 2191518 INFO
(TEST-TestPolicyCloud.testCreateCollectionSplitShard-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## - autoscaling 1
[junit4] 2> 2191518 INFO
(TEST-TestPolicyCloud.testCreateCollectionSplitShard-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ######### Autoscaling event counts
###########
[junit4] 2> 2191519 DEBUG (ScheduledTrigger-7874-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191520 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateCollectionAddShardUsingPolicy
[junit4] 2> 2191520 DEBUG
(TEST-TestPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 4
[junit4] 2> 2191520 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4
[junit4] 2> 2191520 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and
clearing object cache...
[junit4] 2> 2191520 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 2191520 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2191521 DEBUG
(TEST-TestPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed
explicitly
[junit4] 2> 2191521 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:1247)
~[?:?]
[junit4] 2> at
java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:317)
~[?:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:165)
[java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:844) [?:?]
[junit4] 2> 2191521 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 2191521 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 5
[junit4] 2> 2191522 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10010_solr,
127.0.0.1:10009_solr, 127.0.0.1:10013_solr, 127.0.0.1:10012_solr,
127.0.0.1:10011_solr]
[junit4] 2> 2191522 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVersion -1
[junit4] 2> 2191522 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 5
[junit4] 2> 2191531 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2191531 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVersion 5
[junit4] 2> 2191531 DEBUG (ScheduledTrigger-7877-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191552 DEBUG (ScheduledTrigger-7877-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191575 DEBUG (ScheduledTrigger-7877-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191595 DEBUG (ScheduledTrigger-7877-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191616 DEBUG (ScheduledTrigger-7877-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191622 DEBUG (simCloudManagerPool-7876-thread-1) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2191622 DEBUG (simCloudManagerPool-7876-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
6
[junit4] 2> 2191622 DEBUG (simCloudManagerPool-7876-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10010_solr,
127.0.0.1:10009_solr, 127.0.0.1:10013_solr, 127.0.0.1:10012_solr,
127.0.0.1:10011_solr]
[junit4] 2> 2191622 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 6
[junit4] 2> 2191622 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2191622 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6, lastZnodeVersion 6
[junit4] 2> 2191634 INFO (simCloudManagerPool-7876-thread-2) [ ]
o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 2425065347
sessionWrapper.createTime 2425065314245717, this.sessionWrapper.createTime
2425065314245717
[junit4] 2> 2191636 DEBUG (ScheduledTrigger-7877-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191656 DEBUG (ScheduledTrigger-7877-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191676 DEBUG (ScheduledTrigger-7877-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191696 DEBUG (ScheduledTrigger-7877-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191716 DEBUG (ScheduledTrigger-7877-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191737 DEBUG (ScheduledTrigger-7877-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191757 DEBUG (ScheduledTrigger-7877-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191758 INFO (simCloudManagerPool-7876-thread-5) [ ]
o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 2425071569
sessionWrapper.createTime 2425071549088417, this.sessionWrapper.createTime
2425071549088417
[junit4] 2> 2191770 DEBUG (simCloudManagerPool-7876-thread-7) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for policiesTest /
s1: {"core_node1":{
[junit4] 2> "node_name":"127.0.0.1:10012_solr",
[junit4] 2> "core":"policiesTest_s1_replica_n1",
[junit4] 2> "leader":"true",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2> "shard":"s1",
[junit4] 2> "collection":"policiesTest"}}
[junit4] 2> 2191770 DEBUG (simCloudManagerPool-7876-thread-8) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for policiesTest /
s2: {"core_node2":{
[junit4] 2> "node_name":"127.0.0.1:10012_solr",
[junit4] 2> "core":"policiesTest_s2_replica_n2",
[junit4] 2> "leader":"true",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2> "shard":"s2",
[junit4] 2> "collection":"policiesTest"}}
[junit4] 2> 2191770 DEBUG (simCloudManagerPool-7876-thread-6) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for policiesTest /
s3: {"core_node3":{
[junit4] 2> "core":"policiesTest_s3_replica_n1",
[junit4] 2> "leader":"true",
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2> "node_name":"127.0.0.1:10012_solr",
[junit4] 2> "base_url":"http://127.0.0.1:10012/solr";,
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "shard":"s3",
[junit4] 2> "collection":"policiesTest"}}
[junit4] 2> 2191771 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testCreateCollectionAddShardUsingPolicy
[junit4] 2> 2191771 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase
[junit4] 2>
[junit4] 2> 2191771 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase
#############################################
[junit4] 2> 2191771 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ############ FINAL CLUSTER STATS
############
[junit4] 2> 2191771 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase
#############################################
[junit4] 2>
[junit4] 2> 2191771 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Live nodes: 5
[junit4] 2> 2191771 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Empty nodes: 4
[junit4] 2> 2191771 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Dead nodes: 0
[junit4] 2> 2191771 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Collections: [policiesTest]
[junit4] 2> 2191771 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Max replicas per node: 3
[junit4] 2> 2191771 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Min replicas per node: 0
[junit4] 2> 2191771 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Total replicas: 3
[junit4] 2> 2191771 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## * policiesTest 3
[junit4] 2> 2191771 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## - active 3
[junit4] 2> 2191771 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ######### Final Solr op counts
##########
[junit4] 2> 2191771 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## - CREATE
1
[junit4] 2> 2191771 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## - CREATESHARD
1
[junit4] 2> 2191771 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## - autoscaling
1
[junit4] 2> 2191771 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ######### Autoscaling event counts
###########
[junit4] 2> 2191773 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[F4A7D64BE8079D4D]) [
] o.a.s.SolrTestCaseJ4 ###Starting testCreateCollectionAddReplica
[junit4] 2> 2191773 DEBUG
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 7
[junit4] 2> 2191773 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 7
[junit4] 2> 2191773 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and
clearing object cache...
[junit4] 2> 2191773 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 2191773 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2191773 DEBUG
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed
explicitly
[junit4] 2> 2191773 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:1247)
~[?:?]
[junit4] 2> at
java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:317)
~[?:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:165)
[java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:844) [?:?]
[junit4] 2> 2191774 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 2191774 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 8
[junit4] 2> 2191774 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10010_solr,
127.0.0.1:10009_solr, 127.0.0.1:10013_solr, 127.0.0.1:10012_solr,
127.0.0.1:10011_solr]
[junit4] 2> 2191774 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 8, lastZnodeVersion -1
[junit4] 2> 2191774 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 8
[junit4] 2> 2191775 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2191775 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 8, lastZnodeVersion 8
[junit4] 2> 2191775 DEBUG (ScheduledTrigger-7880-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191795 DEBUG (ScheduledTrigger-7880-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191815 DEBUG (ScheduledTrigger-7880-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191835 DEBUG (ScheduledTrigger-7880-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191855 DEBUG (ScheduledTrigger-7880-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191874 DEBUG (simCloudManagerPool-7879-thread-1) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2191875 DEBUG (simCloudManagerPool-7879-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
9
[junit4] 2> 2191875 DEBUG (simCloudManagerPool-7879-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10010_solr,
127.0.0.1:10009_solr, 127.0.0.1:10013_solr, 127.0.0.1:10012_solr,
127.0.0.1:10011_solr]
[junit4] 2> 2191875 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 9
[junit4] 2> 2191875 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2191875 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 9, lastZnodeVersion 9
[junit4] 2> 2191876 DEBUG (ScheduledTrigger-7880-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191886 INFO (simCloudManagerPool-7879-thread-2) [ ]
o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 2425077972
sessionWrapper.createTime 2425077957183567, this.sessionWrapper.createTime
2425077957183567
[junit4] 2> 2191896 DEBUG (ScheduledTrigger-7880-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191899 INFO (simCloudManagerPool-7879-thread-4) [ ]
o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 2425078617
sessionWrapper.createTime 2425078601970267, this.sessionWrapper.createTime
2425078601970267
[junit4] 2> 2191899 INFO (simCloudManagerPool-7879-thread-4) [ ]
o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10013_solr for creating new
replica
[junit4] 2> 2191910 DEBUG (simCloudManagerPool-7879-thread-5) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for
testCreateCollectionAddReplica / shard1: {"core_node1":{
[junit4] 2> "node_name":"127.0.0.1:10013_solr",
[junit4] 2> "core":"testCreateCollectionAddReplica_shard1_replica_n1",
[junit4] 2> "leader":"true",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCreateCollectionAddReplica"}}
[junit4] 2> 2191911 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[F4A7D64BE8079D4D]) [
] o.a.s.SolrTestCaseJ4 ###Ending testCreateCollectionAddReplica
[junit4] 2> 2191911 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase
[junit4] 2>
[junit4] 2> 2191911 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase
#############################################
[junit4] 2> 2191911 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ############ FINAL CLUSTER STATS
############
[junit4] 2> 2191911 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase
#############################################
[junit4] 2>
[junit4] 2> 2191911 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Live nodes: 5
[junit4] 2> 2191911 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Empty nodes: 4
[junit4] 2> 2191911 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Dead nodes: 0
[junit4] 2> 2191911 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Collections:
[testCreateCollectionAddReplica]
[junit4] 2> 2191911 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Max replicas per node: 2
[junit4] 2> 2191911 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Min replicas per node: 0
[junit4] 2> 2191911 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## Total replicas: 2
[junit4] 2> 2191911 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## * testCreateCollectionAddReplica
2
[junit4] 2> 2191911 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## - active 2
[junit4] 2> 2191911 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ######### Final Solr op counts ##########
[junit4] 2> 2191911 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## - ADDREPLICA 1
[junit4] 2> 2191911 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## - CREATE 1
[junit4] 2> 2191911 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ## - autoscaling 1
[junit4] 2> 2191911 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[F4A7D64BE8079D4D]) [
] o.a.s.c.a.s.SimSolrCloudTestCase ######### Autoscaling event counts
###########
[junit4] 2> 2191913 INFO
(TEST-TestPolicyCloud.testDataProvider-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testDataProvider
[junit4] 2> 2191913 DEBUG
(TEST-TestPolicyCloud.testDataProvider-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
10
[junit4] 2> 2191913 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion
10
[junit4] 2> 2191913 INFO
(TEST-TestPolicyCloud.testDataProvider-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and clearing
object cache...
[junit4] 2> 2191913 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 2191913 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2191913 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:1247)
~[?:?]
[junit4] 2> at
java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:317)
~[?:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:165)
[java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:844) [?:?]
[junit4] 2> 2191913 DEBUG
(TEST-TestPolicyCloud.testDataProvider-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 2191914 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 2191914 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 11
[junit4] 2> 2191914 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10010_solr,
127.0.0.1:10009_solr, 127.0.0.1:10013_solr, 127.0.0.1:10012_solr,
127.0.0.1:10011_solr]
[junit4] 2> 2191914 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 11, lastZnodeVersion -1
[junit4] 2> 2191914 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion
11
[junit4] 2> 2191918 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2191918 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 11, lastZnodeVersion 11
[junit4] 2> 2191918 DEBUG (ScheduledTrigger-7883-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191938 DEBUG (ScheduledTrigger-7883-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191959 DEBUG (ScheduledTrigger-7883-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191979 DEBUG (ScheduledTrigger-7883-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2191999 DEBUG (ScheduledTrigger-7883-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192019 DEBUG (ScheduledTrigger-7883-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192039 DEBUG (ScheduledTrigger-7883-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192060 DEBUG (ScheduledTrigger-7883-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192069 INFO
(TEST-TestPolicyCloud.testDataProvider-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testDataProvider
[junit4] 2> 2192069 INFO
(TEST-TestPolicyCloud.testDataProvider-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase
[junit4] 2>
[junit4] 2> 2192069 INFO
(TEST-TestPolicyCloud.testDataProvider-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase #############################################
[junit4] 2> 2192069 INFO
(TEST-TestPolicyCloud.testDataProvider-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ############ FINAL CLUSTER STATS ############
[junit4] 2> 2192069 INFO
(TEST-TestPolicyCloud.testDataProvider-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase #############################################
[junit4] 2>
[junit4] 2> 2192069 INFO
(TEST-TestPolicyCloud.testDataProvider-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## Live nodes: 5
[junit4] 2> 2192069 INFO
(TEST-TestPolicyCloud.testDataProvider-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## Empty nodes: 3
[junit4] 2> 2192069 INFO
(TEST-TestPolicyCloud.testDataProvider-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## Dead nodes: 0
[junit4] 2> 2192069 INFO
(TEST-TestPolicyCloud.testDataProvider-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## Collections: [policiesTest]
[junit4] 2> 2192069 INFO
(TEST-TestPolicyCloud.testDataProvider-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## Max replicas per node: 1
[junit4] 2> 2192069 INFO
(TEST-TestPolicyCloud.testDataProvider-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## Min replicas per node: 0
[junit4] 2> 2192069 INFO
(TEST-TestPolicyCloud.testDataProvider-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## Total replicas: 2
[junit4] 2> 2192069 INFO
(TEST-TestPolicyCloud.testDataProvider-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## * policiesTest 2
[junit4] 2> 2192069 INFO
(TEST-TestPolicyCloud.testDataProvider-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## - active 2
[junit4] 2> 2192069 INFO
(TEST-TestPolicyCloud.testDataProvider-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ######### Final Solr op counts ##########
[junit4] 2> 2192069 INFO
(TEST-TestPolicyCloud.testDataProvider-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## - ADDROLE 1
[junit4] 2> 2192069 INFO
(TEST-TestPolicyCloud.testDataProvider-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## - CREATE 1
[junit4] 2> 2192069 INFO
(TEST-TestPolicyCloud.testDataProvider-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ######### Autoscaling event counts ###########
[junit4] 2> 2192070 INFO
(TEST-TestPolicyCloud.testMetricsTag-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testMetricsTag
[junit4] 2> 2192071 DEBUG
(TEST-TestPolicyCloud.testMetricsTag-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
12
[junit4] 2> 2192071 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion
12
[junit4] 2> 2192071 INFO
(TEST-TestPolicyCloud.testMetricsTag-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and clearing
object cache...
[junit4] 2> 2192071 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 2192071 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2192071 DEBUG
(TEST-TestPolicyCloud.testMetricsTag-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 2192071 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:1247)
~[?:?]
[junit4] 2> at
java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:317)
~[?:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:165)
[java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:844) [?:?]
[junit4] 2> 2192071 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 2192071 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 13
[junit4] 2> 2192071 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10010_solr,
127.0.0.1:10009_solr, 127.0.0.1:10013_solr, 127.0.0.1:10012_solr,
127.0.0.1:10011_solr]
[junit4] 2> 2192071 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 13, lastZnodeVersion -1
[junit4] 2> 2192072 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion
13
[junit4] 2> 2192072 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2192072 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 13, lastZnodeVersion 13
[junit4] 2> 2192072 DEBUG (ScheduledTrigger-7886-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192092 DEBUG (ScheduledTrigger-7886-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192112 DEBUG (ScheduledTrigger-7886-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192133 DEBUG (ScheduledTrigger-7886-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192153 DEBUG (ScheduledTrigger-7886-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192172 ERROR (simCloudManagerPool-7885-thread-1) [ ]
o.a.s.h.RequestHandlerBase org.apache.solr.api.ApiBag$ExceptionWithErrObject:
Error in command payload, errors: [{set-cluster-policy=[{cores=<10, node=#ANY},
{replica=<2, shard=#EACH, node=#ANY}, {metrics:abc=overseer, replica=0}],
errorMessages=[Invalid metrics: param in {
[junit4] 2> "metrics:abc":"overseer",
[junit4] 2> "replica":0} must have at 2 or 3 segments after 'metrics:'
separated by ':']}],
[junit4] 2> at
org.apache.solr.cloud.autoscaling.AutoScalingHandler.processOps(AutoScalingHandler.java:207)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:145)
[junit4] 2> at
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:195)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.sim.SimCloudManager.simHandleSolrRequest(SimCloudManager.java:497)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.sim.SimCloudManager.lambda$request$2(SimCloudManager.java:459)
[junit4] 2> at
java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at
java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
[junit4] 2> at
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2>
[junit4] 2> 2192172 DEBUG (simCloudManagerPool-7885-thread-1) [ ]
o.a.s.c.a.s.SimCloudManager -- exception handling request
[junit4] 2> org.apache.solr.api.ApiBag$ExceptionWithErrObject: Error in
command payload
[junit4] 2> at
org.apache.solr.cloud.autoscaling.AutoScalingHandler.processOps(AutoScalingHandler.java:207)
~[java/:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:145)
~[java/:?]
[junit4] 2> at
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:195)
~[java/:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.sim.SimCloudManager.simHandleSolrRequest(SimCloudManager.java:497)
[test/:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.sim.SimCloudManager.lambda$request$2(SimCloudManager.java:459)
[test/:?]
[junit4] 2> at
java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[java/:?]
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
[?:?]
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[?:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:844) [?:?]
[junit4] 2> 2192173 DEBUG (simCloudManagerPool-7885-thread-2) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2192173 DEBUG (ScheduledTrigger-7886-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192173 DEBUG (simCloudManagerPool-7885-thread-2) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
14
[junit4] 2> 2192173 DEBUG (simCloudManagerPool-7885-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10010_solr,
127.0.0.1:10009_solr, 127.0.0.1:10013_solr, 127.0.0.1:10012_solr,
127.0.0.1:10011_solr]
[junit4] 2> 2192173 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion
14
[junit4] 2> 2192173 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2192173 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 14, lastZnodeVersion 14
[junit4] 2> 2192185 INFO (simCloudManagerPool-7885-thread-3) [ ]
o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 2425092907
sessionWrapper.createTime 2425092885520217, this.sessionWrapper.createTime
2425092885520217
[junit4] 2> 2192193 DEBUG (ScheduledTrigger-7886-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192197 INFO
(TEST-TestPolicyCloud.testMetricsTag-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testMetricsTag
[junit4] 2> 2192197 INFO
(TEST-TestPolicyCloud.testMetricsTag-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase
[junit4] 2>
[junit4] 2> 2192197 INFO
(TEST-TestPolicyCloud.testMetricsTag-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase #############################################
[junit4] 2> 2192197 INFO
(TEST-TestPolicyCloud.testMetricsTag-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ############ FINAL CLUSTER STATS ############
[junit4] 2> 2192197 INFO
(TEST-TestPolicyCloud.testMetricsTag-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase #############################################
[junit4] 2>
[junit4] 2> 2192197 INFO
(TEST-TestPolicyCloud.testMetricsTag-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## Live nodes: 5
[junit4] 2> 2192197 INFO
(TEST-TestPolicyCloud.testMetricsTag-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## Empty nodes: 4
[junit4] 2> 2192197 INFO
(TEST-TestPolicyCloud.testMetricsTag-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## Dead nodes: 0
[junit4] 2> 2192197 INFO
(TEST-TestPolicyCloud.testMetricsTag-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## Collections: [metricsTest]
[junit4] 2> 2192197 INFO
(TEST-TestPolicyCloud.testMetricsTag-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## Max replicas per node: 1
[junit4] 2> 2192197 INFO
(TEST-TestPolicyCloud.testMetricsTag-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## Min replicas per node: 0
[junit4] 2> 2192197 INFO
(TEST-TestPolicyCloud.testMetricsTag-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## Total replicas: 1
[junit4] 2> 2192197 INFO
(TEST-TestPolicyCloud.testMetricsTag-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## * metricsTest 1
[junit4] 2> 2192197 INFO
(TEST-TestPolicyCloud.testMetricsTag-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## - active 1
[junit4] 2> 2192197 INFO
(TEST-TestPolicyCloud.testMetricsTag-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ######### Final Solr op counts ##########
[junit4] 2> 2192197 INFO
(TEST-TestPolicyCloud.testMetricsTag-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## - CREATE 1
[junit4] 2> 2192197 INFO
(TEST-TestPolicyCloud.testMetricsTag-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ## - autoscaling 2
[junit4] 2> 2192197 INFO
(TEST-TestPolicyCloud.testMetricsTag-seed#[F4A7D64BE8079D4D]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase ######### Autoscaling event counts ###########
[junit4] 2> 2192199 INFO
(TEST-TestPolicyCloud.testDataProviderPerReplicaDetails-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testDataProviderPerReplicaDetails
[junit4] 2> 2192199 DEBUG
(TEST-TestPolicyCloud.testDataProviderPerReplicaDetails-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 15
[junit4] 2> 2192199 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion
15
[junit4] 2> 2192199 INFO
(TEST-TestPolicyCloud.testDataProviderPerReplicaDetails-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and
clearing object cache...
[junit4] 2> 2192199 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 2192199 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2192199 DEBUG
(TEST-TestPolicyCloud.testDataProviderPerReplicaDetails-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed
explicitly
[junit4] 2> 2192199 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:1247)
~[?:?]
[junit4] 2> at
java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:317)
~[?:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:165)
[java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:844) [?:?]
[junit4] 2> 2192200 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 2192200 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 16
[junit4] 2> 2192200 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10010_solr,
127.0.0.1:10009_solr, 127.0.0.1:10013_solr, 127.0.0.1:10012_solr,
127.0.0.1:10011_solr]
[junit4] 2> 2192200 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 16, lastZnodeVersion -1
[junit4] 2> 2192200 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion
16
[junit4] 2> 2192204 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2192204 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 16, lastZnodeVersion 16
[junit4] 2> 2192204 DEBUG (ScheduledTrigger-7889-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192224 DEBUG (ScheduledTrigger-7889-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192244 DEBUG (ScheduledTrigger-7889-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192264 DEBUG (ScheduledTrigger-7889-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192284 DEBUG (ScheduledTrigger-7889-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192305 DEBUG (ScheduledTrigger-7889-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192325 DEBUG (ScheduledTrigger-7889-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192345 DEBUG (ScheduledTrigger-7889-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192365 DEBUG (ScheduledTrigger-7889-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192385 DEBUG (ScheduledTrigger-7889-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192405 DEBUG (ScheduledTrigger-7889-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192426 DEBUG (ScheduledTrigger-7889-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192446 DEBUG (ScheduledTrigger-7889-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192464 INFO
(TEST-TestPolicyCloud.testDataProviderPerReplicaDetails-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testDataProviderPerReplicaDetails
[junit4] 2> 2192464 INFO
(TEST-TestPolicyCloud.testDataProviderPerReplicaDetails-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase
[junit4] 2>
[junit4] 2> 2192464 INFO
(TEST-TestPolicyCloud.testDataProviderPerReplicaDetails-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase
#############################################
[junit4] 2> 2192464 INFO
(TEST-TestPolicyCloud.testDataProviderPerReplicaDetails-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ############ FINAL CLUSTER STATS
############
[junit4] 2> 2192464 INFO
(TEST-TestPolicyCloud.testDataProviderPerReplicaDetails-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase
#############################################
[junit4] 2>
[junit4] 2> 2192464 INFO
(TEST-TestPolicyCloud.testDataProviderPerReplicaDetails-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Live nodes: 5
[junit4] 2> 2192464 INFO
(TEST-TestPolicyCloud.testDataProviderPerReplicaDetails-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Empty nodes: 5
[junit4] 2> 2192464 INFO
(TEST-TestPolicyCloud.testDataProviderPerReplicaDetails-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Dead nodes: 0
[junit4] 2> 2192464 INFO
(TEST-TestPolicyCloud.testDataProviderPerReplicaDetails-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Collections: []
[junit4] 2> 2192464 INFO
(TEST-TestPolicyCloud.testDataProviderPerReplicaDetails-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Max replicas per node: 0
[junit4] 2> 2192464 INFO
(TEST-TestPolicyCloud.testDataProviderPerReplicaDetails-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Min replicas per node: 0
[junit4] 2> 2192464 INFO
(TEST-TestPolicyCloud.testDataProviderPerReplicaDetails-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Total replicas: 0
[junit4] 2> 2192464 INFO
(TEST-TestPolicyCloud.testDataProviderPerReplicaDetails-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ######### Final Solr op counts
##########
[junit4] 2> 2192464 INFO
(TEST-TestPolicyCloud.testDataProviderPerReplicaDetails-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## - CREATE 1
[junit4] 2> 2192464 INFO
(TEST-TestPolicyCloud.testDataProviderPerReplicaDetails-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## - DELETE 1
[junit4] 2> 2192464 INFO
(TEST-TestPolicyCloud.testDataProviderPerReplicaDetails-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ######### Autoscaling event counts
###########
[junit4] 2> 2192466 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.SolrTestCaseJ4 ###Starting
testCreateCollectionAddShardWithReplicaTypeUsingPolicy
[junit4] 2> 2192466 DEBUG
(TEST-TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 17
[junit4] 2> 2192466 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion
17
[junit4] 2> 2192466 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and
clearing object cache...
[junit4] 2> 2192466 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 2192466 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2192466 DEBUG
(TEST-TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed
explicitly
[junit4] 2> 2192466 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:1247)
~[?:?]
[junit4] 2> at
java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:317)
~[?:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:165)
[java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:844) [?:?]
[junit4] 2> 2192466 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 2192466 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 18
[junit4] 2> 2192467 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10010_solr,
127.0.0.1:10009_solr, 127.0.0.1:10013_solr, 127.0.0.1:10012_solr,
127.0.0.1:10011_solr]
[junit4] 2> 2192467 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 18, lastZnodeVersion -1
[junit4] 2> 2192467 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion
18
[junit4] 2> 2192467 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2192467 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 18, lastZnodeVersion 18
[junit4] 2> 2192467 DEBUG (ScheduledTrigger-7892-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192487 DEBUG (ScheduledTrigger-7892-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192507 DEBUG (ScheduledTrigger-7892-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192528 DEBUG (ScheduledTrigger-7892-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192548 DEBUG (ScheduledTrigger-7892-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192566 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.TestPolicyCloud NRT 127.0.0.1:10010_solr PULL
127.0.0.1:10009_solr , TLOG 127.0.0.1:10009_solr
[junit4] 2> 2192567 DEBUG (simCloudManagerPool-7891-thread-1) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2192567 DEBUG (simCloudManagerPool-7891-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
19
[junit4] 2> 2192568 DEBUG (simCloudManagerPool-7891-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:10010_solr,
127.0.0.1:10009_solr, 127.0.0.1:10013_solr, 127.0.0.1:10012_solr,
127.0.0.1:10011_solr]
[junit4] 2> 2192568 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion
19
[junit4] 2> 2192568 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2192568 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 19, lastZnodeVersion 19
[junit4] 2> 2192568 DEBUG (ScheduledTrigger-7892-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192580 INFO (simCloudManagerPool-7891-thread-2) [ ]
o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 2425112650
sessionWrapper.createTime 2425112598943767, this.sessionWrapper.createTime
2425112598943767
[junit4] 2> 2192588 DEBUG (ScheduledTrigger-7892-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192608 DEBUG (ScheduledTrigger-7892-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192628 DEBUG (ScheduledTrigger-7892-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192648 DEBUG (ScheduledTrigger-7892-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192669 DEBUG (ScheduledTrigger-7892-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192689 DEBUG (ScheduledTrigger-7892-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192709 DEBUG (ScheduledTrigger-7892-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192715 INFO (simCloudManagerPool-7891-thread-6) [ ]
o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 2425119406
sessionWrapper.createTime 2425119335055967, this.sessionWrapper.createTime
2425119335055967
[junit4] 2> 2192729 DEBUG (ScheduledTrigger-7892-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 2192747 DEBUG (simCloudManagerPool-7891-thread-7) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for policiesTest /
s3: {"core_node10":{
[junit4] 2> "core":"policiesTest_s3_replica_t2",
[junit4] 2> "leader":"true",
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2> "node_name":"127.0.0.1:10009_solr",
[junit4] 2> "base_url":"http://127.0.0.1:10009/solr";,
[junit4] 2> "state":"active",
[junit4] 2> "type":"TLOG",
[junit4] 2> "shard":"s3",
[junit4] 2> "collection":"policiesTest"}}
[junit4] 2> 2192747 DEBUG (simCloudManagerPool-7891-thread-8) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for policiesTest /
s1: {"core_node6":{
[junit4] 2> "node_name":"127.0.0.1:10010_solr",
[junit4] 2> "core":"policiesTest_s1_replica_n1",
[junit4] 2> "leader":"true",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2> "shard":"s1",
[junit4] 2> "collection":"policiesTest"}}
[junit4] 2> 2192747 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.SolrTestCaseJ4 ###Ending
testCreateCollectionAddShardWithReplicaTypeUsingPolicy
[junit4] 2> 2192747 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase
[junit4] 2>
[junit4] 2> 2192747 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase
#############################################
[junit4] 2> 2192747 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ############ FINAL CLUSTER STATS
############
[junit4] 2> 2192747 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase
#############################################
[junit4] 2>
[junit4] 2> 2192747 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Live nodes: 5
[junit4] 2> 2192747 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Empty nodes: 3
[junit4] 2> 2192747 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Dead nodes: 0
[junit4] 2> 2192747 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Collections: [policiesTest]
[junit4] 2> 2192747 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Max replicas per node: 4
[junit4] 2> 2192747 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Min replicas per node: 0
[junit4] 2> 2192747 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## Total replicas: 6
[junit4] 2> 2192747 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## * policiesTest 6
[junit4] 2> 2192747 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## - active 6
[junit4] 2> 2192747 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ######### Final Solr op counts
##########
[junit4] 2> 2192747 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## - CREATE 1
[junit4] 2> 2192747 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## - CREATESHARD 1
[junit4] 2> 2192747 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ## - autoscaling 1
[junit4] 2> 2192747 INFO
(TEST-TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[F4A7D64BE8079D4D])
[ ] o.a.s.c.a.s.SimSolrCloudTestCase ######### Autoscaling event counts
###########
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestPolicyCloud
-Dtests.method=testCreateCollectionAddShardWithReplicaTypeUsingPolicy
-Dtests.seed=F4A7D64BE8079D4D -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=mfe -Dtests.timezone=Indian/Chagos -Dtests.asserts=true
-Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.28s J1 |
TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy <<<
[junit4] > Throwable #1: java.lang.NullPointerException
[junit4] > at
__randomizedtesting.SeedInfo.seed([F4A7D64BE8079D4D:64F5C3D8E5CB5CB1]:0)
[junit4] > at
org.apache.solr.cloud.autoscaling.sim.SimClusterStateProvider.lambda$getCollectionStates$52(SimClusterStateProvider.java:1236)
[junit4] > at
java.base/java.util.ArrayList.forEach(ArrayList.java:1380)
[junit4] > at
org.apache.solr.cloud.autoscaling.sim.SimClusterStateProvider.lambda$getCollectionStates$53(SimClusterStateProvider.java:1228)
[junit4] > at
java.base/java.util.concurrent.ConcurrentHashMap.forEach(ConcurrentHashMap.java:1617)
[junit4] > at
org.apache.solr.cloud.autoscaling.sim.SimClusterStateProvider.getCollectionStates(SimClusterStateProvider.java:1227)
[junit4] > at
org.apache.solr.cloud.autoscaling.sim.SimClusterStateProvider.getClusterState(SimClusterStateProvider.java:1209)
[junit4] > at
org.apache.solr.cloud.autoscaling.sim.SimSolrCloudTestCase.getCollectionState(SimSolrCloudTestCase.java:190)
[junit4] > at
org.apache.solr.cloud.autoscaling.sim.TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy(TestPolicyCloud.java:286)
[junit4] > at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at
java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 2192749 WARN (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are
closed, exiting.
[junit4] 2> 2192749 DEBUG
(SUITE-TestPolicyCloud-seed#[F4A7D64BE8079D4D]-worker) [ ]
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.sim.TestPolicyCloud_F4A7D64BE8079D4D-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=319, maxMBSortInHeap=6.039791143695715,
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@2f68ed29),
locale=mfe, timezone=Indian/Chagos
[junit4] 2> NOTE: Linux 4.13.0-36-generic amd64/Oracle Corporation 9.0.4
(64-bit)/cpus=8,threads=1,free=380691848,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestUseDocValuesAsStored2,
TestUninvertingReader, RegexBytesRefFilterTest, MergeStrategyTest,
TestFastLRUCache, TestMaxTokenLenTokenizer, TestNumericTokenStream,
OverseerTaskQueueTest, TestCursorMarkWithoutUniqueKey, ZkShardTermsTest,
OverseerModifyCollectionTest, BadComponentTest, HLLSerializationTest,
FastVectorHighlighterTest, TestRuleBasedAuthorizationPlugin, QueryEqualityTest,
TestMacros, AssignBackwardCompatibilityTest, ActionThrottleTest, TestNRTOpen,
ReplicaListTransformerTest, UpdateLogTest, TestConfigReload, SliceStateTest,
TestNodeAddedTrigger, TestQueryWrapperFilter, HttpPartitionTest,
CdcrBidirectionalTest, PrimitiveFieldTypeTest, BlockCacheTest,
TestCloudManagedSchema, TestReload, TriLevelCompositeIdRoutingTest,
TestManagedSchemaThreadSafety, TestEmbeddedSolrServerSchemaAPI,
TestExclusionRuleCollectionAccess, V2StandaloneTest, TestSearchPerf,
DocumentBuilderTest, SolrMetricReporterTest, PrimUtilsTest, DateRangeFieldTest,
ScriptEngineTest, TestSolrIndexConfig, MissingSegmentRecoveryTest,
TestSolr4Spatial, DistributedExpandComponentTest, TestClassicSimilarityFactory,
TestXIncludeConfig, TestAuthenticationFramework,
TestCollectionsAPIViaSolrCloudCluster, LeaderVoteWaitTimeoutTest,
TestApiFramework, SOLR749Test, TestLeaderInitiatedRecoveryThread,
HighlighterTest, TestSweetSpotSimilarityFactory,
ChaosMonkeySafeLeaderWithPullReplicasTest, ManagedSchemaRoundRobinCloudTest,
LeaderElectionContextKeyTest, TestReRankQParserPlugin, TestSolr4Spatial2,
TestSimpleTextCodec, DOMUtilTest, DeleteReplicaTest, TestSolrJ,
TestDistributedGrouping, BasicFunctionalityTest,
DistributedFacetPivotWhiteBoxTest, CollectionTooManyReplicasTest,
BJQParserTest, TestTrackingShardHandlerFactory, TestRTGBase, TestMissingGroups,
ChaosMonkeySafeLeaderTest, WrapperMergePolicyFactoryTest, TestIntervalFaceting,
TestSolrConfigHandlerConcurrent, TestReplicationHandlerBackup, ForceLeaderTest,
FieldMutatingUpdateProcessorTest, DistributedFacetPivotLongTailTest,
TestAddFieldRealTimeGet, TestCloudPseudoReturnFields, SystemInfoHandlerTest,
TestComplexPhraseQParserPlugin, TermsComponentTest, DeleteInactiveReplicaTest,
ShardSplitTest, TestOverriddenPrefixQueryForCustomFieldType,
TestRebalanceLeaders, TestExecutePlanAction, BasicZkTest,
TestConfigSetsAPIZkFailure, TestQueryUtils, XsltUpdateRequestHandlerTest,
SchemaVersionSpecificBehaviorTest, DistribCursorPagingTest, CdcrUpdateLogTest,
TestRecovery, ZkControllerTest, SolrSlf4jReporterTest,
PKIAuthenticationIntegrationTest, TestNestedDocsSort, TestDynamicFieldResource,
TestCloudInspectUtil, QueryParsingTest, TestDistribDocBasedVersion,
SoftAutoCommitTest, TestImpersonationWithHadoopAuth,
TestBackupRepositoryFactory, TestMinMaxOnMultiValuedField,
TestSurroundQueryParser, ResourceLoaderTest, ShardRoutingCustomTest,
TestGeoJSONResponseWriter, AtomicUpdateProcessorFactoryTest,
TestExpandComponent, TestSearcherReuse, TestLuceneIndexBackCompat,
TestPerFieldSimilarityWithDefaultOverride, DistributedIntervalFacetingTest,
TestSimpleTrackingShardHandler, HttpSolrCallGetCoreTest, TestZkChroot,
TestSegmentSorting, DocExpirationUpdateProcessorFactoryTest,
ConfigureRecoveryStrategyTest, SecurityConfHandlerTest, TestSolrCloudSnapshots,
TestPushWriter, BasicDistributedZk2Test, OverseerTest,
FullSolrCloudDistribCmdsTest, ClusterStateUpdateTest, TestRandomFaceting,
LeaderElectionTest, DistributedSpellCheckComponentTest, TestStressReorder,
TestJoin, SpellCheckComponentTest, ConvertedLegacyTest, TestSort,
TestFiltering, WordBreakSolrSpellCheckerTest, TestUpdate, SortByFunctionTest,
CacheHeaderTest, DisMaxRequestHandlerTest, TestReversedWildcardFilterFactory,
DocumentAnalysisRequestHandlerTest, TestValueSourceCache,
PathHierarchyTokenizerFactoryTest, FieldAnalysisRequestHandlerTest,
SolrPluginUtilsTest, TestCollationField, UpdateRequestProcessorFactoryTest,
UniqFieldsUpdateProcessorFactoryTest, JsonLoaderTest,
TestPartialUpdateDeduplication, TestPHPSerializedResponseWriter,
CSVRequestHandlerTest, SearchHandlerTest, TestLFUCache, TestSystemIdResolver,
SpellingQueryConverterTest, RAMDirectoryFactoryTest, TestLRUCache, TestUtils,
TestDocumentBuilder, ZkNodePropsTest, UUIDFieldTest, FileUtilsTest,
DistributedMLTComponentTest, CursorPagingTest, TestTolerantSearch,
TokenizerChainTest, TestEmbeddedSolrServerAdminHandler, TestJettySolrRunner,
ChaosMonkeyNothingIsSafeWithPullReplicasTest, ConcurrentCreateRoutedAliasTest,
ConfigSetsAPITest, ConnectionManagerTest, CreateCollectionCleanupTest,
DeleteLastCustomShardedReplicaTest, DistribDocExpirationUpdateProcessorTest,
LegacyCloudClusterPropTest, MigrateRouteKeyTest, MoveReplicaTest,
RemoteQueryErrorTest, ReplaceNodeNoTargetTest, ReplaceNodeTest,
ReplicationFactorTest, SSLMigrationTest, SaslZkACLProviderTest,
SolrCLIZkUtilsTest, TestCloudConsistency, TestCloudSearcherWarming,
TestMiniSolrCloudClusterSSL, TestPullReplica, TestRandomFlRTGCloud,
TestSolrCloudWithSecureImpersonation, TestStressCloudBlindAtomicUpdates,
TestTolerantUpdateProcessorRandomCloud, TestCollectionAPI,
TestLocalFSCloudBackupRestore, TestReplicaProperties, ExecutePlanActionTest,
ScheduledMaintenanceTriggerTest, ScheduledTriggerTest, SystemLogListenerTest,
TestPolicyCloud, TestClusterStateProvider, TestDistribStateManager,
TestPolicyCloud]
[junit4] Completed [601/786 (1!)] on J1 in 1.63s, 7 tests, 1 error <<<
FAILURES!
[...truncated 43292 lines...]
[repro] Jenkins log URL:
https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/21710/consoleText
[repro] Revision: 08686038e1378aca09efdc6b6657c065713fe8f2
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC"
[repro] Repro line: ant test -Dtestcase=TestPolicyCloud
-Dtests.method=testCreateCollectionAddShardWithReplicaTypeUsingPolicy
-Dtests.seed=F4A7D64BE8079D4D -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=mfe -Dtests.timezone=Indian/Chagos -Dtests.asserts=true
-Dtests.file.encoding=ISO-8859-1
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] TestPolicyCloud
[repro] ant compile-test
[...truncated 3301 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5
-Dtests.class="*.TestPolicyCloud" -Dtests.showOutput=onerror
"-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC" -Dtests.seed=F4A7D64BE8079D4D
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=mfe
-Dtests.timezone=Indian/Chagos -Dtests.asserts=true
-Dtests.file.encoding=ISO-8859-1
[...truncated 157 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.cloud.autoscaling.TestPolicyCloud
[repro] 0/5 failed: org.apache.solr.cloud.autoscaling.sim.TestPolicyCloud
[repro] Exiting with code 0
[...truncated 40 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
