You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2018/09/25 00:11:28 UTC
[JENKINS] Lucene-Solr-7.x-Solaris (64bit/jdk1.8.0) - Build # 827 -
Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/827/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.sim.TestSimPolicyCloud.testCreateCollectionAddReplica
Error Message:
Timeout waiting for collection to become active Live Nodes: [127.0.0.1:10006_solr, 127.0.0.1:10010_solr, 127.0.0.1:10008_solr, 127.0.0.1:10009_solr, 127.0.0.1:10007_solr] Last available state: DocCollection(testCreateCollectionAddReplica//clusterstate.json/23)={ "replicationFactor":"1", "pullReplicas":"0", "router":{"name":"compositeId"}, "maxShardsPerNode":"1", "autoAddReplicas":"false", "nrtReplicas":"1", "tlogReplicas":"0", "autoCreated":"true", "policy":"c1", "shards":{"shard1":{ "replicas":{"core_node1":{ "core":"testCreateCollectionAddReplica_shard1_replica_n1", "SEARCHER.searcher.maxDoc":0, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":10240, "node_name":"127.0.0.1:10010_solr", "state":"active", "type":"NRT", "INDEX.sizeInGB":9.5367431640625E-6, "SEARCHER.searcher.numDocs":0}}, "range":"80000000-7fffffff", "state":"active"}}}
Stack Trace:
java.lang.AssertionError: Timeout waiting for collection to become active
Live Nodes: [127.0.0.1:10006_solr, 127.0.0.1:10010_solr, 127.0.0.1:10008_solr, 127.0.0.1:10009_solr, 127.0.0.1:10007_solr]
Last available state: DocCollection(testCreateCollectionAddReplica//clusterstate.json/23)={
"replicationFactor":"1",
"pullReplicas":"0",
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"1",
"tlogReplicas":"0",
"autoCreated":"true",
"policy":"c1",
"shards":{"shard1":{
"replicas":{"core_node1":{
"core":"testCreateCollectionAddReplica_shard1_replica_n1",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10010_solr",
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0}},
"range":"80000000-7fffffff",
"state":"active"}}}
at __randomizedtesting.SeedInfo.seed([4708F66C80555458:C72893429116BCFE]:0)
at org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:70)
at org.apache.solr.cloud.autoscaling.sim.TestSimPolicyCloud.testCreateCollectionAddReplica(TestSimPolicyCloud.java:123)
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.TimeoutException: last state: DocCollection(testCreateCollectionAddReplica//clusterstate.json/23)={
"replicationFactor":"1",
"pullReplicas":"0",
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"1",
"tlogReplicas":"0",
"autoCreated":"true",
"policy":"c1",
"shards":{"shard1":{
"replicas":{"core_node1":{
"core":"testCreateCollectionAddReplica_shard1_replica_n1",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10010_solr",
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0}},
"range":"80000000-7fffffff",
"state":"active"}}}
at org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:112)
at org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:64)
... 41 more
Build Log:
[...truncated 14289 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.sim.TestSimPolicyCloud
[junit4] 2> 3419124 INFO (SUITE-TestSimPolicyCloud-seed#[4708F66C80555458]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.sim.TestSimPolicyCloud_4708F66C80555458-001/init-core-data-001
[junit4] 2> 3419125 INFO (SUITE-TestSimPolicyCloud-seed#[4708F66C80555458]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 3419125 INFO (SUITE-TestSimPolicyCloud-seed#[4708F66C80555458]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 3419128 DEBUG (SUITE-TestSimPolicyCloud-seed#[4708F66C80555458]-worker) [ ] o.a.s.c.a.s.SimClusterStateProvider --- new Overseer leader: 127.0.0.1:10006_solr
[junit4] 2> 3419129 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 3419129 INFO (SUITE-TestSimPolicyCloud-seed#[4708F66C80555458]-worker) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 3419129 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 0
[junit4] 2> 3419129 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion -1
[junit4] 2> 3419129 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 0
[junit4] 2> 3419129 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:10006_solr, 127.0.0.1:10010_solr, 127.0.0.1:10009_solr, 127.0.0.1:10008_solr, 127.0.0.1:10007_solr]
[junit4] 2> 3419129 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 3419129 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion 0
[junit4] 2> 3419129 DEBUG (ScheduledTrigger-11880-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419161 DEBUG (ScheduledTrigger-11880-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419165 INFO (TEST-TestSimPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[4708F66C80555458]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateCollectionAddShardUsingPolicy
[junit4] 2> 3419165 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[4708F66C80555458]) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 3419166 INFO (TEST-TestSimPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[4708F66C80555458]) [ ] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and clearing object cache...
[junit4] 2> 3419166 WARN (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 3419173 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 3419173 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 3419173 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 3419173 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 3419173 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 3419173 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[4708F66C80555458]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 3419173 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 3419173 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 3419174 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion -1
[junit4] 2> 3419174 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 3419174 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:10006_solr, 127.0.0.1:10010_solr, 127.0.0.1:10009_solr, 127.0.0.1:10008_solr, 127.0.0.1:10007_solr]
[junit4] 2> 3419174 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 3419174 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 3419174 DEBUG (ScheduledTrigger-11885-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419201 DEBUG (ScheduledTrigger-11885-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419223 DEBUG (ScheduledTrigger-11885-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419243 DEBUG (ScheduledTrigger-11885-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419263 DEBUG (ScheduledTrigger-11885-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419280 DEBUG (simCloudManagerPool-11884-thread-1) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 3419281 DEBUG (simCloudManagerPool-11884-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 3419284 DEBUG (ScheduledTrigger-11885-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419284 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 3419284 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 3419284 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 3419299 INFO (simCloudManagerPool-11884-thread-2) [ ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 43412301 sessionWrapper.createTime 43412258069334, this.sessionWrapper.createTime 43412258069334
[junit4] 2> 3419304 DEBUG (ScheduledTrigger-11885-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419316 DEBUG (simCloudManagerPool-11884-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider -- submit leader election for policiesTest / s1
[junit4] 2> 3419316 DEBUG (simCloudManagerPool-11884-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider -- no replicas in policiesTest / s2
[junit4] 2> 3419317 DEBUG (simCloudManagerPool-11884-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider Running leader election for policiesTest / s1
[junit4] 2> 3419322 DEBUG (simCloudManagerPool-11884-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for policiesTest / s1: core_node1
[junit4] 2> 3419325 DEBUG (ScheduledTrigger-11885-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419328 DEBUG (simCloudManagerPool-11884-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider -- already has leader policiesTest / s1
[junit4] 2> 3419328 DEBUG (simCloudManagerPool-11884-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider -- submit leader election for policiesTest / s2
[junit4] 2> 3419329 DEBUG (simCloudManagerPool-11884-thread-6) [ ] o.a.s.c.a.s.SimClusterStateProvider Running leader election for policiesTest / s2
[junit4] 2> 3419333 DEBUG (simCloudManagerPool-11884-thread-6) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for policiesTest / s2: core_node2
[junit4] 2> 3419345 DEBUG (ScheduledTrigger-11885-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419365 DEBUG (ScheduledTrigger-11885-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419385 DEBUG (ScheduledTrigger-11885-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419405 DEBUG (ScheduledTrigger-11885-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419429 DEBUG (ScheduledTrigger-11885-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419438 INFO (simCloudManagerPool-11884-thread-7) [ ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 43419242 sessionWrapper.createTime 43419220262284, this.sessionWrapper.createTime 43419220262284
[junit4] 2> 3419449 DEBUG (simCloudManagerPool-11884-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider -- submit leader election for policiesTest / s3
[junit4] 2> 3419449 DEBUG (simCloudManagerPool-11884-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider -- already has leader policiesTest / s1
[junit4] 2> 3419449 DEBUG (simCloudManagerPool-11884-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider -- already has leader policiesTest / s2
[junit4] 2> 3419450 DEBUG (simCloudManagerPool-11884-thread-8) [ ] o.a.s.c.a.s.SimClusterStateProvider Running leader election for policiesTest / s3
[junit4] 2> 3419450 DEBUG (ScheduledTrigger-11885-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419454 DEBUG (simCloudManagerPool-11884-thread-8) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for policiesTest / s3: core_node3
[junit4] 2> 3419454 INFO (TEST-TestSimPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[4708F66C80555458]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCreateCollectionAddShardUsingPolicy
[junit4] 2> 3419457 INFO (TEST-TestSimPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[4708F66C80555458]) [ ] 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: [policiesTest]
[junit4] 2> ## Max replicas per node: 3
[junit4] 2> ## Min replicas per node: 0
[junit4] 2> ## Total replicas: 3
[junit4] 2> ## * policiesTest 3
[junit4] 2> ## - active 3
[junit4] 2> ######### Solr op counts ##########
[junit4] 2> ## - CREATE 1
[junit4] 2> ## - CREATESHARD 1
[junit4] 2> ## - autoscaling 1
[junit4] 2> ######### Autoscaling event counts ###########
[junit4] 2>
[junit4] 2> 3419472 DEBUG (ScheduledTrigger-11885-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419492 DEBUG (ScheduledTrigger-11885-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419513 DEBUG (ScheduledTrigger-11885-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419520 INFO (TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[4708F66C80555458]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateCollectionSplitShard
[junit4] 2> 3419520 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[4708F66C80555458]) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 4
[junit4] 2> 3419520 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4
[junit4] 2> 3419520 INFO (TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[4708F66C80555458]) [ ] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and clearing object cache...
[junit4] 2> 3419520 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 3419520 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 3419520 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 3419525 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 3419525 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 3419525 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 3419525 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> 3419525 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 3419525 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[4708F66C80555458]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 3419526 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 3419526 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 5
[junit4] 2> 3419526 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVersion -1
[junit4] 2> 3419526 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 5
[junit4] 2> 3419526 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:10006_solr, 127.0.0.1:10010_solr, 127.0.0.1:10009_solr, 127.0.0.1:10008_solr, 127.0.0.1:10007_solr]
[junit4] 2> 3419526 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 3419526 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVersion 5
[junit4] 2> 3419527 DEBUG (ScheduledTrigger-11888-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419554 DEBUG (ScheduledTrigger-11888-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419577 DEBUG (ScheduledTrigger-11888-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419597 DEBUG (ScheduledTrigger-11888-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419617 DEBUG (ScheduledTrigger-11888-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419627 DEBUG (simCloudManagerPool-11887-thread-1) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 3419627 DEBUG (simCloudManagerPool-11887-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 6
[junit4] 2> 3419630 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 6
[junit4] 2> 3419630 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 3419630 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6, lastZnodeVersion 6
[junit4] 2> 3419638 DEBUG (ScheduledTrigger-11888-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419642 INFO (simCloudManagerPool-11887-thread-2) [ ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 43429478 sessionWrapper.createTime 43429443620384, this.sessionWrapper.createTime 43429443620384
[junit4] 2> 3419653 DEBUG (simCloudManagerPool-11887-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider -- submit leader election for testCreateCollectionSplitShard / shard1
[junit4] 2> 3419654 DEBUG (simCloudManagerPool-11887-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider Running leader election for testCreateCollectionSplitShard / shard1
[junit4] 2> 3419657 DEBUG (simCloudManagerPool-11887-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testCreateCollectionSplitShard / shard1: core_node1
[junit4] 2> 3419658 DEBUG (ScheduledTrigger-11888-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419664 DEBUG (simCloudManagerPool-11887-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider -- already has leader testCreateCollectionSplitShard / shard1
[junit4] 2> 3419704 DEBUG (ScheduledTrigger-11888-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419724 DEBUG (ScheduledTrigger-11888-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419744 DEBUG (ScheduledTrigger-11888-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419765 DEBUG (ScheduledTrigger-11888-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419771 INFO (simCloudManagerPool-11887-thread-6) [ ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 43435904 sessionWrapper.createTime 43435836038584, this.sessionWrapper.createTime 43435836038584
[junit4] 2> 3419785 DEBUG (ScheduledTrigger-11888-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419805 DEBUG (ScheduledTrigger-11888-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419817 DEBUG (simCloudManagerPool-11887-thread-6) [ ] o.a.s.c.a.s.SimClusterStateProvider -- already has leader testCreateCollectionSplitShard / shard1
[junit4] 2> 3419817 DEBUG (simCloudManagerPool-11887-thread-6) [ ] o.a.s.c.a.s.SimClusterStateProvider -- submit leader election for testCreateCollectionSplitShard / shard1_1
[junit4] 2> 3419817 DEBUG (simCloudManagerPool-11887-thread-6) [ ] o.a.s.c.a.s.SimClusterStateProvider -- submit leader election for testCreateCollectionSplitShard / shard1_0
[junit4] 2> 3419817 DEBUG (simCloudManagerPool-11887-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider Running leader election for testCreateCollectionSplitShard / shard1_1
[junit4] 2> 3419824 DEBUG (simCloudManagerPool-11887-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testCreateCollectionSplitShard / shard1_1: core_node7
[junit4] 2> 3419825 DEBUG (simCloudManagerPool-11887-thread-8) [ ] o.a.s.c.a.s.SimClusterStateProvider Running leader election for testCreateCollectionSplitShard / shard1_0
[junit4] 2> 3419829 DEBUG (ScheduledTrigger-11888-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419833 DEBUG (simCloudManagerPool-11887-thread-8) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testCreateCollectionSplitShard / shard1_0: core_node6
[junit4] 2> 3419849 DEBUG (ScheduledTrigger-11888-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419869 DEBUG (ScheduledTrigger-11888-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419889 DEBUG (ScheduledTrigger-11888-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419909 DEBUG (ScheduledTrigger-11888-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419930 DEBUG (ScheduledTrigger-11888-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419935 INFO (TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[4708F66C80555458]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCreateCollectionSplitShard
[junit4] 2> 3419935 INFO (TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[4708F66C80555458]) [ ] 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> ## - SPLITSHARD 1
[junit4] 2> ## - autoscaling 1
[junit4] 2> ######### Autoscaling event counts ###########
[junit4] 2>
[junit4] 2> 3419950 DEBUG (ScheduledTrigger-11888-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419972 DEBUG (ScheduledTrigger-11888-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3419995 DEBUG (ScheduledTrigger-11888-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420019 DEBUG (ScheduledTrigger-11888-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420019 INFO (TEST-TestSimPolicyCloud.testDataProvider-seed#[4708F66C80555458]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDataProvider
[junit4] 2> 3420019 DEBUG (TEST-TestSimPolicyCloud.testDataProvider-seed#[4708F66C80555458]) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 7
[junit4] 2> 3420019 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 7
[junit4] 2> 3420019 INFO (TEST-TestSimPolicyCloud.testDataProvider-seed#[4708F66C80555458]) [ ] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and clearing object cache...
[junit4] 2> 3420019 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 3420023 DEBUG (TEST-TestSimPolicyCloud.testDataProvider-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 3420023 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 3420023 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> 3420024 DEBUG (TEST-TestSimPolicyCloud.testDataProvider-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 3420024 DEBUG (TEST-TestSimPolicyCloud.testDataProvider-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 3420024 DEBUG (TEST-TestSimPolicyCloud.testDataProvider-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 3420024 DEBUG (TEST-TestSimPolicyCloud.testDataProvider-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 3420024 DEBUG (TEST-TestSimPolicyCloud.testDataProvider-seed#[4708F66C80555458]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 3420026 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 3420026 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 8
[junit4] 2> 3420027 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 8, lastZnodeVersion -1
[junit4] 2> 3420027 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 8
[junit4] 2> 3420027 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:10006_solr, 127.0.0.1:10010_solr, 127.0.0.1:10009_solr, 127.0.0.1:10008_solr, 127.0.0.1:10007_solr]
[junit4] 2> 3420027 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 3420027 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 8, lastZnodeVersion 8
[junit4] 2> 3420027 DEBUG (ScheduledTrigger-11891-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420047 DEBUG (ScheduledTrigger-11891-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420075 DEBUG (ScheduledTrigger-11891-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420095 DEBUG (ScheduledTrigger-11891-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420115 DEBUG (ScheduledTrigger-11891-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420137 DEBUG (ScheduledTrigger-11891-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420150 DEBUG (simCloudManagerPool-11890-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider -- submit leader election for policiesTest / shard1
[junit4] 2> 3420150 DEBUG (simCloudManagerPool-11890-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider Running leader election for policiesTest / shard1
[junit4] 2> 3420154 DEBUG (simCloudManagerPool-11890-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for policiesTest / shard1: core_node4
[junit4] 2> 3420157 DEBUG (ScheduledTrigger-11891-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420161 DEBUG (simCloudManagerPool-11890-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider -- already has leader policiesTest / shard1
[junit4] 2> 3420177 DEBUG (ScheduledTrigger-11891-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420197 DEBUG (ScheduledTrigger-11891-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420198 INFO (TEST-TestSimPolicyCloud.testDataProvider-seed#[4708F66C80555458]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDataProvider
[junit4] 2> 3420198 INFO (TEST-TestSimPolicyCloud.testDataProvider-seed#[4708F66C80555458]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase #######################################
[junit4] 2> ############ CLUSTER STATE ############
[junit4] 2> #######################################
[junit4] 2> ## Live nodes: 5
[junit4] 2> ## Empty nodes: 3
[junit4] 2> ## Dead nodes: 0
[junit4] 2> ## Collections: [policiesTest]
[junit4] 2> ## Max replicas per node: 1
[junit4] 2> ## Min replicas per node: 0
[junit4] 2> ## Total replicas: 2
[junit4] 2> ## * policiesTest 2
[junit4] 2> ## - active 2
[junit4] 2> ######### Solr op counts ##########
[junit4] 2> ## - ADDROLE 1
[junit4] 2> ## - CREATE 1
[junit4] 2> ######### Autoscaling event counts ###########
[junit4] 2>
[junit4] 2> 3420217 DEBUG (ScheduledTrigger-11891-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420240 DEBUG (ScheduledTrigger-11891-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420246 INFO (TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[4708F66C80555458]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateCollectionAddReplica
[junit4] 2> 3420246 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[4708F66C80555458]) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 9
[junit4] 2> 3420247 INFO (TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[4708F66C80555458]) [ ] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and clearing object cache...
[junit4] 2> 3420247 WARN (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 3420253 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 3420253 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 3420253 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 3420253 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 3420254 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 3420254 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[4708F66C80555458]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 3420254 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 3420254 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 10
[junit4] 2> 3420254 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 10, lastZnodeVersion -1
[junit4] 2> 3420254 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 10
[junit4] 2> 3420254 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:10006_solr, 127.0.0.1:10010_solr, 127.0.0.1:10009_solr, 127.0.0.1:10008_solr, 127.0.0.1:10007_solr]
[junit4] 2> 3420255 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 3420255 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 10, lastZnodeVersion 10
[junit4] 2> 3420255 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420275 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420298 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420326 DEBUG (ScheduledTrigger-11894-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420349 DEBUG (ScheduledTrigger-11894-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420354 DEBUG (simCloudManagerPool-11893-thread-1) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 3420355 DEBUG (simCloudManagerPool-11893-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 11
[junit4] 2> 3420358 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 11
[junit4] 2> 3420358 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 3420358 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 11, lastZnodeVersion 11
[junit4] 2> 3420369 INFO (simCloudManagerPool-11893-thread-2) [ ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 43465841 sessionWrapper.createTime 43465821286334, this.sessionWrapper.createTime 43465821286334
[junit4] 2> 3420370 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420380 DEBUG (simCloudManagerPool-11893-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider -- submit leader election for testCreateCollectionAddReplica / shard1
[junit4] 2> 3420380 DEBUG (simCloudManagerPool-11893-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider Running leader election for testCreateCollectionAddReplica / shard1
[junit4] 2> 3420384 DEBUG (simCloudManagerPool-11893-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testCreateCollectionAddReplica / shard1: core_node1
[junit4] 2> 3420391 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420411 DEBUG (ScheduledTrigger-11894-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420432 DEBUG (ScheduledTrigger-11894-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420452 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420473 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420493 DEBUG (ScheduledTrigger-11894-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420513 DEBUG (ScheduledTrigger-11894-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420533 DEBUG (ScheduledTrigger-11894-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420553 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420574 DEBUG (ScheduledTrigger-11894-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420595 DEBUG (ScheduledTrigger-11894-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420615 DEBUG (ScheduledTrigger-11894-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420635 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420656 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420676 DEBUG (ScheduledTrigger-11894-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420696 DEBUG (ScheduledTrigger-11894-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420717 DEBUG (ScheduledTrigger-11894-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420737 DEBUG (ScheduledTrigger-11894-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420757 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420778 DEBUG (ScheduledTrigger-11894-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420798 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420818 DEBUG (ScheduledTrigger-11894-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420839 DEBUG (ScheduledTrigger-11894-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420859 DEBUG (ScheduledTrigger-11894-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420879 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420900 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420920 DEBUG (ScheduledTrigger-11894-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420940 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420961 DEBUG (ScheduledTrigger-11894-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3420982 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421002 DEBUG (ScheduledTrigger-11894-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421023 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421043 DEBUG (ScheduledTrigger-11894-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421063 DEBUG (ScheduledTrigger-11894-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421083 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421103 DEBUG (ScheduledTrigger-11894-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421124 DEBUG (ScheduledTrigger-11894-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421144 DEBUG (ScheduledTrigger-11894-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421164 DEBUG (ScheduledTrigger-11894-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421184 DEBUG (ScheduledTrigger-11894-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421204 DEBUG (ScheduledTrigger-11894-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421224 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421245 DEBUG (ScheduledTrigger-11894-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421265 DEBUG (ScheduledTrigger-11894-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421285 DEBUG (ScheduledTrigger-11894-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421305 DEBUG (ScheduledTrigger-11894-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421325 DEBUG (ScheduledTrigger-11894-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421346 DEBUG (ScheduledTrigger-11894-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421366 DEBUG (ScheduledTrigger-11894-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421388 DEBUG (ScheduledTrigger-11894-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421408 DEBUG (ScheduledTrigger-11894-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421428 DEBUG (ScheduledTrigger-11894-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421449 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421469 DEBUG (ScheduledTrigger-11894-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421489 DEBUG (ScheduledTrigger-11894-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421509 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421529 DEBUG (ScheduledTrigger-11894-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421549 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421570 DEBUG (ScheduledTrigger-11894-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421590 DEBUG (ScheduledTrigger-11894-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421610 DEBUG (ScheduledTrigger-11894-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421631 DEBUG (ScheduledTrigger-11894-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421651 DEBUG (ScheduledTrigger-11894-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421672 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421692 DEBUG (ScheduledTrigger-11894-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421712 DEBUG (ScheduledTrigger-11894-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421733 DEBUG (ScheduledTrigger-11894-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421753 DEBUG (ScheduledTrigger-11894-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421773 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421794 DEBUG (ScheduledTrigger-11894-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421816 DEBUG (ScheduledTrigger-11894-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421838 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421860 DEBUG (ScheduledTrigger-11894-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421881 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421901 DEBUG (ScheduledTrigger-11894-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421921 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421941 DEBUG (ScheduledTrigger-11894-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421964 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3421984 DEBUG (ScheduledTrigger-11894-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422005 DEBUG (ScheduledTrigger-11894-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422025 DEBUG (ScheduledTrigger-11894-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422045 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422065 DEBUG (ScheduledTrigger-11894-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422086 DEBUG (ScheduledTrigger-11894-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422106 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422126 DEBUG (ScheduledTrigger-11894-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422147 DEBUG (ScheduledTrigger-11894-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422167 DEBUG (ScheduledTrigger-11894-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422171 INFO (TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[4708F66C80555458]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCreateCollectionAddReplica
[junit4] 2> 3422171 INFO (TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[4708F66C80555458]) [ ] 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: [testCreateCollectionAddReplica]
[junit4] 2> ## Max replicas per node: 1
[junit4] 2> ## Min replicas per node: 0
[junit4] 2> ## Total replicas: 1
[junit4] 2> ## * testCreateCollectionAddReplica 1
[junit4] 2> ## - active 1
[junit4] 2> ######### Solr op counts ##########
[junit4] 2> ## - CREATE 1
[junit4] 2> ## - autoscaling 1
[junit4] 2> ######### Autoscaling event counts ###########
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSimPolicyCloud -Dtests.method=testCreateCollectionAddReplica -Dtests.seed=4708F66C80555458 -Dtests.slow=true -Dtests.locale=es-MX -Dtests.timezone=Pacific/Norfolk -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 1.98s J1 | TestSimPolicyCloud.testCreateCollectionAddReplica <<<
[junit4] > Throwable #1: java.lang.AssertionError: Timeout waiting for collection to become active
[junit4] > Live Nodes: [127.0.0.1:10006_solr, 127.0.0.1:10010_solr, 127.0.0.1:10008_solr, 127.0.0.1:10009_solr, 127.0.0.1:10007_solr]
[junit4] > Last available state: DocCollection(testCreateCollectionAddReplica//clusterstate.json/23)={
[junit4] > "replicationFactor":"1",
[junit4] > "pullReplicas":"0",
[junit4] > "router":{"name":"compositeId"},
[junit4] > "maxShardsPerNode":"1",
[junit4] > "autoAddReplicas":"false",
[junit4] > "nrtReplicas":"1",
[junit4] > "tlogReplicas":"0",
[junit4] > "autoCreated":"true",
[junit4] > "policy":"c1",
[junit4] > "shards":{"shard1":{
[junit4] > "replicas":{"core_node1":{
[junit4] > "core":"testCreateCollectionAddReplica_shard1_replica_n1",
[junit4] > "SEARCHER.searcher.maxDoc":0,
[junit4] > "SEARCHER.searcher.deletedDocs":0,
[junit4] > "INDEX.sizeInBytes":10240,
[junit4] > "node_name":"127.0.0.1:10010_solr",
[junit4] > "state":"active",
[junit4] > "type":"NRT",
[junit4] > "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] > "SEARCHER.searcher.numDocs":0}},
[junit4] > "range":"80000000-7fffffff",
[junit4] > "state":"active"}}}
[junit4] > at __randomizedtesting.SeedInfo.seed([4708F66C80555458:C72893429116BCFE]:0)
[junit4] > at org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:70)
[junit4] > at org.apache.solr.cloud.autoscaling.sim.TestSimPolicyCloud.testCreateCollectionAddReplica(TestSimPolicyCloud.java:123)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > Caused by: java.util.concurrent.TimeoutException: last state: DocCollection(testCreateCollectionAddReplica//clusterstate.json/23)={
[junit4] > "replicationFactor":"1",
[junit4] > "pullReplicas":"0",
[junit4] > "router":{"name":"compositeId"},
[junit4] > "maxShardsPerNode":"1",
[junit4] > "autoAddReplicas":"false",
[junit4] > "nrtReplicas":"1",
[junit4] > "tlogReplicas":"0",
[junit4] > "autoCreated":"true",
[junit4] > "policy":"c1",
[junit4] > "shards":{"shard1":{
[junit4] > "replicas":{"core_node1":{
[junit4] > "core":"testCreateCollectionAddReplica_shard1_replica_n1",
[junit4] > "SEARCHER.searcher.maxDoc":0,
[junit4] > "SEARCHER.searcher.deletedDocs":0,
[junit4] > "INDEX.sizeInBytes":10240,
[junit4] > "node_name":"127.0.0.1:10010_solr",
[junit4] > "state":"active",
[junit4] > "type":"NRT",
[junit4] > "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] > "SEARCHER.searcher.numDocs":0}},
[junit4] > "range":"80000000-7fffffff",
[junit4] > "state":"active"}}}
[junit4] > at org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:112)
[junit4] > at org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:64)
[junit4] > ... 41 more
[junit4] 2> 3422188 DEBUG (ScheduledTrigger-11894-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422209 DEBUG (ScheduledTrigger-11894-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422228 INFO (TEST-TestSimPolicyCloud.testMetricsTag-seed#[4708F66C80555458]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMetricsTag
[junit4] 2> 3422228 DEBUG (TEST-TestSimPolicyCloud.testMetricsTag-seed#[4708F66C80555458]) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 12
[junit4] 2> 3422228 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 12
[junit4] 2> 3422228 INFO (TEST-TestSimPolicyCloud.testMetricsTag-seed#[4708F66C80555458]) [ ] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and clearing object cache...
[junit4] 2> 3422228 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 3422236 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 3422236 DEBUG (TEST-TestSimPolicyCloud.testMetricsTag-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 3422236 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> 3422236 DEBUG (TEST-TestSimPolicyCloud.testMetricsTag-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 3422236 DEBUG (TEST-TestSimPolicyCloud.testMetricsTag-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 3422236 DEBUG (TEST-TestSimPolicyCloud.testMetricsTag-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 3422236 DEBUG (TEST-TestSimPolicyCloud.testMetricsTag-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 3422236 DEBUG (TEST-TestSimPolicyCloud.testMetricsTag-seed#[4708F66C80555458]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 3422237 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 3422237 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 13
[junit4] 2> 3422238 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 13, lastZnodeVersion -1
[junit4] 2> 3422238 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 13
[junit4] 2> 3422238 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:10006_solr, 127.0.0.1:10010_solr, 127.0.0.1:10009_solr, 127.0.0.1:10008_solr, 127.0.0.1:10007_solr]
[junit4] 2> 3422238 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 3422238 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 13, lastZnodeVersion 13
[junit4] 2> 3422238 DEBUG (ScheduledTrigger-11897-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422260 DEBUG (ScheduledTrigger-11897-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422291 DEBUG (ScheduledTrigger-11897-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422314 DEBUG (ScheduledTrigger-11897-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422334 DEBUG (ScheduledTrigger-11897-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422337 ERROR (simCloudManagerPool-11896-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:210)
[junit4] 2> at org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:148)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)
[junit4] 2> at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.simHandleSolrRequest(SimCloudManager.java:684)
[junit4] 2> at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.lambda$request$15(SimCloudManager.java:629)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 3422338 DEBUG (simCloudManagerPool-11896-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:210) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:148) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.simHandleSolrRequest(SimCloudManager.java:684) ~[test/:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.lambda$request$15(SimCloudManager.java:629) ~[test/:?]
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_172]
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209) ~[java/:?]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_172]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_172]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
[junit4] 2> 3422339 DEBUG (simCloudManagerPool-11896-thread-2) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 3422339 DEBUG (simCloudManagerPool-11896-thread-2) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 14
[junit4] 2> 3422343 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 14
[junit4] 2> 3422343 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 3422343 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 14, lastZnodeVersion 14
[junit4] 2> 3422355 INFO (simCloudManagerPool-11896-thread-3) [ ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 43565188 sessionWrapper.createTime 43565167525984, this.sessionWrapper.createTime 43565167525984
[junit4] 2> 3422355 DEBUG (ScheduledTrigger-11897-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422366 DEBUG (simCloudManagerPool-11896-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider -- submit leader election for metricsTest / shard1
[junit4] 2> 3422366 DEBUG (simCloudManagerPool-11896-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider Running leader election for metricsTest / shard1
[junit4] 2> 3422369 DEBUG (simCloudManagerPool-11896-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for metricsTest / shard1: core_node1
[junit4] 2> 3422370 INFO (TEST-TestSimPolicyCloud.testMetricsTag-seed#[4708F66C80555458]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMetricsTag
[junit4] 2> 3422370 INFO (TEST-TestSimPolicyCloud.testMetricsTag-seed#[4708F66C80555458]) [ ] 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: [metricsTest]
[junit4] 2> ## Max replicas per node: 1
[junit4] 2> ## Min replicas per node: 0
[junit4] 2> ## Total replicas: 1
[junit4] 2> ## * metricsTest 1
[junit4] 2> ## - active 1
[junit4] 2> ######### Solr op counts ##########
[junit4] 2> ## - CREATE 1
[junit4] 2> ## - autoscaling 2
[junit4] 2> ######### Autoscaling event counts ###########
[junit4] 2>
[junit4] 2> 3422383 DEBUG (ScheduledTrigger-11897-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422403 DEBUG (ScheduledTrigger-11897-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422427 INFO (TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[4708F66C80555458]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDataProviderPerReplicaDetails
[junit4] 2> 3422427 DEBUG (TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[4708F66C80555458]) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 15
[junit4] 2> 3422427 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 15
[junit4] 2> 3422427 INFO (TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[4708F66C80555458]) [ ] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and clearing object cache...
[junit4] 2> 3422427 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 3422427 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 3422430 DEBUG (TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 3422431 DEBUG (TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 3422431 DEBUG (TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 3422431 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> 3422431 DEBUG (TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 3422431 DEBUG (TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 3422431 DEBUG (TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[4708F66C80555458]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 3422431 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 3422431 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 16
[junit4] 2> 3422432 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 16, lastZnodeVersion -1
[junit4] 2> 3422432 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 16
[junit4] 2> 3422432 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:10006_solr, 127.0.0.1:10010_solr, 127.0.0.1:10009_solr, 127.0.0.1:10008_solr, 127.0.0.1:10007_solr]
[junit4] 2> 3422432 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 3422432 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 16, lastZnodeVersion 16
[junit4] 2> 3422432 DEBUG (ScheduledTrigger-11900-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422457 DEBUG (ScheduledTrigger-11900-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422487 DEBUG (ScheduledTrigger-11900-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422507 DEBUG (ScheduledTrigger-11900-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422527 DEBUG (ScheduledTrigger-11900-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422550 DEBUG (ScheduledTrigger-11900-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422561 DEBUG (simCloudManagerPool-11899-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider -- submit leader election for perReplicaDataColl / shard1
[junit4] 2> 3422561 DEBUG (simCloudManagerPool-11899-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider Running leader election for perReplicaDataColl / shard1
[junit4] 2> 3422564 DEBUG (simCloudManagerPool-11899-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for perReplicaDataColl / shard1: core_node1
[junit4] 2> 3422570 DEBUG (ScheduledTrigger-11900-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422571 DEBUG (simCloudManagerPool-11899-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider -- already has leader perReplicaDataColl / shard1
[junit4] 2> 3422586 DEBUG (simCloudManagerPool-11899-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider -- already has leader perReplicaDataColl / shard1
[junit4] 2> 3422590 DEBUG (ScheduledTrigger-11900-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422600 DEBUG (simCloudManagerPool-11899-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider -- already has leader perReplicaDataColl / shard1
[junit4] 2> 3422612 DEBUG (ScheduledTrigger-11900-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422617 DEBUG (simCloudManagerPool-11899-thread-6) [ ] o.a.s.c.a.s.SimClusterStateProvider -- already has leader perReplicaDataColl / shard1
[junit4] 2> 3422632 DEBUG (ScheduledTrigger-11900-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422652 DEBUG (ScheduledTrigger-11900-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422672 DEBUG (ScheduledTrigger-11900-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422694 DEBUG (ScheduledTrigger-11900-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422714 DEBUG (ScheduledTrigger-11900-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422724 INFO (TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[4708F66C80555458]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDataProviderPerReplicaDetails
[junit4] 2> 3422724 INFO (TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[4708F66C80555458]) [ ] 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> 3422735 DEBUG (ScheduledTrigger-11900-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422767 DEBUG (ScheduledTrigger-11900-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422790 DEBUG (ScheduledTrigger-11900-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5
[junit4] 2> 3422790 INFO (TEST-TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[4708F66C80555458]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateCollectionAddShardWithReplicaTypeUsingPolicy
[junit4] 2> 3422790 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[4708F66C80555458]) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 17
[junit4] 2> 3422790 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 17
[junit4] 2> 3422790 INFO (TEST-TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[4708F66C80555458]) [ ] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and clearing object cache...
[junit4] 2> 3422794 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 3422794 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 3422797 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 3422794 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> 3422797 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 3422797 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 3422797 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 3422797 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[4708F66C80555458]) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 3422797 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[4708F66C80555458]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 3422797 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 3422798 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.jso
[...truncated too long message...]
.
[junit4] Suite: org.apache.solr.cloud.autoscaling.sim.TestSimPolicyCloud
[junit4] OK 0.78s J0 | TestSimPolicyCloud.testCreateCollectionAddShardUsingPolicy
[junit4] OK 0.46s J0 | TestSimPolicyCloud.testCreateCollectionSplitShard
[junit4] OK 0.20s J0 | TestSimPolicyCloud.testDataProvider
[junit4] OK 0.18s J0 | TestSimPolicyCloud.testCreateCollectionAddReplica
[junit4] OK 0.15s J0 | TestSimPolicyCloud.testMetricsTag
[junit4] OK 0.29s J0 | TestSimPolicyCloud.testDataProviderPerReplicaDetails
[junit4] OK 0.32s J0 | TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy
[junit4] Completed [1/5] on J0 in 5.37s, 7 tests
[junit4]
[junit4] Suite: org.apache.solr.cloud.autoscaling.sim.TestSimPolicyCloud
[junit4] OK 0.74s J1 | TestSimPolicyCloud.testCreateCollectionAddShardUsingPolicy
[junit4] OK 0.46s J1 | TestSimPolicyCloud.testCreateCollectionSplitShard
[junit4] OK 0.23s J1 | TestSimPolicyCloud.testDataProvider
[junit4] OK 0.18s J1 | TestSimPolicyCloud.testCreateCollectionAddReplica
[junit4] OK 0.16s J1 | TestSimPolicyCloud.testMetricsTag
[junit4] OK 0.29s J1 | TestSimPolicyCloud.testDataProviderPerReplicaDetails
[junit4] OK 0.40s J1 | TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy
[junit4] Completed [2/5] on J1 in 5.54s, 7 tests
[junit4]
[junit4] Duplicate suite name used with XML reports: org.apache.solr.cloud.autoscaling.sim.TestSimPolicyCloud. This may confuse tools that process XML reports. Set 'ignoreDuplicateSuites' to true to skip this message.
[junit4] Suite: org.apache.solr.cloud.autoscaling.sim.TestSimPolicyCloud
[junit4] OK 0.32s J0 | TestSimPolicyCloud.testCreateCollectionAddShardUsingPolicy
[junit4] OK 0.40s J0 | TestSimPolicyCloud.testCreateCollectionSplitShard
[junit4] OK 0.17s J0 | TestSimPolicyCloud.testDataProvider
[junit4] OK 0.16s J0 | TestSimPolicyCloud.testCreateCollectionAddReplica
[junit4] OK 0.14s J0 | TestSimPolicyCloud.testMetricsTag
[junit4] OK 0.28s J0 | TestSimPolicyCloud.testDataProviderPerReplicaDetails
[junit4] OK 0.30s J0 | TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy
[junit4] Completed [3/5] on J0 in 1.83s, 7 tests
[junit4]
[junit4] Suite: org.apache.solr.cloud.autoscaling.sim.TestSimPolicyCloud
[junit4] OK 0.27s J1 | TestSimPolicyCloud.testCreateCollectionAddShardUsingPolicy
[junit4] OK 0.39s J1 | TestSimPolicyCloud.testCreateCollectionSplitShard
[junit4] OK 0.16s J1 | TestSimPolicyCloud.testDataProvider
[junit4] OK 0.15s J1 | TestSimPolicyCloud.testCreateCollectionAddReplica
[junit4] OK 0.15s J1 | TestSimPolicyCloud.testMetricsTag
[junit4] OK 0.29s J1 | TestSimPolicyCloud.testDataProviderPerReplicaDetails
[junit4] OK 0.30s J1 | TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy
[junit4] Completed [4/5] on J1 in 1.74s, 7 tests
[junit4]
[junit4] Suite: org.apache.solr.cloud.autoscaling.sim.TestSimPolicyCloud
[junit4] OK 0.26s J0 | TestSimPolicyCloud.testCreateCollectionAddShardUsingPolicy
[junit4] OK 0.40s J0 | TestSimPolicyCloud.testCreateCollectionSplitShard
[junit4] OK 0.19s J0 | TestSimPolicyCloud.testDataProvider
[junit4] OK 0.15s J0 | TestSimPolicyCloud.testCreateCollectionAddReplica
[junit4] OK 0.13s J0 | TestSimPolicyCloud.testMetricsTag
[junit4] OK 0.27s J0 | TestSimPolicyCloud.testDataProviderPerReplicaDetails
[junit4] OK 0.29s J0 | TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy
[junit4] Completed [5/5] on J0 in 1.97s, 7 tests
[junit4]
[junit4] JVM J0: 0.74 .. 10.86 = 10.12s
[junit4] JVM J1: 0.74 .. 9.15 = 8.41s
[junit4] Execution time total: 10 seconds
[junit4] Tests summary: 5 suites, 35 tests
[junit4] Could not remove temporary path: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp])
[junit4] Could not remove temporary path: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp])
[echo] 5 slowest tests:
[junit4:tophints] 442.71s | org.apache.solr.cloud.cdcr.CdcrWithNodesRestartsTest
[junit4:tophints] 363.37s | org.apache.solr.cloud.api.collections.ShardSplitTest
[junit4:tophints] 352.43s | org.apache.solr.cloud.cdcr.CdcrOpsAndBoundariesTest
[junit4:tophints] 122.83s | org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest
[junit4:tophints] 117.59s | org.apache.solr.update.SoftAutoCommitTest
-check-totals:
test-nocompile:
BUILD SUCCESSFUL
Total time: 13 seconds
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.cloud.autoscaling.sim.TestSimPolicyCloud
[repro] Exiting with code 0
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath$ValidateAntFileMask.hasMatch(FilePath.java:2845)
at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2724)
at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2705)
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3084)
Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to Solaris VBOX
at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1741)
at hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357)
at hudson.remoting.Channel.call(Channel.java:955)
at hudson.FilePath.act(FilePath.java:1070)
at hudson.FilePath.act(FilePath.java:1059)
at hudson.FilePath.validateAntFileMask(FilePath.java:2703)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
at hudson.model.Run.execute(Run.java:1831)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
Caused: hudson.FilePath$TunneledInterruptedException
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3086)
at hudson.remoting.UserRequest.perform(UserRequest.java:212)
at hudson.remoting.UserRequest.perform(UserRequest.java:54)
at hudson.remoting.Request$2.run(Request.java:369)
at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
Caused: java.lang.InterruptedException: java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath.act(FilePath.java:1072)
at hudson.FilePath.act(FilePath.java:1059)
at hudson.FilePath.validateAntFileMask(FilePath.java:2703)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
at hudson.model.Run.execute(Run.java:1831)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
No artifacts found that match the file pattern "**/*.events,heapdumps/**,**/*_pid*.log". Configuration error?
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/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=eeaf6fc7d10a66ad1687a901882a89d987443c24, workspace=/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris
[WARNINGS] Computing warning deltas based on reference build #826
Recording test results
Setting ANT_1_8_2_HOME=/export/home/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=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[JENKINS] Lucene-Solr-7.x-Solaris (64bit/jdk1.8.0) - Build # 828 -
Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/828/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseG1GC
4 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.rest.TestManagedResourceStorage
Error Message:
SolrCore.getOpenCount()==2
Stack Trace:
java.lang.RuntimeException: SolrCore.getOpenCount()==2
at __randomizedtesting.SeedInfo.seed([8D3EF5D4E26FC84F]:0)
at org.apache.solr.util.TestHarness.close(TestHarness.java:380)
at org.apache.solr.SolrTestCaseJ4.deleteCore(SolrTestCaseJ4.java:802)
at org.apache.solr.cloud.AbstractZkTestCase.azt_afterClass(AbstractZkTestCase.java:147)
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$7.evaluate(RandomizedRunner.java:898)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: junit.framework.TestSuite.org.apache.solr.rest.TestManagedResourceStorage
Error Message:
4 threads leaked from SUITE scope at org.apache.solr.rest.TestManagedResourceStorage: 1) Thread[id=63, name=SessionTracker, state=TIMED_WAITING, group=TGRP-TestManagedResourceStorage] at java.lang.Object.wait(Native Method) at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:147) 2) Thread[id=61, name=Thread-3, state=WAITING, group=TGRP-TestManagedResourceStorage] at java.lang.Object.wait(Native Method) at java.lang.Thread.join(Thread.java:1252) at java.lang.Thread.join(Thread.java:1326) at org.apache.zookeeper.server.NIOServerCnxnFactory.join(NIOServerCnxnFactory.java:313) at org.apache.solr.cloud.ZkTestServer$ZKServerMain.runFromConfig(ZkTestServer.java:313) at org.apache.solr.cloud.ZkTestServer$2.run(ZkTestServer.java:496) 3) Thread[id=62, name=NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0, state=RUNNABLE, group=TGRP-TestManagedResourceStorage] at sun.nio.ch.DevPollArrayWrapper.poll0(Native Method) at sun.nio.ch.DevPollArrayWrapper.poll(DevPollArrayWrapper.java:223) at sun.nio.ch.DevPollSelectorImpl.doSelect(DevPollSelectorImpl.java:98) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:196) at java.lang.Thread.run(Thread.java:748) 4) Thread[id=65, name=ProcessThread(sid:0 cport:37914):, state=WAITING, group=TGRP-TestManagedResourceStorage] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at org.apache.zookeeper.server.PrepRequestProcessor.run(PrepRequestProcessor.java:123)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 4 threads leaked from SUITE scope at org.apache.solr.rest.TestManagedResourceStorage:
1) Thread[id=63, name=SessionTracker, state=TIMED_WAITING, group=TGRP-TestManagedResourceStorage]
at java.lang.Object.wait(Native Method)
at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:147)
2) Thread[id=61, name=Thread-3, state=WAITING, group=TGRP-TestManagedResourceStorage]
at java.lang.Object.wait(Native Method)
at java.lang.Thread.join(Thread.java:1252)
at java.lang.Thread.join(Thread.java:1326)
at org.apache.zookeeper.server.NIOServerCnxnFactory.join(NIOServerCnxnFactory.java:313)
at org.apache.solr.cloud.ZkTestServer$ZKServerMain.runFromConfig(ZkTestServer.java:313)
at org.apache.solr.cloud.ZkTestServer$2.run(ZkTestServer.java:496)
3) Thread[id=62, name=NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0, state=RUNNABLE, group=TGRP-TestManagedResourceStorage]
at sun.nio.ch.DevPollArrayWrapper.poll0(Native Method)
at sun.nio.ch.DevPollArrayWrapper.poll(DevPollArrayWrapper.java:223)
at sun.nio.ch.DevPollSelectorImpl.doSelect(DevPollSelectorImpl.java:98)
at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:196)
at java.lang.Thread.run(Thread.java:748)
4) Thread[id=65, name=ProcessThread(sid:0 cport:37914):, state=WAITING, group=TGRP-TestManagedResourceStorage]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.zookeeper.server.PrepRequestProcessor.run(PrepRequestProcessor.java:123)
at __randomizedtesting.SeedInfo.seed([8D3EF5D4E26FC84F]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.rest.TestManagedResourceStorage
Error Message:
There are still zombie threads that couldn't be terminated: 1) Thread[id=62, name=NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0, state=RUNNABLE, group=TGRP-TestManagedResourceStorage] at sun.nio.ch.IOUtil.drain(Native Method) at sun.nio.ch.DevPollSelectorImpl.doSelect(DevPollSelectorImpl.java:109) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:196) at java.lang.Thread.run(Thread.java:748)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
1) Thread[id=62, name=NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0, state=RUNNABLE, group=TGRP-TestManagedResourceStorage]
at sun.nio.ch.IOUtil.drain(Native Method)
at sun.nio.ch.DevPollSelectorImpl.doSelect(DevPollSelectorImpl.java:109)
at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:196)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([8D3EF5D4E26FC84F]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.rest.TestManagedResourceStorage
Error Message:
ObjectTracker found 1 object(s) that were not released!!! [ZkCollectionTerms] org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.solr.cloud.ZkCollectionTerms at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) at org.apache.solr.cloud.ZkCollectionTerms.<init>(ZkCollectionTerms.java:39) at org.apache.solr.cloud.ZkController.getCollectionTerms(ZkController.java:1560) at org.apache.solr.cloud.ZkController.getShardTerms(ZkController.java:1555) at org.apache.solr.cloud.ZkController.register(ZkController.java:1115) at org.apache.solr.cloud.ZkController.register(ZkController.java:1080) at org.apache.solr.core.ZkContainer.lambda$registerInZk$0(ZkContainer.java:187) 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) at java.lang.Thread.run(Thread.java:748)
Stack Trace:
java.lang.AssertionError: ObjectTracker found 1 object(s) that were not released!!! [ZkCollectionTerms]
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.solr.cloud.ZkCollectionTerms
at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
at org.apache.solr.cloud.ZkCollectionTerms.<init>(ZkCollectionTerms.java:39)
at org.apache.solr.cloud.ZkController.getCollectionTerms(ZkController.java:1560)
at org.apache.solr.cloud.ZkController.getShardTerms(ZkController.java:1555)
at org.apache.solr.cloud.ZkController.register(ZkController.java:1115)
at org.apache.solr.cloud.ZkController.register(ZkController.java:1080)
at org.apache.solr.core.ZkContainer.lambda$registerInZk$0(ZkContainer.java:187)
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)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([8D3EF5D4E26FC84F]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:304)
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$7.evaluate(RandomizedRunner.java:898)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 12338 lines...]
[junit4] Suite: org.apache.solr.rest.TestManagedResourceStorage
[junit4] 2> 2673 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.rest.TestManagedResourceStorage_8D3EF5D4E26FC84F-001/init-core-data-001
[junit4] 2> 2929 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 2987 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [ ] o.e.j.u.log Logging initialized @3052ms to org.eclipse.jetty.util.log.Slf4jLog
[junit4] 2> 2997 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 3028 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 3031 INFO (Thread-1) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 3031 INFO (Thread-1) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 3160 ERROR (Thread-1) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 3162 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:54256
[junit4] 2> 3298 INFO (zkConnectionManagerCallback-2-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3334 INFO (zkConnectionManagerCallback-4-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3370 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [ ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 3392 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [ ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 3399 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [ ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 3412 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [ ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 3416 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [ ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 3425 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [ ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 3436 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [ ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 3450 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [ ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 3456 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [ ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 3464 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [ ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 3473 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [ ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 3479 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 3566 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [ ] o.a.s.c.SolrResourceLoader Using system property solr.solr.home: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr
[junit4] 2> 3576 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/lib, /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 3817 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.6.0
[junit4] 2> 4012 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 4337 WARN (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [ ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.LowerCaseTokenizerFactory]. Please consult documentation how to replace it accordingly.
[junit4] 2> 4404 WARN (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [ ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.WordDelimiterFilterFactory]. Please consult documentation how to replace it accordingly.
[junit4] 2> 4491 WARN (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [ ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.GeoHashField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 4505 WARN (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [ ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.LatLonType]. Please consult documentation how to replace it accordingly.
[junit4] 2> 4535 WARN (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [ ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.CurrencyField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 4654 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 5445 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54256/solr
[junit4] 2> 5488 INFO (zkConnectionManagerCallback-8-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 5511 INFO (zkConnectionManagerCallback-10-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 5859 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [n:127.0.0.1:8983_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:8983_
[junit4] 2> 5862 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [n:127.0.0.1:8983_ ] o.a.s.c.Overseer Overseer (id=72063525429641219-127.0.0.1:8983_-n_0000000000) starting
[junit4] 2> 5924 INFO (zkConnectionManagerCallback-17-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 5928 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [n:127.0.0.1:8983_ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54256/solr ready
[junit4] 2> 6018 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [n:127.0.0.1:8983_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:8983_
[junit4] 2> 6028 INFO (OverseerStateUpdate-72063525429641219-127.0.0.1:8983_-n_0000000000) [n:127.0.0.1:8983_ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:8983_
[junit4] 2> 6050 INFO (zkCallback-9-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 6068 INFO (zkCallback-16-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 6401 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [n:127.0.0.1:8983_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 6661 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [n:127.0.0.1:8983_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50d3205c
[junit4] 2> 6665 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [n:127.0.0.1:8983_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50d3205c
[junit4] 2> 6665 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [n:127.0.0.1:8983_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50d3205c
[junit4] 2> 6745 INFO (coreLoadExecutor-13-thread-1-processing-n:127.0.0.1:8983_) [n:127.0.0.1:8983_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 7758 INFO (coreLoadExecutor-13-thread-1-processing-n:127.0.0.1:8983_) [n:127.0.0.1:8983_ c:collection1 s:shard1 x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/lib, /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 7840 INFO (coreLoadExecutor-13-thread-1-processing-n:127.0.0.1:8983_) [n:127.0.0.1:8983_ c:collection1 s:shard1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.6.0
[junit4] 2> 7890 INFO (coreLoadExecutor-13-thread-1-processing-n:127.0.0.1:8983_) [n:127.0.0.1:8983_ c:collection1 s:shard1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 8042 INFO (coreLoadExecutor-13-thread-1-processing-n:127.0.0.1:8983_) [n:127.0.0.1:8983_ c:collection1 s:shard1 x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 8077 INFO (coreLoadExecutor-13-thread-1-processing-n:127.0.0.1:8983_) [n:127.0.0.1:8983_ c:collection1 s:shard1 x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1, trusted=true
[junit4] 2> 8147 INFO (coreLoadExecutor-13-thread-1-processing-n:127.0.0.1:8983_) [n:127.0.0.1:8983_ c:collection1 s:shard1 x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard1.127.0.0.1:8983__collection1' (registry 'solr.core.collection1.shard1.127.0.0.1:8983__collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50d3205c
[junit4] 2> 8166 INFO (coreLoadExecutor-13-thread-1-processing-n:127.0.0.1:8983_) [n:127.0.0.1:8983_ c:collection1 s:shard1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.rest.TestManagedResourceStorage_8D3EF5D4E26FC84F-001/init-core-data-001/]
[junit4] 2> 8237 INFO (coreLoadExecutor-13-thread-1-processing-n:127.0.0.1:8983_) [n:127.0.0.1:8983_ c:collection1 s:shard1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=83.044921875, floorSegmentMB=1.4716796875, forceMergeDeletesPctAllowed=17.9125909775201, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.562669561494706, deletesPctAllowed=31.88216480137627
[junit4] 2> 8344 WARN (coreLoadExecutor-13-thread-1-processing-n:127.0.0.1:8983_) [n:127.0.0.1:8983_ c:collection1 s:shard1 x:collection1] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.OldLuceneQParserPlugin]. Please consult documentation how to replace it accordingly.
[junit4] 2> 9447 INFO (coreLoadExecutor-13-thread-1-processing-n:127.0.0.1:8983_) [n:127.0.0.1:8983_ c:collection1 s:shard1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 9447 INFO (coreLoadExecutor-13-thread-1-processing-n:127.0.0.1:8983_) [n:127.0.0.1:8983_ c:collection1 s:shard1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 9460 INFO (coreLoadExecutor-13-thread-1-processing-n:127.0.0.1:8983_) [n:127.0.0.1:8983_ c:collection1 s:shard1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 9460 INFO (coreLoadExecutor-13-thread-1-processing-n:127.0.0.1:8983_) [n:127.0.0.1:8983_ c:collection1 s:shard1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 9465 INFO (coreLoadExecutor-13-thread-1-processing-n:127.0.0.1:8983_) [n:127.0.0.1:8983_ c:collection1 s:shard1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=1.025390625, floorSegmentMB=0.7900390625, forceMergeDeletesPctAllowed=28.078336876940654, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.48591076369126973, deletesPctAllowed=24.22851978258249
[junit4] 2> 9493 INFO (coreLoadExecutor-13-thread-1-processing-n:127.0.0.1:8983_) [n:127.0.0.1:8983_ c:collection1 s:shard1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2b55c1b5[collection1] main]
[junit4] 2> 9559 INFO (coreLoadExecutor-13-thread-1-processing-n:127.0.0.1:8983_) [n:127.0.0.1:8983_ c:collection1 s:shard1 x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 9668 INFO (coreLoadExecutor-13-thread-1-processing-n:127.0.0.1:8983_) [n:127.0.0.1:8983_ c:collection1 s:shard1 x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 9788 INFO (coreLoadExecutor-13-thread-1-processing-n:127.0.0.1:8983_) [n:127.0.0.1:8983_ c:collection1 s:shard1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 9790 INFO (searcherExecutor-14-thread-1-processing-n:127.0.0.1:8983_ x:collection1 c:collection1 s:shard1) [n:127.0.0.1:8983_ c:collection1 s:shard1 x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 9798 INFO (searcherExecutor-14-thread-1-processing-n:127.0.0.1:8983_ x:collection1 c:collection1 s:shard1) [n:127.0.0.1:8983_ c:collection1 s:shard1 x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 9798 INFO (searcherExecutor-14-thread-1-processing-n:127.0.0.1:8983_ x:collection1 c:collection1 s:shard1) [n:127.0.0.1:8983_ c:collection1 s:shard1 x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 9798 INFO (searcherExecutor-14-thread-1-processing-n:127.0.0.1:8983_ x:collection1 c:collection1 s:shard1) [n:127.0.0.1:8983_ c:collection1 s:shard1 x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 9799 INFO (coreLoadExecutor-13-thread-1-processing-n:127.0.0.1:8983_) [n:127.0.0.1:8983_ c:collection1 s:shard1 x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1612582876679766016
[junit4] 2> 9799 INFO (searcherExecutor-14-thread-1-processing-n:127.0.0.1:8983_ x:collection1 c:collection1 s:shard1) [n:127.0.0.1:8983_ c:collection1 s:shard1 x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 9800 INFO (searcherExecutor-14-thread-1-processing-n:127.0.0.1:8983_ x:collection1 c:collection1 s:shard1) [n:127.0.0.1:8983_ c:collection1 s:shard1 x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 9801 INFO (searcherExecutor-14-thread-1-processing-n:127.0.0.1:8983_ x:collection1 c:collection1 s:shard1) [n:127.0.0.1:8983_ c:collection1 s:shard1 x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 9801 INFO (searcherExecutor-14-thread-1-processing-n:127.0.0.1:8983_ x:collection1 c:collection1 s:shard1) [n:127.0.0.1:8983_ c:collection1 s:shard1 x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 9802 INFO (searcherExecutor-14-thread-1-processing-n:127.0.0.1:8983_ x:collection1 c:collection1 s:shard1) [n:127.0.0.1:8983_ c:collection1 s:shard1 x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 9811 INFO (searcherExecutor-14-thread-1-processing-n:127.0.0.1:8983_ x:collection1 c:collection1 s:shard1) [n:127.0.0.1:8983_ c:collection1 s:shard1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2b55c1b5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 9812 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [n:127.0.0.1:8983_ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 9831 INFO (TEST-TestManagedResourceStorage.testZkBasedJsonStorage-seed#[8D3EF5D4E26FC84F]) [n:127.0.0.1:8983_ ] o.a.s.SolrTestCaseJ4 ###Starting testZkBasedJsonStorage
[junit4] 2> 9836 INFO (TEST-TestManagedResourceStorage.testZkBasedJsonStorage-seed#[8D3EF5D4E26FC84F]) [n:127.0.0.1:8983_ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /test
[junit4] 2> 9838 INFO (TEST-TestManagedResourceStorage.testZkBasedJsonStorage-seed#[8D3EF5D4E26FC84F]) [n:127.0.0.1:8983_ ] o.a.s.r.ManagedResourceStorage Wrote 136 bytes to new znode /test/_test_foo.json
[junit4] 2> 9838 INFO (TEST-TestManagedResourceStorage.testZkBasedJsonStorage-seed#[8D3EF5D4E26FC84F]) [n:127.0.0.1:8983_ ] o.a.s.r.ManagedResourceStorage Saved JSON object to path _test_foo.json using ZooKeeperStorageIO:path=/test
[junit4] 2> 9839 INFO (TEST-TestManagedResourceStorage.testZkBasedJsonStorage-seed#[8D3EF5D4E26FC84F]) [n:127.0.0.1:8983_ ] o.a.s.r.ManagedResourceStorage Loaded LinkedHashMap at path _test_foo.json using ZooKeeperStorageIO:path=/test
[junit4] 2> 9840 INFO (TEST-TestManagedResourceStorage.testZkBasedJsonStorage-seed#[8D3EF5D4E26FC84F]) [n:127.0.0.1:8983_ ] o.a.s.r.ManagedResourceStorage Wrote 175 bytes to existing znode /test/_test_foo.json
[junit4] 2> 9841 INFO (TEST-TestManagedResourceStorage.testZkBasedJsonStorage-seed#[8D3EF5D4E26FC84F]) [n:127.0.0.1:8983_ ] o.a.s.r.ManagedResourceStorage Saved JSON object to path _test_foo.json using ZooKeeperStorageIO:path=/test
[junit4] 2> 9841 INFO (TEST-TestManagedResourceStorage.testZkBasedJsonStorage-seed#[8D3EF5D4E26FC84F]) [n:127.0.0.1:8983_ ] o.a.s.r.ManagedResourceStorage Loaded LinkedHashMap at path _test_foo.json using ZooKeeperStorageIO:path=/test
[junit4] 2> 9843 INFO (TEST-TestManagedResourceStorage.testZkBasedJsonStorage-seed#[8D3EF5D4E26FC84F]) [n:127.0.0.1:8983_ ] o.a.s.SolrTestCaseJ4 ###Ending testZkBasedJsonStorage
[junit4] 2> 9850 INFO (TEST-TestManagedResourceStorage.testFileBasedJsonStorage-seed#[8D3EF5D4E26FC84F]) [n:127.0.0.1:8983_ ] o.a.s.SolrTestCaseJ4 ###Starting testFileBasedJsonStorage
[junit4] 2> 9851 INFO (TEST-TestManagedResourceStorage.testFileBasedJsonStorage-seed#[8D3EF5D4E26FC84F]) [n:127.0.0.1:8983_ ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.rest.TestManagedResourceStorage_8D3EF5D4E26FC84F-001/json-storage-001/managed
[junit4] 2> 9852 INFO (TEST-TestManagedResourceStorage.testFileBasedJsonStorage-seed#[8D3EF5D4E26FC84F]) [n:127.0.0.1:8983_ ] o.a.s.r.ManagedResourceStorage Saved JSON object to path _test_foo.json using file:dir=/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.rest.TestManagedResourceStorage_8D3EF5D4E26FC84F-001/json-storage-001/managed
[junit4] 2> 9852 INFO (TEST-TestManagedResourceStorage.testFileBasedJsonStorage-seed#[8D3EF5D4E26FC84F]) [n:127.0.0.1:8983_ ] o.a.s.r.ManagedResourceStorage Loaded LinkedHashMap at path _test_foo.json using file:dir=/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.rest.TestManagedResourceStorage_8D3EF5D4E26FC84F-001/json-storage-001/managed
[junit4] 2> 9852 INFO (TEST-TestManagedResourceStorage.testFileBasedJsonStorage-seed#[8D3EF5D4E26FC84F]) [n:127.0.0.1:8983_ ] o.a.s.r.ManagedResourceStorage Saved JSON object to path _test_foo.json using file:dir=/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.rest.TestManagedResourceStorage_8D3EF5D4E26FC84F-001/json-storage-001/managed
[junit4] 2> 9852 INFO (TEST-TestManagedResourceStorage.testFileBasedJsonStorage-seed#[8D3EF5D4E26FC84F]) [n:127.0.0.1:8983_ ] o.a.s.r.ManagedResourceStorage Loaded LinkedHashMap at path _test_foo.json using file:dir=/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.rest.TestManagedResourceStorage_8D3EF5D4E26FC84F-001/json-storage-001/managed
[junit4] 2> 9853 INFO (TEST-TestManagedResourceStorage.testFileBasedJsonStorage-seed#[8D3EF5D4E26FC84F]) [n:127.0.0.1:8983_ ] o.a.s.SolrTestCaseJ4 ###Ending testFileBasedJsonStorage
[junit4] 2> 9853 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [n:127.0.0.1:8983_ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 9854 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [n:127.0.0.1:8983_ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2059039242
[junit4] 2> 9855 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [n:127.0.0.1:8983_ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 9855 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [n:127.0.0.1:8983_ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5ecf9f9d: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@51f9be20
[junit4] 2> 9866 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [n:127.0.0.1:8983_ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 9867 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [n:127.0.0.1:8983_ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5d60ea1: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@4b395f16
[junit4] 2> 9867 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [n:127.0.0.1:8983_ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 9867 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [n:127.0.0.1:8983_ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3c175182: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@48812ee0
[junit4] 2> 9870 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [n:127.0.0.1:8983_ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:8983_
[junit4] 2> 9872 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [n:127.0.0.1:8983_ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 9874 INFO (coreCloseExecutor-19-thread-1-processing-n:127.0.0.1:8983_) [n:127.0.0.1:8983_ c:collection1 s:shard1 r:127.0.0.1:8983__collection1 x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2e235280
[junit4] 2> 9874 INFO (coreCloseExecutor-19-thread-1-processing-n:127.0.0.1:8983_) [n:127.0.0.1:8983_ c:collection1 s:shard1 r:127.0.0.1:8983__collection1 x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1.shard1.127.0.0.1:8983__collection1, tag=2e235280
[junit4] 2> 9874 INFO (coreCloseExecutor-19-thread-1-processing-n:127.0.0.1:8983_) [n:127.0.0.1:8983_ c:collection1 s:shard1 r:127.0.0.1:8983__collection1 x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@c3e9a27: rootName = null, domain = solr.core.collection1.shard1.127.0.0.1:8983__collection1, service url = null, agent id = null] for registry solr.core.collection1.shard1.127.0.0.1:8983__collection1 / com.codahale.metrics.MetricRegistry@689ac149
[junit4] 2> 9984 INFO (zkCallback-9-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 9984 INFO (zkCallback-16-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 9993 INFO (coreCloseExecutor-19-thread-1-processing-n:127.0.0.1:8983_) [n:127.0.0.1:8983_ c:collection1 s:shard1 r:127.0.0.1:8983__collection1 x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1.shard1.leader, tag=2e235280
[junit4] 2> 10006 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [n:127.0.0.1:8983_ ] o.a.s.c.Overseer Overseer (id=72063525429641219-127.0.0.1:8983_-n_0000000000) closing
[junit4] 2> 10007 INFO (OverseerStateUpdate-72063525429641219-127.0.0.1:8983_-n_0000000000) [n:127.0.0.1:8983_ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:8983_
[junit4] 2> 10007 WARN (OverseerAutoScalingTriggerThread-72063525429641219-127.0.0.1:8983_-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 10025 ERROR (coreZkRegister-1-thread-1-processing-n:127.0.0.1:8983_ x:collection1 c:collection1 s:shard1) [n:127.0.0.1:8983_ c:collection1 s:shard1 r:127.0.0.1:8983__collection1 x:collection1] o.a.s.c.ZkContainer :org.apache.solr.common.SolrException: Error creating shard term node in Zookeeper for collection: collection1
[junit4] 2> at org.apache.solr.cloud.ZkShardTerms.ensureTermNodeExist(ZkShardTerms.java:338)
[junit4] 2> at org.apache.solr.cloud.ZkShardTerms.<init>(ZkShardTerms.java:90)
[junit4] 2> at org.apache.solr.cloud.ZkCollectionTerms.getShard(ZkCollectionTerms.java:45)
[junit4] 2> at org.apache.solr.cloud.ZkController.getShardTerms(ZkController.java:1555)
[junit4] 2> at org.apache.solr.cloud.ZkController.register(ZkController.java:1115)
[junit4] 2> at org.apache.solr.cloud.ZkController.register(ZkController.java:1080)
[junit4] 2> at org.apache.solr.core.ZkContainer.lambda$registerInZk$0(ZkContainer.java:187)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/collection1/terms
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:130)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java:1105)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$exists$3(SolrZkClient.java:317)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:317)
[junit4] 2> at org.apache.solr.cloud.ZkShardTerms.ensureTermNodeExist(ZkShardTerms.java:318)
[junit4] 2> ... 10 more
[junit4] 2>
[junit4] 2> 10027 ERROR (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [n:127.0.0.1:8983_ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 10029 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [n:127.0.0.1:8983_ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:54256 54256
[junit4] 2> 10235 INFO (Thread-1) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:54256 54256
[junit4] 2> 10237 WARN (Thread-1) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 2 /solr/aliases.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 2 /solr/clusterstate.json
[junit4] 2> 2 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 2 /solr/live_nodes
[junit4] 2> 2 /solr/collections
[junit4] 2>
[junit4] 2> 10238 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [n:127.0.0.1:8983_ ] o.a.s.SolrTestCaseJ4 Waiting for all tracked resources to be released
[junit4] 2> 20249 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [n:127.0.0.1:8983_ ] o.a.s.SolrTestCaseJ4 Waiting for all tracked resources to be released
[junit4] 2> 30257 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [n:127.0.0.1:8983_ ] o.a.s.SolrTestCaseJ4 Waiting for all tracked resources to be released
[junit4] 2> SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]:
[junit4] 2> java.lang.Object.wait(Native Method)
[junit4] 2> java.lang.Thread.join(Thread.java:1260)
[junit4] 2> [...com.carrotsearch.randomizedtesting.*]
[junit4] 2>
[junit4] 2> Finalizer:
[junit4] 2> java.lang.Object.wait(Native Method)
[junit4] 2> java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:144)
[junit4] 2> java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:165)
[junit4] 2> java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:216)
[junit4] 2>
[junit4] 2> Signal Dispatcher:
[junit4] 2>
[junit4] 2> JUnit4-serializer-daemon:
[junit4] 2> java.lang.Thread.sleep(Native Method)
[junit4] 2> com.carrotsearch.ant.tasks.junit4.events.Serializer$1.run(Serializer.java:50)
[junit4] 2>
[junit4] 2> Reference Handler:
[junit4] 2> java.lang.Object.wait(Native Method)
[junit4] 2> java.lang.Object.wait(Object.java:502)
[junit4] 2> java.lang.ref.Reference.tryHandlePending(Reference.java:191)
[junit4] 2> java.lang.ref.Reference$ReferenceHandler.run(Reference.java:153)
[junit4] 2>
[junit4] 2> main:
[junit4] 2> java.lang.Object.wait(Native Method)
[junit4] 2> java.lang.Thread.join(Thread.java:1252)
[junit4] 2> java.lang.Thread.join(Thread.java:1326)
[junit4] 2> [...com.carrotsearch.randomizedtesting.*]
[junit4] 2> com.carrotsearch.ant.tasks.junit4.slave.SlaveMain.execute(SlaveMain.java:269)
[junit4] 2> com.carrotsearch.ant.tasks.junit4.slave.SlaveMain.main(SlaveMain.java:394)
[junit4] 2> com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe.main(SlaveMainSafe.java:13)
[junit4] 2>
[junit4] 2> SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker:
[junit4] 2> java.lang.Thread.dumpThreads(Native Method)
[junit4] 2> java.lang.Thread.getAllStackTraces(Thread.java:1610)
[junit4] 2> org.apache.solr.SolrTestCaseJ4.clearObjectTrackerAndCheckEmpty(SolrTestCaseJ4.java:375)
[junit4] 2> org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:303)
[junit4] 2> [...sun.*, java.lang.reflect.*, com.carrotsearch.randomizedtesting.*]
[junit4] 2> org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> [...com.carrotsearch.randomizedtesting.*]
[junit4] 2> org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
[junit4] 2> [...com.carrotsearch.randomizedtesting.*]
[junit4] 2> org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
[junit4] 2> org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
[junit4] 2> [...com.carrotsearch.randomizedtesting.*]
[junit4] 2> java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 31260 INFO (SUITE-TestManagedResourceStorage-seed#[8D3EF5D4E26FC84F]-worker) [n:127.0.0.1:8983_ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=1930, maxMBSortInHeap=6.199651485272881, sim=RandomSimilarity(queryNorm=false): {}, locale=sr-Latn-RS, timezone=Asia/Choibalsan
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_172 (64-bit)/cpus=3,threads=1,free=53189992,total=100663296
[junit4] 2> NOTE: All tests run in this JVM: [TestManagedResourceStorage]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestManagedResourceStorage -Dtests.seed=8D3EF5D4E26FC84F -Dtests.slow=true -Dtests.locale=sr-Latn-RS -Dtests.timezone=Asia/Choibalsan -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J0 | TestManagedResourceStorage (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: ObjectTracker found 1 object(s) that were not released!!! [ZkCollectionTerms]
[junit4] > org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.solr.cloud.ZkCollectionTerms
[junit4] > at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
[junit4] > at org.apache.solr.cloud.ZkCollectionTerms.<init>(ZkCollectionTerms.java:39)
[junit4] > at org.apache.solr.cloud.ZkController.getCollectionTerms(ZkController.java:1560)
[junit4] > at org.apache.solr.cloud.ZkController.getShardTerms(ZkController.java:1555)
[junit4] > at org.apache.solr.cloud.ZkController.register(ZkController.java:1115)
[junit4] > at org.apache.solr.cloud.ZkController.register(ZkController.java:1080)
[junit4] > at org.apache.solr.core.ZkContainer.lambda$registerInZk$0(ZkContainer.java:187)
[junit4] > at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > at __randomizedtesting.SeedInfo.seed([8D3EF5D4E26FC84F]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:304)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] Completed [4/833 (1!)] on J0 in 30.45s, 2 tests, 1 failure <<< FAILURES!
[...truncated 51744 lines...]
[asciidoctor:convert] asciidoctor: ERROR: about-this-guide.adoc: line 1: invalid part, must have at least one section (e.g., chapter, appendix, etc.)
[asciidoctor:convert] asciidoctor: ERROR: solr-glossary.adoc: line 1: invalid part, must have at least one section (e.g., chapter, appendix, etc.)
[java] Processed 2430 links (1982 relative) to 3170 anchors in 245 files
[echo] Validated Links & Anchors via: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-ref-guide/bare-bones-html/
-documentation-lint:
[jtidy] Checking for broken html (such as invalid tags)...
[delete] Deleting directory /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/jtidy_tmp
[echo] Checking for broken links...
[exec]
[exec] Crawl/parse...
[exec]
[exec] Verify...
[echo] Checking for malformed docs...
jar-checksums:
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
common.resolve:
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
jar-checksums:
[mkdir] Created dir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/null1227943022
[copy] Copying 35 files to /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/null1227943022
[delete] Deleting directory /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/null1227943022
resolve-example:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
resolve-server:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
jar-checksums:
[mkdir] Created dir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/null490136946
[copy] Copying 239 files to /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/null490136946
[delete] Deleting directory /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/null490136946
check-working-copy:
[ivy:cachepath] :: resolving dependencies :: org.eclipse.jgit#org.eclipse.jgit-caller;working
[ivy:cachepath] confs: [default]
[ivy:cachepath] found org.eclipse.jgit#org.eclipse.jgit;4.6.0.201612231935-r in public
[ivy:cachepath] found com.jcraft#jsch;0.1.53 in public
[ivy:cachepath] found com.googlecode.javaewah#JavaEWAH;1.1.6 in public
[ivy:cachepath] found org.apache.httpcomponents#httpclient;4.3.6 in public
[ivy:cachepath] found org.apache.httpcomponents#httpcore;4.3.3 in public
[ivy:cachepath] found commons-logging#commons-logging;1.1.3 in public
[ivy:cachepath] found commons-codec#commons-codec;1.6 in public
[ivy:cachepath] found org.slf4j#slf4j-api;1.7.2 in public
[ivy:cachepath] :: resolution report :: resolve 29ms :: artifacts dl 4ms
---------------------------------------------------------------------
| | modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| default | 8 | 0 | 0 | 0 || 8 | 0 |
---------------------------------------------------------------------
[wc-checker] Initializing working copy...
[wc-checker] SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
[wc-checker] SLF4J: Defaulting to no-operation (NOP) logger implementation
[wc-checker] SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
[wc-checker] Checking working copy status...
-jenkins-base:
BUILD SUCCESSFUL
Total time: 114 minutes 4 seconds
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[Lucene-Solr-7.x-Solaris] $ /bin/sh -xe /var/tmp/jenkins4389704165198252918.sh
+ mv lucene/build lucene/build.orig
+ mv solr/build solr/build.orig
+ ANT_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
+ export ANT_HOME
+ PATH=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/bin:/export/home/jenkins/tools/java/64bit/jdk1.8.0/bin:/usr/bin:/usr/sbin
+ export PATH
+ python3.4 -u dev-tools/scripts/reproduceJenkinsFailures.py --no-git https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/828/consoleText
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/828/consoleText
[repro] Revision: eeaf6fc7d10a66ad1687a901882a89d987443c24
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC"
[repro] Repro line: ant test -Dtestcase=TestManagedResourceStorage -Dtests.seed=8D3EF5D4E26FC84F -Dtests.slow=true -Dtests.locale=sr-Latn-RS -Dtests.timezone=Asia/Choibalsan -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[repro] ant clean
Buildfile: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/build.xml
clean:
clean:
clean:
BUILD SUCCESSFUL
Total time: 0 seconds
[repro] Test suites by module:
[repro] solr/core
[repro] TestManagedResourceStorage
[repro] ant compile-test
Buildfile: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/build.xml
check-analyzers-icu-uptodate:
jar-analyzers-icu:
-mrjar-classes-uptodate:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: Apache Ivy 2.4.0 - 20141213170938 :: http://ant.apache.org/ivy/ ::
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve-groovy:
[ivy:cachepath] :: resolving dependencies :: org.codehaus.groovy#groovy-all-caller;working
[ivy:cachepath] confs: [default]
[ivy:cachepath] found org.codehaus.groovy#groovy-all;2.4.15 in public
[ivy:cachepath] :: resolution report :: resolve 120ms :: artifacts dl 1ms
---------------------------------------------------------------------
| | modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| default | 1 | 0 | 0 | 0 || 1 | 0 |
---------------------------------------------------------------------
check-analyzers-common-uptodate:
jar-analyzers-common:
-mrjar-classes-uptodate:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve-groovy:
-check-git-state:
-git-cleanroot:
-copy-git-state:
[copy] Copying 1 file to /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build
git-autoclean:
resolve:
common.init:
compile-lucene-core:
-check-git-state:
-git-cleanroot:
-copy-git-state:
git-autoclean:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
init:
-clover.disable:
-clover.load:
-clover.classpath:
-clover.setup:
clover:
compile-core:
[mkdir] Created dir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java
[javac] Compiling 857 source files to /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java
[javac] Note: Some input files use or override a deprecated API.
[javac] Note: Recompile with -Xlint:deprecation for details.
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/util/graph/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/document/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/util/bkd/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/codecs/compressing/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/codecs/lucene60/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/codecs/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/util/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/index/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/util/fst/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/analysis/tokenattributes/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/search/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/codecs/lucene62/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/util/packed/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/util/mutable/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/codecs/lucene70/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/codecs/perfield/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/analysis/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/analysis/standard/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/store/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/codecs/blocktree/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/search/spans/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/search/similarities/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/util/automaton/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/codecs/lucene50/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/geo/package-info.class
[copy] Copying 3 files to /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java
init:
-clover.disable:
-clover.load:
-clover.classpath:
-clover.setup:
clover:
compile-core:
[mkdir] Created dir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java
[javac] Compiling 475 source files to /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java
[javac] Note: Some input files use or override a deprecated API.
[javac] Note: Recompile with -Xlint:deprecation for details.
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/hunspell/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/minhash/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/ga/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/ca/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/custom/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/sr/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/collation/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/sv/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/bg/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/payloads/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/ngram/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/core/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/bn/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/fr/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/tartarus/snowball/ext/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/br/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/miscellaneous/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/sinks/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/cjk/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/synonym/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/wikipedia/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/fa/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/reverse/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/nl/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/ro/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/fi/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/ru/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/no/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/el/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/ckb/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/tartarus/snowball/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/in/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/util/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/it/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/snowball/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/en/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/es/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/shingle/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/eu/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/ar/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/query/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/id/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/compound/hyphenation/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/charfilter/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/pt/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/lv/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/lt/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/hu/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/hy/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/pattern/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/cz/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/th/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/path/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/tr/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/da/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/de/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/hi/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/collation/tokenattributes/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/gl/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/commongrams/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java/org/apache/lucene/analysis/compound/package-info.class
[copy] Copying 40 files to /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java
patch-mrjar-classes:
[patch-cls] Remapped 0 class files for Java 9 to: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/classes/java9
[touch] Creating /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/analysis/common/patch-mrjar.stamp
-mrjar-check:
-mrjar-core:
-jar-core:
[exec] Execute failed: java.io.IOException: Cannot run program "git" (in directory "/export/home/jenkins/workspace/L
[...truncated too long message...]
] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 96846 ERROR (ProcessThread(sid:0 cport:37914):) [ ] o.a.z.s.ZooKeeperCriticalThread Severe unrecoverable error, from thread : ProcessThread(sid:0 cport:37914):
[junit4] 2> java.lang.InterruptedException: null
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2014) ~[?:1.8.0_172]
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2048) ~[?:1.8.0_172]
[junit4] 2> at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) ~[?:1.8.0_172]
[junit4] 2> at org.apache.zookeeper.server.PrepRequestProcessor.run(PrepRequestProcessor.java:123) [zookeeper-3.4.11.jar:3.4.11-37e277162d567b55a07d1755f0b31c32e93c01a0]
[junit4] 2> 96846 ERROR (ProcessThread(sid:0 cport:37914):) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 96849 WARN (Thread-3) [ ] o.a.s.c.ZkTestServer Server interrupted
[junit4] 2> java.lang.InterruptedException: null
[junit4] 2> at java.lang.Object.wait(Native Method) ~[?:1.8.0_172]
[junit4] 2> at java.lang.Thread.join(Thread.java:1252) ~[?:1.8.0_172]
[junit4] 2> at java.lang.Thread.join(Thread.java:1326) ~[?:1.8.0_172]
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.join(NIOServerCnxnFactory.java:313) ~[zookeeper-3.4.11.jar:3.4.11-37e277162d567b55a07d1755f0b31c32e93c01a0]
[junit4] 2> at org.apache.solr.cloud.ZkTestServer$ZKServerMain.runFromConfig(ZkTestServer.java:313) [java/:?]
[junit4] 2> at org.apache.solr.cloud.ZkTestServer$2.run(ZkTestServer.java:496) [java/:?]
[junit4] 2> Sep 25, 2018 2:13:52 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> SEVERE: There are still zombie threads that couldn't be terminated:
[junit4] 2> 1) Thread[id=62, name=NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0, state=RUNNABLE, group=TGRP-TestManagedResourceStorage]
[junit4] 2> at sun.nio.ch.IOUtil.drain(Native Method)
[junit4] 2> at sun.nio.ch.DevPollSelectorImpl.doSelect(DevPollSelectorImpl.java:109)
[junit4] 2> at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
[junit4] 2> at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:196)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=1930, maxMBSortInHeap=6.199651485272881, sim=RandomSimilarity(queryNorm=false): {}, locale=sr-Latn-RS, timezone=Asia/Choibalsan
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_172 (64-bit)/cpus=3,threads=3,free=45178112,total=100663296
[junit4] 2> NOTE: All tests run in this JVM: [TestManagedResourceStorage, TestManagedResourceStorage]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestManagedResourceStorage -Dtests.seed=8D3EF5D4E26FC84F -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=sr-Latn-RS -Dtests.timezone=Asia/Choibalsan -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J0 | TestManagedResourceStorage (suite) <<<
[junit4] > Throwable #1: java.lang.RuntimeException: SolrCore.getOpenCount()==2
[junit4] > at __randomizedtesting.SeedInfo.seed([8D3EF5D4E26FC84F]:0)
[junit4] > at org.apache.solr.util.TestHarness.close(TestHarness.java:380)
[junit4] > at org.apache.solr.SolrTestCaseJ4.deleteCore(SolrTestCaseJ4.java:802)
[junit4] > at org.apache.solr.cloud.AbstractZkTestCase.azt_afterClass(AbstractZkTestCase.java:147)
[junit4] > at java.lang.Thread.run(Thread.java:748)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: 4 threads leaked from SUITE scope at org.apache.solr.rest.TestManagedResourceStorage:
[junit4] > 1) Thread[id=63, name=SessionTracker, state=TIMED_WAITING, group=TGRP-TestManagedResourceStorage]
[junit4] > at java.lang.Object.wait(Native Method)
[junit4] > at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:147)
[junit4] > 2) Thread[id=61, name=Thread-3, state=WAITING, group=TGRP-TestManagedResourceStorage]
[junit4] > at java.lang.Object.wait(Native Method)
[junit4] > at java.lang.Thread.join(Thread.java:1252)
[junit4] > at java.lang.Thread.join(Thread.java:1326)
[junit4] > at org.apache.zookeeper.server.NIOServerCnxnFactory.join(NIOServerCnxnFactory.java:313)
[junit4] > at org.apache.solr.cloud.ZkTestServer$ZKServerMain.runFromConfig(ZkTestServer.java:313)
[junit4] > at org.apache.solr.cloud.ZkTestServer$2.run(ZkTestServer.java:496)
[junit4] > 3) Thread[id=62, name=NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0, state=RUNNABLE, group=TGRP-TestManagedResourceStorage]
[junit4] > at sun.nio.ch.DevPollArrayWrapper.poll0(Native Method)
[junit4] > at sun.nio.ch.DevPollArrayWrapper.poll(DevPollArrayWrapper.java:223)
[junit4] > at sun.nio.ch.DevPollSelectorImpl.doSelect(DevPollSelectorImpl.java:98)
[junit4] > at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
[junit4] > at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
[junit4] > at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:196)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > 4) Thread[id=65, name=ProcessThread(sid:0 cport:37914):, state=WAITING, group=TGRP-TestManagedResourceStorage]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at org.apache.zookeeper.server.PrepRequestProcessor.run(PrepRequestProcessor.java:123)
[junit4] > at __randomizedtesting.SeedInfo.seed([8D3EF5D4E26FC84F]:0)Throwable #3: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
[junit4] > 1) Thread[id=62, name=NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0, state=RUNNABLE, group=TGRP-TestManagedResourceStorage]
[junit4] > at sun.nio.ch.IOUtil.drain(Native Method)
[junit4] > at sun.nio.ch.DevPollSelectorImpl.doSelect(DevPollSelectorImpl.java:109)
[junit4] > at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
[junit4] > at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
[junit4] > at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:196)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > at __randomizedtesting.SeedInfo.seed([8D3EF5D4E26FC84F]:0)
[junit4] Completed [5/5 (1!)] on J0 in 87.06s, 2 tests, 3 errors <<< FAILURES!
[junit4]
[junit4]
[junit4] Tests with failures [seed: 8D3EF5D4E26FC84F]:
[junit4] - org.apache.solr.rest.TestManagedResourceStorage (suite)
[junit4]
[junit4]
[junit4] JVM J0: 0.70 .. 100.43 = 99.73s
[junit4] JVM J1: 0.70 .. 20.59 = 19.88s
[junit4] Execution time total: 1 minute 40 seconds
[junit4] Tests summary: 5 suites, 10 tests, 3 suite-level errors
BUILD FAILED
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/common-build.xml:1567: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/common-build.xml:1092: There were test failures: 5 suites, 10 tests, 3 suite-level errors [seed: 8D3EF5D4E26FC84F]
Total time: 1 minute 42 seconds
[repro] Setting last failure code to 256
[repro] Failures:
[repro] 1/5 failed: org.apache.solr.rest.TestManagedResourceStorage
[repro] Exiting with code 256
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting ANT_1_8_2_HOME=/export/home/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=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/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=eeaf6fc7d10a66ad1687a901882a89d987443c24, workspace=/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris
[WARNINGS] Computing warning deltas based on reference build #827
Recording test results
Setting ANT_1_8_2_HOME=/export/home/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=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2