Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2740/

2 tests failed.
FAILED:  org.apache.solr.cloud.autoscaling.sim.TestPolicyCloud.testDataProvider

Error Message:
Timeout waiting for collection to become active Live Nodes: 
[127.0.0.1:10102_solr, 127.0.0.1:10100_solr, 127.0.0.1:10104_solr, 
127.0.0.1:10101_solr, 127.0.0.1:10103_solr] Last available state: 
DocCollection(policiesTest//clusterstate.json/43)={   "replicationFactor":"1",  
 "pullReplicas":"0",   "router":{"name":"implicit"},   "maxShardsPerNode":"1",  
 "autoAddReplicas":"false",   "nrtReplicas":"2",   "tlogReplicas":"0",   
"autoCreated":"true",   "shards":{"shard1":{       "replicas":{         
"core_node10":{           "core":"policiesTest_shard1_replica_n1",           
"SEARCHER.searcher.maxDoc":0,           "SEARCHER.searcher.deletedDocs":0,      
     "INDEX.sizeInBytes":10240,           "node_name":"127.0.0.1:10104_solr",   
        "state":"active",           "type":"NRT",           
"INDEX.sizeInGB":9.5367431640625E-6,           "SEARCHER.searcher.numDocs":0},  
       "core_node11":{           "core":"policiesTest_shard1_replica_n2",       
    "SEARCHER.searcher.maxDoc":0,           "SEARCHER.searcher.deletedDocs":0,  
         "INDEX.sizeInBytes":10240,           
"node_name":"127.0.0.1:10103_solr",           "state":"active",           
"type":"NRT",           "INDEX.sizeInGB":9.5367431640625E-6,           
"SEARCHER.searcher.numDocs":0}},       "state":"active"}}}

Stack Trace:
java.lang.AssertionError: Timeout waiting for collection to become active
Live Nodes: [127.0.0.1:10102_solr, 127.0.0.1:10100_solr, 127.0.0.1:10104_solr, 
127.0.0.1:10101_solr, 127.0.0.1:10103_solr]
Last available state: DocCollection(policiesTest//clusterstate.json/43)={
  "replicationFactor":"1",
  "pullReplicas":"0",
  "router":{"name":"implicit"},
  "maxShardsPerNode":"1",
  "autoAddReplicas":"false",
  "nrtReplicas":"2",
  "tlogReplicas":"0",
  "autoCreated":"true",
  "shards":{"shard1":{
      "replicas":{
        "core_node10":{
          "core":"policiesTest_shard1_replica_n1",
          "SEARCHER.searcher.maxDoc":0,
          "SEARCHER.searcher.deletedDocs":0,
          "INDEX.sizeInBytes":10240,
          "node_name":"127.0.0.1:10104_solr",
          "state":"active",
          "type":"NRT",
          "INDEX.sizeInGB":9.5367431640625E-6,
          "SEARCHER.searcher.numDocs":0},
        "core_node11":{
          "core":"policiesTest_shard1_replica_n2",
          "SEARCHER.searcher.maxDoc":0,
          "SEARCHER.searcher.deletedDocs":0,
          "INDEX.sizeInBytes":10240,
          "node_name":"127.0.0.1:10103_solr",
          "state":"active",
          "type":"NRT",
          "INDEX.sizeInGB":9.5367431640625E-6,
          "SEARCHER.searcher.numDocs":0}},
      "state":"active"}}}
        at 
__randomizedtesting.SeedInfo.seed([5C3844A526EDE99A:6493EC90942FED43]:0)
        at 
org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:70)
        at 
org.apache.solr.cloud.autoscaling.sim.TestPolicyCloud.testDataProvider(TestPolicyCloud.java:324)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
org.junit.rules.ExpectedException$ExpectedExceptionStatement.evaluate(ExpectedException.java:110)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
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(policiesTest//clusterstate.json/43)={
  "replicationFactor":"1",
  "pullReplicas":"0",
  "router":{"name":"implicit"},
  "maxShardsPerNode":"1",
  "autoAddReplicas":"false",
  "nrtReplicas":"2",
  "tlogReplicas":"0",
  "autoCreated":"true",
  "shards":{"shard1":{
      "replicas":{
        "core_node10":{
          "core":"policiesTest_shard1_replica_n1",
          "SEARCHER.searcher.maxDoc":0,
          "SEARCHER.searcher.deletedDocs":0,
          "INDEX.sizeInBytes":10240,
          "node_name":"127.0.0.1:10104_solr",
          "state":"active",
          "type":"NRT",
          "INDEX.sizeInGB":9.5367431640625E-6,
          "SEARCHER.searcher.numDocs":0},
        "core_node11":{
          "core":"policiesTest_shard1_replica_n2",
          "SEARCHER.searcher.maxDoc":0,
          "SEARCHER.searcher.deletedDocs":0,
          "INDEX.sizeInBytes":10240,
          "node_name":"127.0.0.1:10103_solr",
          "state":"active",
          "type":"NRT",
          "INDEX.sizeInGB":9.5367431640625E-6,
          "SEARCHER.searcher.numDocs":0}},
      "state":"active"}}}
        at 
org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:112)
        at 
org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:64)
        ... 41 more


FAILED:  
org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration.testSearchRate

Error Message:
The trigger did not start in time

Stack Trace:
java.lang.AssertionError: The trigger did not start in time
        at 
__randomizedtesting.SeedInfo.seed([5C3844A526EDE99A:1705A2CE92B4FD5]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at 
org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration.testSearchRate(TestTriggerIntegration.java:1262)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 12660 lines...]
   [junit4] Suite: org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration
   [junit4]   2> Creating dataDir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.sim.TestTriggerIntegration_5C3844A526EDE99A-001/init-core-data-001
   [junit4]   2> 677002 INFO  
(SUITE-TestTriggerIntegration-seed#[5C3844A526EDE99A]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 677003 INFO  
(SUITE-TestTriggerIntegration-seed#[5C3844A526EDE99A]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 677003 INFO  
(SUITE-TestTriggerIntegration-seed#[5C3844A526EDE99A]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 677212 DEBUG 
(SUITE-TestTriggerIntegration-seed#[5C3844A526EDE99A]-worker) [    ] 
o.a.s.c.a.s.SimClusterStateProvider --- new Overseer leader: 
127.0.0.1:10000_solr
   [junit4]   2> 677212 DEBUG (Simulated OverseerAutoScalingTriggerThread) [    
] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and 
.scheduled_maintenance triggers
   [junit4]   2> 677213 DEBUG (Simulated OverseerAutoScalingTriggerThread) [    
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode 
version 0
   [junit4]   2> 677221 DEBUG (Simulated OverseerAutoScalingTriggerThread) [    
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion -1
   [junit4]   2> 677221 DEBUG (Simulated OverseerAutoScalingTriggerThread) [    
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 0
   [junit4]   2> 677236 INFO  
(SUITE-TestTriggerIntegration-seed#[5C3844A526EDE99A]-worker) [    ] 
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history 
in memory.
   [junit4] IGNOR/A 0.00s J1 | 
TestTriggerIntegration.testNodeAddedTriggerRestoreState
   [junit4]    > Assumption #1: 'badapple' test group is disabled 
(@BadApple(bugUrl=https://issues.apache.org/jira/browse/SOLR-12028))
   [junit4] IGNOR/A 0.01s J1 | TestTriggerIntegration.testTriggerThrottling
   [junit4]    > Assumption #1: 'badapple' test group is disabled 
(@BadApple(bugUrl=https://issues.apache.org/jira/browse/SOLR-12028))
   [junit4] IGNOR/A 0.00s J1 | TestTriggerIntegration.testEventQueue
   [junit4]    > Assumption #1: 'badapple' test group is disabled 
(@BadApple(bugUrl=https://issues.apache.org/jira/browse/SOLR-12028))
   [junit4]   2> 677255 DEBUG (Simulated OverseerAutoScalingTriggerThread) [    
] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial 
livenodes: [127.0.0.1:10001_solr, 127.0.0.1:10000_solr]
   [junit4]   2> 677258 DEBUG (Simulated OverseerAutoScalingTriggerThread) [    
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
   [junit4]   2> 677258 DEBUG (Simulated OverseerAutoScalingTriggerThread) [    
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion 0
   [junit4]   2> 677258 DEBUG (ScheduledTrigger-1135-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 677302 INFO  
(TEST-TestTriggerIntegration.testSearchRate-seed#[5C3844A526EDE99A]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testSearchRate
   [junit4]   2> 677302 DEBUG 
(TEST-TestTriggerIntegration.testSearchRate-seed#[5C3844A526EDE99A]) [    ] 
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 
1
   [junit4]   2> 677302 DEBUG (Simulated OverseerAutoScalingTriggerThread) [    
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
   [junit4]   2> 677323 DEBUG (Simulated OverseerAutoScalingTriggerThread) [    
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
   [junit4]   2> 677324 DEBUG (Simulated OverseerAutoScalingTriggerThread) [    
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
   [junit4]   2> 677324 DEBUG (Simulated OverseerAutoScalingTriggerThread) [    
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
   [junit4]   2> 677324 INFO  
(TEST-TestTriggerIntegration.testSearchRate-seed#[5C3844A526EDE99A]) [    ] 
o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and clearing 
object cache...
   [junit4]   2> 677325 WARN  (Simulated OverseerAutoScalingTriggerThread) [    
] o.a.s.c.a.OverseerTriggerThread Interrupted
   [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 
org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:189)
 [java/:?]
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
   [junit4]   2> 677327 DEBUG 
(TEST-TestTriggerIntegration.testSearchRate-seed#[5C3844A526EDE99A]) [    ] 
o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
   [junit4]   2> 677327 DEBUG 
(TEST-TestTriggerIntegration.testSearchRate-seed#[5C3844A526EDE99A]) [    ] 
o.a.s.c.a.ScheduledTriggers Shutting down action executor now
   [junit4]   2> 677327 DEBUG 
(TEST-TestTriggerIntegration.testSearchRate-seed#[5C3844A526EDE99A]) [    ] 
o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
   [junit4]   2> 677327 DEBUG 
(TEST-TestTriggerIntegration.testSearchRate-seed#[5C3844A526EDE99A]) [    ] 
o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool 
executor
   [junit4]   2> 677331 DEBUG 
(TEST-TestTriggerIntegration.testSearchRate-seed#[5C3844A526EDE99A]) [    ] 
o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
   [junit4]   2> 677331 DEBUG 
(TEST-TestTriggerIntegration.testSearchRate-seed#[5C3844A526EDE99A]) [    ] 
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
   [junit4]   2> 677335 DEBUG (Simulated OverseerAutoScalingTriggerThread) [    
] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and 
.scheduled_maintenance triggers
   [junit4]   2> 677335 DEBUG (Simulated OverseerAutoScalingTriggerThread) [    
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode 
version 2
   [junit4]   2> 677335 DEBUG (Simulated OverseerAutoScalingTriggerThread) [    
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion -1
   [junit4]   2> 677335 DEBUG (Simulated OverseerAutoScalingTriggerThread) [    
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
   [junit4]   2> 677336 DEBUG (Simulated OverseerAutoScalingTriggerThread) [    
] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial 
livenodes: [127.0.0.1:10001_solr, 127.0.0.1:10000_solr]
   [junit4]   2> 677336 DEBUG (Simulated OverseerAutoScalingTriggerThread) [    
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
   [junit4]   2> 677336 DEBUG (Simulated OverseerAutoScalingTriggerThread) [    
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
   [junit4]   2> 677336 DEBUG (ScheduledTrigger-1140-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 677372 DEBUG (ScheduledTrigger-1140-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 677408 DEBUG (ScheduledTrigger-1140-thread-4) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 677428 DEBUG (ScheduledTrigger-1140-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 677449 DEBUG (ScheduledTrigger-1140-thread-3) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 677482 DEBUG (ScheduledTrigger-1140-thread-4) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 677502 DEBUG (ScheduledTrigger-1140-thread-4) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 677503 DEBUG (simCloudManagerPool-1139-thread-1) [    ] 
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 677503 DEBUG (simCloudManagerPool-1139-thread-1) [    ] 
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 
3
   [junit4]   2> 677504 DEBUG (Simulated OverseerAutoScalingTriggerThread) [    
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
   [junit4]   2> 677504 DEBUG (Simulated OverseerAutoScalingTriggerThread) [    
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
   [junit4]   2> 677504 DEBUG (Simulated OverseerAutoScalingTriggerThread) [    
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
   [junit4]   2> 677522 DEBUG (ScheduledTrigger-1140-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 677560 DEBUG (ScheduledTrigger-1140-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 677595 DEBUG (ScheduledTrigger-1140-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 677619 DEBUG (ScheduledTrigger-1140-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 677621 DEBUG (simCloudManagerPool-1139-thread-2) [    ] 
o.a.s.c.a.s.SimClusterStateProvider -- submit leader election for .system / 
shard1
   [junit4]   2> 677623 DEBUG (simCloudManagerPool-1139-thread-3) [    ] 
o.a.s.c.a.s.SimClusterStateProvider Running leader election for .system / shard1
   [junit4]   2> 677666 DEBUG (ScheduledTrigger-1140-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 677681 DEBUG (simCloudManagerPool-1139-thread-3) [    ] 
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for .system / shard1: 
core_node1
   [junit4]   2> 677692 DEBUG (ScheduledTrigger-1140-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 677704 DEBUG (simCloudManagerPool-1139-thread-5) [    ] 
o.a.s.c.a.s.SimClusterStateProvider -- submit leader election for collection1 / 
shard1
   [junit4]   2> 677704 DEBUG (simCloudManagerPool-1139-thread-7) [    ] 
o.a.s.c.a.s.SimClusterStateProvider Running leader election for collection1 / 
shard1
   [junit4]   2> 677705 DEBUG (simCloudManagerPool-1139-thread-7) [    ] 
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for collection1 / 
shard1: core_node1
   [junit4]   2> 677717 DEBUG (ScheduledTrigger-1140-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 677717 DEBUG (simCloudManagerPool-1139-thread-6) [    ] 
o.a.s.c.a.s.SimClusterStateProvider -- already has leader collection1 / shard1
   [junit4]   2> 677749 DEBUG (ScheduledTrigger-1140-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 677770 DEBUG (ScheduledTrigger-1140-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 677790 DEBUG (ScheduledTrigger-1140-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 677822 DEBUG (ScheduledTrigger-1140-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 677830 DEBUG (simCloudManagerPool-1139-thread-8) [    ] 
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 677830 DEBUG (simCloudManagerPool-1139-thread-8) [    ] 
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 
4
   [junit4]   2> 677843 DEBUG (ScheduledTrigger-1140-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 677855 DEBUG (Simulated OverseerAutoScalingTriggerThread) [    
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4
   [junit4]   2> 677855 DEBUG (Simulated OverseerAutoScalingTriggerThread) [    
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
   [junit4]   2> 677855 DEBUG (Simulated OverseerAutoScalingTriggerThread) [    
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion 4
   [junit4]   2> 677862 DEBUG (ScheduledTrigger-1140-thread-4) [    ] 
o.a.s.c.a.SearchRateTrigger ### rates for node 127.0.0.1:10001_solr
   [junit4]   2> 677863 DEBUG (ScheduledTrigger-1140-thread-3) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 677880 DEBUG (ScheduledTrigger-1140-thread-4) [    ] 
o.a.s.c.a.SearchRateTrigger ### rates for node 127.0.0.1:10000_solr
   [junit4]   2> 677896 DEBUG (simCloudManagerPool-1139-thread-9) [    ] 
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 677896 DEBUG (simCloudManagerPool-1139-thread-9) [    ] 
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 
5
   [junit4]   2> 677896 DEBUG (ScheduledTrigger-1140-thread-3) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 677896 DEBUG (Simulated OverseerAutoScalingTriggerThread) [    
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 5
   [junit4]   2> 677896 DEBUG (Simulated OverseerAutoScalingTriggerThread) [    
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
   [junit4]   2> 677897 DEBUG (Simulated OverseerAutoScalingTriggerThread) [    
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVersion 5
   [junit4]   2> 677919 DEBUG (ScheduledTrigger-1140-thread-4) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 677948 DEBUG (ScheduledTrigger-1140-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 677973 DEBUG (ScheduledTrigger-1140-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 677985 DEBUG (ScheduledTrigger-1140-thread-3) [    ] 
o.a.s.c.a.SearchRateTrigger ### rates for node 127.0.0.1:10001_solr
   [junit4]   2> 677985 DEBUG (ScheduledTrigger-1140-thread-3) [    ] 
o.a.s.c.a.SearchRateTrigger ###  
metrics:solr.core.collection1.shard1.replica_n2:QUERY./select.requestTimes:1minRate
      250
   [junit4]   2> 677987 DEBUG (ScheduledTrigger-1140-thread-3) [    ] 
o.a.s.c.a.SearchRateTrigger ### rates for node 127.0.0.1:10000_solr
   [junit4]   2> 677987 DEBUG (ScheduledTrigger-1140-thread-3) [    ] 
o.a.s.c.a.SearchRateTrigger ###  
metrics:solr.core.collection1.shard1.replica_n1:QUERY./select.requestTimes:1minRate
      250
   [junit4]   2> 677987 DEBUG (ScheduledTrigger-1140-thread-3) [    ] 
o.a.s.c.a.SearchRateTrigger ## Collection: collection1
   [junit4]   2> 677988 DEBUG (ScheduledTrigger-1140-thread-3) [    ] 
o.a.s.c.a.SearchRateTrigger ##  - shard1
   [junit4]   2> 677988 DEBUG (ScheduledTrigger-1140-thread-3) [    ] 
o.a.s.c.a.SearchRateTrigger ##     collection1_shard1_replica_n2  250.0
   [junit4]   2> 677988 DEBUG (ScheduledTrigger-1140-thread-3) [    ] 
o.a.s.c.a.SearchRateTrigger ##     collection1_shard1_replica_n1  250.0
   [junit4]   2> 677993 DEBUG (ScheduledTrigger-1140-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 678029 DEBUG (ScheduledTrigger-1140-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 678050 DEBUG (ScheduledTrigger-1140-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 678098 DEBUG (ScheduledTrigger-1140-thread-3) [    ] 
o.a.s.c.a.SearchRateTrigger -- collection1.shard1: totalShardRate=500.0, 
shardRate=250.0
   [junit4]   2> 678111 DEBUG (ScheduledTrigger-1140-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 678131 DEBUG (ScheduledTrigger-1140-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 678152 DEBUG (ScheduledTrigger-1140-thread-3) [    ] 
o.a.s.c.a.SearchRateTrigger ### rates for node 127.0.0.1:10001_solr
   [junit4]   2> 678168 DEBUG (ScheduledTrigger-1140-thread-3) [    ] 
o.a.s.c.a.SearchRateTrigger ###  
metrics:solr.core.collection1.shard1.replica_n2:QUERY./select.requestTimes:1minRate
      250
   [junit4]   2> 678168 DEBUG (ScheduledTrigger-1140-thread-3) [    ] 
o.a.s.c.a.SearchRateTrigger ### rates for node 127.0.0.1:10000_solr
   [junit4]   2> 678168 DEBUG (ScheduledTrigger-1140-thread-3) [    ] 
o.a.s.c.a.SearchRateTrigger ###  
metrics:solr.core.collection1.shard1.replica_n1:QUERY./select.requestTimes:1minRate
      250
   [junit4]   2> 678168 DEBUG (ScheduledTrigger-1140-thread-3) [    ] 
o.a.s.c.a.SearchRateTrigger ## Collection: collection1
   [junit4]   2> 678168 DEBUG (ScheduledTrigger-1140-thread-3) [    ] 
o.a.s.c.a.SearchRateTrigger ##  - shard1
   [junit4]   2> 678168 DEBUG (ScheduledTrigger-1140-thread-3) [    ] 
o.a.s.c.a.SearchRateTrigger ##     collection1_shard1_replica_n2  250.0
   [junit4]   2> 678168 DEBUG (ScheduledTrigger-1140-thread-3) [    ] 
o.a.s.c.a.SearchRateTrigger ##     collection1_shard1_replica_n1  250.0
   [junit4]   2> 678168 DEBUG (ScheduledTrigger-1140-thread-3) [    ] 
o.a.s.c.a.SearchRateTrigger -- collection1.shard1: totalShardRate=500.0, 
shardRate=250.0
   [junit4]   2> 678184 DEBUG (ScheduledTrigger-1140-thread-4) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 678204 DEBUG (ScheduledTrigger-1140-thread-4) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 678257 DEBUG (ScheduledTrigger-1140-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 678313 DEBUG (ScheduledTrigger-1140-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 678334 DEBUG (ScheduledTrigger-1140-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2
   [junit4]   2> 678348 DEBUG (ScheduledTrigger-1140-thread-3) [    ] 
o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
   [junit4]   2>   "id":"3e4bcecdcb83b7Ta3cq93xc7bvqebfw4a5k7wqrn",
   [junit4]   2>   "source":"search_rate_trigger",
   [junit4]   2>   "eventTime":17534800144073655,
   [junit4]   2>   "eventType":"SEARCHRATE",
   [junit4]   2>   "properties":{
   [junit4]   2>     "hotReplicas":[
   [junit4]   2>       "{\"core_node2\":{\n    
\"core\":\"collection1_shard1_replica_n2\",\n    \"shard\":\"shard1\",\n    
\"collection\":\"collection1\",\n    \"node_name\":\"127.0.0.1:10001_solr\",\n  
  \"type\":\"NRT\",\n    \"SEARCHER.searcher.maxDoc\":0,\n    
\"SEARCHER.searcher.deletedDocs\":0,\n    \"INDEX.sizeInBytes\":10240,\n    
\"rate\":250.0,\n    \"state\":\"active\",\n    
\"QUERY./select.requestTimes:1minRate\":250,\n    
\"INDEX.sizeInGB\":9.5367431640625E-6,\n    \"SEARCHER.searcher.numDocs\":0}}",
   [junit4]   2>       "{\"core_node1\":{\n    
\"core\":\"collection1_shard1_replica_n1\",\n    \"shard\":\"shard1\",\n    
\"collection\":\"collection1\",\n    \"node_name\":\"127.0.0.1:10000_solr\",\n  
  \"type\":\"NRT\",\n    \"leader\":\"true\",\n    
\"SEARCHER.searcher.maxDoc\":0,\n    \"SEARCHER.searcher.deletedDocs\":0,\n    
\"INDEX.sizeInBytes\":10240,\n    \"rate\":250.0,\n    \"state\":\"active\",\n  
  \"QUERY./select.requestTimes:1minRate\":250,\n    
\"INDEX.sizeInGB\":9.5367431640625E-6,\n    \"SEARCHER.searcher.numDocs\":0}}"],
   [junit4]   2>     "coldNodes":{},
   [junit4]   2>     "coldShards":{},
   [junit4]   2>     "coldReplicas":[],
   [junit4]   2>     "violationType":["hotShards"],
   [junit4]   2>     "coldCollections":{},
   [junit4]   2>     "hotShards":{"collection1":{"shard1":250.0}},
   [junit4]   2>     "hotCollections":{"collection1":500.0},
   [junit4]   2>     "hotNodes":{
   [junit4]   2>       "127.0.0.1:10001_solr":250.0,
   [junit4]   2>       "127.0.0.1:10000_solr":250.0},
   [junit4]   2>     "requestedOps":[
   [junit4]   2>       "Op{action=ADDREPLICA, hints={COLL_SHARD=[{\n  
\"first\":\"collection1\",\n  \"second\":\"shard1\"}]}}",
   [junit4]   2>       "Op{action=ADDREPLICA, hints={COLL_SHARD=[{\n  
\"first\":\"collection1\",\n  \"second\":\"shard1\"}]}}",
   [junit4]   2>       "Op{action=ADDREPLICA, hints={COLL_SHARD=[{\n  
\"first\":\"collection1\",\n  \"second\":\"shard1\"}]}}"]}}
   [junit4]   2> 678348 DEBUG (ScheduledTrigger-1140-thread-3) [    ] 
o.a.s.c.a.ScheduledTriggers Pausing all triggers: [.auto_add_replicas, 
search_rate_trigger]
   [junit4]   2> 678350 INFO  
(TEST-TestTriggerIntegration.testSearchRate-seed#[5C3844A526EDE99A]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testSearchRate
   [junit4]   2> 678354 INFO  
(TEST-TestTriggerIntegration.testSearchRate-seed#[5C3844A526EDE99A]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase #######################################
   [junit4]   2> ############ CLUSTER STATE ############
   [junit4]   2> #######################################
   [junit4]   2> ## Live nodes:         2
   [junit4]   2> ## Empty nodes:        0
   [junit4]   2> ## Dead nodes:         0
   [junit4]   2> ## Collections:        [collection1, .system]
   [junit4]   2> ## Max replicas per node:      2
   [junit4]   2> ## Min replicas per node:      1
   [junit4]   2> ## Total replicas:             3
   [junit4]   2> ## * .system           1
   [junit4]   2> ##             - active           1
   [junit4]   2> ## * collection1               2
   [junit4]   2> ##             - active           2
   [junit4]   2> ######### Solr op counts ##########
   [junit4]   2> ##             - CREATE             1
   [junit4]   2> ##             - autoscaling        3
   [junit4]   2> ######### Autoscaling event counts ###########
   [junit4]   2> 
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestTriggerIntegration -Dtests.method=testSearchRate 
-Dtests.seed=5C3844A526EDE99A -Dtests.multiplier=2 -Dtests.slow=true 
-Dtests.locale=ja-JP-u-ca-japanese-x-lvariant-JP 
-Dtests.timezone=America/Glace_Bay -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE 1.13s J1 | TestTriggerIntegration.testSearchRate <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: The trigger did not 
start in time
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([5C3844A526EDE99A:1705A2CE92B4FD5]:0)
   [junit4]    >        at 
org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration.testSearchRate(TestTriggerIntegration.java:1262)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4] IGNOR/A 0.00s J1 | TestTriggerIntegration.testEventFromRestoredState
   [junit4]    > Assumption #1: 'badapple' test group is disabled 
(@BadApple(bugUrl=https://issues.apache.org/jira/browse/SOLR-12028))
   [junit4] IGNOR/A 0.00s J1 | TestTriggerIntegration.testNodeAddedTrigger
   [junit4]    > Assumption #1: 'badapple' test group is disabled 
(@BadApple(bugUrl=https://issues.apache.org/jira/browse/SOLR-12028))
   [junit4] IGNOR/A 0.00s J1 | TestTriggerIntegration.testCooldown
   [junit4]    > Assumption #1: 'badapple' test group is disabled 
(@BadApple(bugUrl=https://issues.apache.org/jira/browse/SOLR-12028))
   [junit4] IGNOR/A 0.00s J1 | TestTriggerIntegration.testListeners
   [junit4]    > Assumption #1: 'badapple' test group is disabled 
(@BadApple(bugUrl=https://issues.apache.org/jira/browse/SOLR-12028))
   [junit4] IGNOR/A 0.00s J1 | 
TestTriggerIntegration.testNodeLostTriggerRestoreState
   [junit4]    > Assumption #1: 'badapple' test group is disabled 
(@BadApple(bugUrl=https://issues.apache.org/jira/browse/SOLR-12028))
   [junit4] IGNOR/A 0.00s J1 | TestTriggerIntegration.testNodeLostTrigger
   [junit4]    > Assumption #1: 'badapple' test group is disabled 
(@BadApple(bugUrl=https://issues.apache.org/jira/browse/SOLR-12028))
   [junit4] IGNOR/A 0.00s J1 | 
TestTriggerIntegration.testNodeMarkersRegistration
   [junit4]    > Assumption #1: 'badapple' test group is disabled 
(@BadApple(bugUrl=https://issues.apache.org/jira/browse/SOLR-12028))
   [junit4]   2> 678400 DEBUG (AutoscalingActionExecutor-1141-thread-1) [    ] 
o.a.s.c.a.ScheduledTriggers -- processing actions for {
   [junit4]   2>   "id":"3e4bcecdcb83b7Ta3cq93xc7bvqebfw4a5k7wqrn",
   [junit4]   2>   "source":"search_rate_trigger",
   [junit4]   2>   "eventTime":17534800144073655,
   [junit4]   2>   "eventType":"SEARCHRATE",
   [junit4]   2>   "properties":{
   [junit4]   2>     "hotReplicas":[
   [junit4]   2>       "{\"core_node2\":{\n    
\"core\":\"collection1_shard1_replica_n2\",\n    \"shard\":\"shard1\",\n    
\"collection\":\"collection1\",\n    \"node_name\":\"127.0.0.1:10001_solr\",\n  
  \"type\":\"NRT\",\n    \"SEARCHER.searcher.maxDoc\":0,\n    
\"SEARCHER.searcher.deletedDocs\":0,\n    \"INDEX.sizeInBytes\":10240,\n    
\"rate\":250.0,\n    \"state\":\"active\",\n    
\"QUERY./select.requestTimes:1minRate\":250,\n    
\"INDEX.sizeInGB\":9.5367431640625E-6,\n    \"SEARCHER.searcher.numDocs\":0}}",
   [junit4]   2>       "{\"core_node1\":{\n    
\"core\":\"collection1_shard1_replica_n1\",\n    \"shard\":\"shard1\",\n    
\"collection\":\"collection1\",\n    \"node_name\":\"127.0.0.1:10000_solr\",\n  
  \"type\":\"NRT\",\n    \"leader\":\"true\",\n    
\"SEARCHER.searcher.maxDoc\":0,\n    \"SEARCHER.searcher.deletedDocs\":0,\n    
\"INDEX.sizeInBytes\":10240,\n    \"rate\":250.0,\n    \"state\":\"active\",\n  
  \"QUERY./select.requestTimes:1minRate\":250,\n    
\"INDEX.sizeInGB\":9.5367431640625E-6,\n    \"SEARCHER.searcher.numDocs\":0}}"],
   [junit4]   2>     "coldNodes":{},
   [junit4]   2>     "coldShards":{},
   [junit4]   2>     "coldReplicas":[],
   [junit4]   2>     "violationType":["hotShards"],
   [junit4]   2>     "coldCollections":{},
   [junit4]   2>     "hotShards":{"collection1":{"shard1":250.0}},
   [junit4]   2>     "hotCollections":{"collection1":500.0},
   [junit4]   2>     "hotNodes":{
   [junit4]   2>       "127.0.0.1:10001_solr":250.0,
   [junit4]   2>       "127.0.0.1:10000_solr":250.0},
   [junit4]   2>     "_enqueue_time_":17534818172223805,
   [junit4]   2>     "requestedOps":[
   [junit4]   2>       "Op{action=ADDREPLICA, hints={COLL_SHARD=[{\n  
\"first\":\"collection1\",\n  \"second\":\"shard1\"}]}}",
   [junit4]   2>       "Op{action=ADDREPLICA, hints={COLL_SHARD=[{\n  
\"first\":\"collection1\",\n  \"second\":\"shard1\"}]}}",
   [junit4]   2>       "Op{action=ADDREPLICA, hints={COLL_SHARD=[{\n  
\"first\":\"collection1\",\n  \"second\":\"shard1\"}]}}"]}}
   [junit4]   2> 678402 DEBUG (AutoscalingActionExecutor-1141-thread-1) [    ] 
o.a.s.c.a.ComputePlanAction -- processing event: {
   [junit4]   2>   "id":"3e4bcecdcb83b7Ta3cq93xc7bvqebfw4a5k7wqrn",
   [junit4]   2>   "source":"search_rate_trigger",
   [junit4]   2>   "eventTime":17534800144073655,
   [junit4]   2>   "eventType":"SEARCHRATE",
   [junit4]   2>   "properties":{
   [junit4]   2>     "hotReplicas":[
   [junit4]   2>       "{\"core_node2\":{\n    
\"core\":\"collection1_shard1_replica_n2\",\n    \"shard\":\"shard1\",\n    
\"collection\":\"collection1\",\n    \"node_name\":\"127.0.0.1:10001_solr\",\n  
  \"type\":\"NRT\",\n    \"SEARCHER.searcher.maxDoc\":0,\n    
\"SEARCHER.searcher.deletedDocs\":0,\n    \"INDEX.sizeInBytes\":10240,\n    
\"rate\":250.0,\n    \"state\":\"active\",\n    
\"QUERY./select.requestTimes:1minRate\":250,\n    
\"INDEX.sizeInGB\":9.5367431640625E-6,\n    \"SEARCHER.searcher.numDocs\":0}}",
   [junit4]   2>       "{\"core_node1\":{\n    
\"core\":\"collection1_shard1_replica_n1\",\n    \"shard\":\"shard1\",\n    
\"collection\":\"collection1\",\n    \"node_name\":\"127.0.0.1:10000_solr\",\n  
  \"type\":\"NRT\",\n    \"leader\":\"true\",\n    
\"SEARCHER.searcher.maxDoc\":0,\n    \"SEARCHER.searcher.deletedDocs\":0,\n    
\"INDEX.sizeInBytes\":10240,\n    \"rate\":250.0,\n    \"state\":\"active\",\n  
  \"QUERY./select.requestTimes:1minRate\":250,\n    
\"INDEX.sizeInGB\":9.5367431640625E-6,\n    \"SEARCHER.searcher.numDocs\":0}}"],
   [junit4]   2>     "coldNodes":{},
   [junit4]   2>     "coldShards":{},
   [junit4]   2>     "coldReplicas":[],
   [junit4]   2>     "violationType":["hotShards"],
   [junit4]   2>     "coldCollections":{},
   [junit4]   2>     "hotShards":{"collection1":{"shard1":250.0}},
   [junit4]   2>     "hotCollections":{"collection1":500.0},
   [junit4]   2>     "hotNodes":{
   [junit4]   2>       "127.0.0.1:10001_solr":250.0,
   [junit4]   2>       "127.0.0.1:10000_solr":250.0},
   [junit4]   2>     "_enqueue_time_":17534818172223805,
   [junit4]   2>     "requestedOps":[
   [junit4]   2>       "Op{action=ADDREPLICA, hints={COLL_SHARD=[{\n  
\"first\":\"collection1\",\n  \"second\":\"shard1\"}]}}",
   [junit4]   2>       "Op{action=ADDREPLICA, hints={COLL_SHARD=[{\n  
\"first\":\"collection1\",\n  \"second\":\"shard1\"}]}}",
   [junit4]   2>       "Op{action=ADDREPLICA, hints={COLL_SHARD=[{\n  
\"first\":\"collection1\",\n  \"second\":\"shard1\"}]}}"]}} with context 
properties: {AFTER_ACTION=[start], BEFORE_ACTION=[start, compute]}
   [junit4]   2> 678423 DEBUG 
(SUITE-TestTriggerIntegration-seed#[5C3844A526EDE99A]-worker) [    ] 
o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
   [junit4]   2> 678423 DEBUG 
(SUITE-TestTriggerIntegration-seed#[5C3844A526EDE99A]-worker) [    ] 
o.a.s.c.a.ScheduledTriggers Shutting down action executor now
   [junit4]   2> 678423 DEBUG 
(SUITE-TestTriggerIntegration-seed#[5C3844A526EDE99A]-worker) [    ] 
o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
   [junit4]   2> 678437 WARN  (Simulated OverseerAutoScalingTriggerThread) [    
] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are 
closed, exiting.
   [junit4]   2> 678464 INFO  (AutoscalingActionExecutor-1141-thread-1) [    ] 
o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 17534823944 
sessionWrapper.createTime 17534823822638955, this.sessionWrapper.createTime 
17534823822638955 
   [junit4]   2> 678499 WARN  (AutoscalingActionExecutor-1141-thread-1) [    ] 
o.a.s.c.a.SystemLogListener Exception sending event to collection .system
   [junit4]   2> java.io.IOException: java.lang.InterruptedException
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.sim.SimCloudManager.request(SimCloudManager.java:632)
 ~[test/:?]
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.SystemLogListener.onEvent(SystemLogListener.java:118)
 ~[java/:?]
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.ScheduledTriggers$TriggerListeners.fireListeners(ScheduledTriggers.java:792)
 ~[java/:?]
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:310)
 ~[java/:?]
   [junit4]   2>        at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) 
~[?:1.8.0_172]
   [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> Caused by: java.lang.InterruptedException
   [junit4]   2>        at 
java.util.concurrent.FutureTask.awaitDone(FutureTask.java:404) ~[?:1.8.0_172]
   [junit4]   2>        at 
java.util.concurrent.FutureTask.get(FutureTask.java:191) ~[?:1.8.0_172]
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.sim.SimCloudManager.request(SimCloudManager.java:630)
 ~[test/:?]
   [junit4]   2>        ... 9 more
   [junit4]   2> 678501 WARN  (AutoscalingActionExecutor-1141-thread-1) [    ] 
o.a.s.c.a.ScheduledTriggers Exception executing actions
   [junit4]   2> org.apache.solr.cloud.autoscaling.TriggerActionException: 
Error processing action for trigger event: {
   [junit4]   2>   "id":"3e4bcecdcb83b7Ta3cq93xc7bvqebfw4a5k7wqrn",
   [junit4]   2>   "source":"search_rate_trigger",
   [junit4]   2>   "eventTime":17534800144073655,
   [junit4]   2>   "eventType":"SEARCHRATE",
   [junit4]   2>   "properties":{
   [junit4]   2>     "hotReplicas":[
   [junit4]   2>       "{\"core_node2\":{\n    
\"core\":\"collection1_shard1_replica_n2\",\n    \"shard\":\"shard1\",\n    
\"collection\":\"collection1\",\n    \"node_name\":\"127.0.0.1:10001_solr\",\n  
  \"type\":\"NRT\",\n    \"SEARCHER.searcher.maxDoc\":0,\n    
\"SEARCHER.searcher.deletedDocs\":0,\n    \"INDEX.sizeInBytes\":10240,\n    
\"rate\":250.0,\n    \"state\":\"active\",\n    
\"QUERY./select.requestTimes:1minRate\":250,\n    
\"INDEX.sizeInGB\":9.5367431640625E-6,\n    \"SEARCHER.searcher.numDocs\":0}}",
   [junit4]   2>       "{\"core_node1\":{\n    
\"core\":\"collection1_shard1_replica_n1\",\n    \"shard\":\"shard1\",\n    
\"collection\":\"collection1\",\n    \"node_name\":\"127.0.0.1:10000_solr\",\n  
  \"type\":\"NRT\",\n    \"leader\":\"true\",\n    
\"SEARCHER.searcher.maxDoc\":0,\n    \"SEARCHER.searcher.deletedDocs\":0,\n    
\"INDEX.sizeInBytes\":10240,\n    \"rate\":250.0,\n    \"state\":\"active\",\n  
  \"QUERY./select.requestTimes:1minRate\":250,\n    
\"INDEX.sizeInGB\":9.5367431640625E-6,\n    \"SEARCHER.searcher.numDocs\":0}}"],
   [junit4]   2>     "__start__":1,
   [junit4]   2>     "coldNodes":{},
   [junit4]   2>     "coldShards":{},
   [junit4]   2>     "coldReplicas":[],
   [junit4]   2>     "violationType":["hotShards"],
   [junit4]   2>     "coldCollections":{},
   [junit4]   2>     "hotShards":{"collection1":{"shard1":250.0}},
   [junit4]   2>     "hotCollections":{"collection1":500.0},
   [junit4]   2>     "hotNodes":{
   [junit4]   2>       "127.0.0.1:10001_solr":250.0,
   [junit4]   2>       "127.0.0.1:10000_solr":250.0},
   [junit4]   2>     "_enqueue_time_":17534818172223805,
   [junit4]   2>     "requestedOps":[
   [junit4]   2>       "Op{action=ADDREPLICA, hints={COLL_SHARD=[{\n  
\"first\":\"collection1\",\n  \"second\":\"shard1\"}]}}",
   [junit4]   2>       "Op{action=ADDREPLICA, hints={COLL_SHARD=[{\n  
\"first\":\"collection1\",\n  \"second\":\"shard1\"}]}}",
   [junit4]   2>       "Op{action=ADDREPLICA, hints={COLL_SHARD=[{\n  
\"first\":\"collection1\",\n  \"second\":\"shard1\"}]}}"]}}
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:311)
 ~[java/:?]
   [junit4]   2>        at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) 
~[?:1.8.0_172]
   [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> Caused by: org.apache.solr.common.SolrException: Unexpected 
exception while processing event: {
   [junit4]   2>   "id":"3e4bcecdcb83b7Ta3cq93xc7bvqebfw4a5k7wqrn",
   [junit4]   2>   "source":"search_rate_trigger",
   [junit4]   2>   "eventTime":17534800144073655,
   [junit4]   2>   "eventType":"SEARCHRATE",
   [junit4]   2>   "properties":{
   [junit4]   2>     "hotReplicas":[
   [junit4]   2>       "{\"core_node2\":{\n    
\"core\":\"collection1_shard1_replica_n2\",\n    \"shard\":\"shard1\",\n    
\"collection\":\"collection1\",\n    \"node_name\":\"127.0.0.1:10001_solr\",\n  
  \"type\":\"NRT\",\n    \"SEARCHER.searcher.maxDoc\":0,\n    
\"SEARCHER.searcher.deletedDocs\":0,\n    \"INDEX.sizeInBytes\":10240,\n    
\"rate\":250.0,\n    \"state\":\"active\",\n    
\"QUERY./select.requestTimes:1minRate\":250,\n    
\"INDEX.sizeInGB\":9.5367431640625E-6,\n    \"SEARCHER.searcher.numDocs\":0}}",
   [junit4]   2>       "{\"core_node1\":{\n    
\"core\":\"collection1_shard1_replica_n1\",\n    \"shard\":\"shard1\",\n    
\"collection\":\"collection1\",\n    \"node_name\":\"127.0.0.1:10000_solr\",\n  
  \"type\":\"NRT\",\n    \"leader\":\"true\",\n    
\"SEARCHER.searcher.maxDoc\":0,\n    \"SEARCHER.searcher.deletedDocs\":0,\n    
\"INDEX.sizeInBytes\":10240,\n    \"rate\":250.0,\n    \"state\":\"active\",\n  
  \"QUERY./select.requestTimes:1minRate\":250,\n    
\"INDEX.sizeInGB\":9.5367431640625E-6,\n    \"SEARCHER.searcher.numDocs\":0}}"],
   [junit4]   2>     "__start__":1,
   [junit4]   2>     "coldNodes":{},
   [junit4]   2>     "coldShards":{},
   [junit4]   2>     "coldReplicas":[],
   [junit4]   2>     "violationType":["hotShards"],
   [junit4]   2>     "coldCollections":{},
   [junit4]   2>     "hotShards":{"collection1":{"shard1":250.0}},
   [junit4]   2>     "hotCollections":{"collection1":500.0},
   [junit4]   2>     "hotNodes":{
   [junit4]   2>       "127.0.0.1:10001_solr":250.0,
   [junit4]   2>       "127.0.0.1:10000_solr":250.0},
   [junit4]   2>     "_enqueue_time_":17534818172223805,
   [junit4]   2>     "requestedOps":[
   [junit4]   2>       "Op{action=ADDREPLICA, hints={COLL_SHARD=[{\n  
\"first\":\"collection1\",\n  \"second\":\"shard1\"}]}}",
   [junit4]   2>       "Op{action=ADDREPLICA, hints={COLL_SHARD=[{\n  
\"first\":\"collection1\",\n  \"second\":\"shard1\"}]}}",
   [junit4]   2>       "Op{action=ADDREPLICA, hints={COLL_SHARD=[{\n  
\"first\":\"collection1\",\n  \"second\":\"shard1\"}]}}"]}}
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.ComputePlanAction.process(ComputePlanAction.java:156)
 ~[java/:?]
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:308)
 ~[java/:?]
   [junit4]   2>        ... 6 more
   [junit4]   2> Caused by: java.lang.InterruptedException: stopping - thread 
was interrupted
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.ComputePlanAction.process(ComputePlanAction.java:108)
 ~[java/:?]
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:308)
 ~[java/:?]
   [junit4]   2>        ... 6 more
   [junit4]   2> 678502 DEBUG (AutoscalingActionExecutor-1141-thread-1) [    ] 
o.a.s.c.a.ScheduledTriggers -- processing took 5130 ms for event 
id=3e4bcecdcb83b7Ta3cq93xc7bvqebfw4a5k7wqrn
   [junit4]   2> 678502 DEBUG 
(SUITE-TestTriggerIntegration-seed#[5C3844A526EDE99A]-worker) [    ] 
o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool 
executor
   [junit4]   2> 678502 DEBUG 
(SUITE-TestTriggerIntegration-seed#[5C3844A526EDE99A]-worker) [    ] 
o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
   [junit4]   2> 678502 DEBUG 
(SUITE-TestTriggerIntegration-seed#[5C3844A526EDE99A]-worker) [    ] 
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.sim.TestTriggerIntegration_5C3844A526EDE99A-001
   [junit4]   2> Aug 25, 2018 11:42:31 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene80): {}, 
docValues:{}, maxPointsInLeafNode=791, maxMBSortInHeap=7.096870922658994, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@29ca85bf),
 locale=ja-JP-u-ca-japanese-x-lvariant-JP, timezone=America/Glace_Bay
   [junit4]   2> NOTE: Linux 4.4.0-112-generic amd64/Oracle Corporation 
1.8.0_172 (64-bit)/cpus=4,threads=1,free=211788400,total=516423680
   [junit4]   2> NOTE: All tests run in this JVM: [TestStressUserVersions, 
BadCopyFieldTest, TestHashPartitioner, ExternalFileFieldSortTest, TestConfig, 
TestJsonFacetsStatsParsing, CSVRequestHandlerTest, 
LeaderElectionIntegrationTest, TestFunctionQuery, TestAnalyzedSuggestions, 
TestQueryTypes, AddBlockUpdateTest, HdfsCollectionsAPIDistributedZkTest, 
TestSortByMinMaxFunction, OpenCloseCoreStressTest, CloudMLTQParserTest, 
TestMacros, BlockJoinFacetDistribTest, DocValuesMultiTest, DateFieldTest, 
TestDistribIDF, CdcrBootstrapTest, RemoteQueryErrorTest, 
RegexBoostProcessorTest, CoreMergeIndexesAdminHandlerTest, TestSolrCoreParser, 
TestChildDocTransformer, BitVectorTest, EmbeddedSolrNoSerializeTest, 
TestSQLHandler, TestLRUStatsCache, TestCustomSort, 
TestTrackingShardHandlerFactory, TestCoreBackup, 
ManagedSchemaRoundRobinCloudTest, MetricUtilsTest, TestTriggerIntegration]
   [junit4] Completed [78/830 (1!)] on J1 in 1.98s, 11 tests, 1 failure, 10 
skipped <<< FAILURES!

[...truncated 1351 lines...]
   [junit4] Suite: org.apache.solr.cloud.autoscaling.sim.TestPolicyCloud
   [junit4]   2> Creating dataDir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.sim.TestPolicyCloud_5C3844A526EDE99A-001/init-core-data-001
   [junit4]   2> 3310695 WARN  
(SUITE-TestPolicyCloud-seed#[5C3844A526EDE99A]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
   [junit4]   2> 3310696 INFO  
(SUITE-TestPolicyCloud-seed#[5C3844A526EDE99A]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 3310697 INFO  
(SUITE-TestPolicyCloud-seed#[5C3844A526EDE99A]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 3310697 INFO  
(SUITE-TestPolicyCloud-seed#[5C3844A526EDE99A]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 3310716 INFO  
(SUITE-TestPolicyCloud-seed#[5C3844A526EDE99A]-worker) [    ] 
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history 
in memory.
   [junit4]   2> 3310732 INFO  
(TEST-TestPolicyCloud.testDataProviderPerReplicaDetails-seed#[5C3844A526EDE99A])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testDataProviderPerReplicaDetails
   [junit4]   2> 3310732 INFO  
(TEST-TestPolicyCloud.testDataProviderPerReplicaDetails-seed#[5C3844A526EDE99A])
 [    ] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and 
clearing object cache...
   [junit4]   2> 3310733 WARN  (Simulated OverseerAutoScalingTriggerThread) [   
 ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are 
closed, exiting.
   [junit4]   2> 3311076 INFO  
(TEST-TestPolicyCloud.testDataProviderPerReplicaDetails-seed#[5C3844A526EDE99A])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testDataProviderPerReplicaDetails
   [junit4]   2> 3311076 INFO  
(TEST-TestPolicyCloud.testDataProviderPerReplicaDetails-seed#[5C3844A526EDE99A])
 [    ] 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> 3311190 INFO  
(TEST-TestPolicyCloud.testCreateCollectionSplitShard-seed#[5C3844A526EDE99A]) [ 
   ] o.a.s.SolrTestCaseJ4 ###Starting testCreateCollectionSplitShard
   [junit4]   2> 3311191 INFO  
(TEST-TestPolicyCloud.testCreateCollectionSplitShard-seed#[5C3844A526EDE99A]) [ 
   ] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and 
clearing object cache...
   [junit4]   2> 3311191 WARN  (Simulated OverseerAutoScalingTriggerThread) [   
 ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are 
closed, exiting.
   [junit4]   2> 3311472 INFO  (simCloudManagerPool-5823-thread-2) [    ] 
o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 17614425138 
sessionWrapper.createTime 17614425019211684, this.sessionWrapper.createTime 
17614425019211684 
   [junit4]   2> 3311776 INFO  (simCloudManagerPool-5823-thread-6) [    ] 
o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 17614440358 
sessionWrapper.createTime 17614439495666634, this.sessionWrapper.createTime 
17614439495666634 
   [junit4]   2> 3312052 INFO  
(TEST-TestPolicyCloud.testCreateCollectionSplitShard-seed#[5C3844A526EDE99A]) [ 
   ] o.a.s.SolrTestCaseJ4 ###Ending testCreateCollectionSplitShard
   [junit4]   2> 3312053 INFO  
(TEST-TestPolicyCloud.testCreateCollectionSplitShard-seed#[5C3844A526EDE99A]) [ 
   ] 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> 3312074 INFO  
(TEST-TestPolicyCloud.testMetricsTag-seed#[5C3844A526EDE99A]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testMetricsTag
   [junit4]   2> 3312074 INFO  
(TEST-TestPolicyCloud.testMetricsTag-seed#[5C3844A526EDE99A]) [    ] 
o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and clearing 
object cache...
   [junit4]   2> 3312087 WARN  (Simulated OverseerAutoScalingTriggerThread) [   
 ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are 
closed, exiting.
   [junit4]   2> 3312216 ERROR (simCloudManagerPool-5826-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> 3312240 INFO  (simCloudManagerPool-5826-thread-3) [    ] 
o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 17614463563 
sessionWrapper.createTime 17614463518501134, this.sessionWrapper.createTime 
17614463518501134 
   [junit4]   2> 3312288 INFO  
(TEST-TestPolicyCloud.testMetricsTag-seed#[5C3844A526EDE99A]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testMetricsTag
   [junit4]   2> 3312288 INFO  
(TEST-TestPolicyCloud.testMetricsTag-seed#[5C3844A526EDE99A]) [    ] 
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> 3312304 INFO  
(TEST-TestPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[5C3844A526EDE99A])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testCreateCollectionAddShardUsingPolicy
   [junit4]   2> 3312304 INFO  
(TEST-TestPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[5C3844A526EDE99A])
 [    ] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and 
clearing object cache...
   [junit4]   2> 3312304 WARN  (Simulated OverseerAutoScalingTriggerThread) [   
 ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are 
closed, exiting.
   [junit4]   2> 3312454 INFO  (simCloudManagerPool-5829-thread-2) [    ] 
o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 17614474254 
sessionWrapper.createTime 17614474033672184, this.sessionWrapper.createTime 
17614474033672184 
   [junit4]   2> 3312774 INFO  (simCloudManagerPool-5829-thread-7) [    ] 
o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 17614490273 
sessionWrapper.createTime 17614488579244984, this.sessionWrapper.createTime 
17614488579244984 
   [junit4]   2> 3312871 INFO  
(TEST-TestPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[5C3844A526EDE99A])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testCreateCollectionAddShardUsingPolicy
   [junit4]   2> 3312871 INFO  
(TEST-TestPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[5C3844A526EDE99A])
 [    ] 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> 3312908 INFO  
(TEST-TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[5C3844A526EDE99A])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting 
testCreateCollectionAddShardWithReplicaTypeUsingPolicy
   [junit4]   2> 3312921 INFO  
(TEST-TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[5C3844A526EDE99A])
 [    ] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and 
clearing object cache...
   [junit4]   2> 3313011 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> 3313140 INFO  
(TEST-TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[5C3844A526EDE99A])
 [    ] o.a.s.c.a.s.TestPolicyCloud NRT 127.0.0.1:10100_solr PULL 
127.0.0.1:10104_solr , TLOG 127.0.0.1:10104_solr 
   [junit4]   2> 3313289 INFO  (simCloudManagerPool-5832-thread-2) [    ] 
o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 17614515986 
sessionWrapper.createTime 17614512240104134, this.sessionWrapper.createTime 
17614512240104134 
   [junit4]   2> 3313721 INFO  (simCloudManagerPool-5832-thread-7) [    ] 
o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 17614537612 
sessionWrapper.createTime 17614532184029784, this.sessionWrapper.createTime 
17614532184029784 
   [junit4]   2> 3313812 INFO  
(TEST-TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[5C3844A526EDE99A])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending 
testCreateCollectionAddShardWithReplicaTypeUsingPolicy
   [junit4]   2> 3313812 INFO  
(TEST-TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[5C3844A526EDE99A])
 [    ] 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:      4
   [junit4]   2> ## Min replicas per node:      0
   [junit4]   2> ## Total replicas:             6
   [junit4]   2> ## * policiesTest              6
   [junit4]   2> ##             - active           6
   [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> 3313850 INFO  
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[5C3844A526EDE99A]) [ 
   ] o.a.s.SolrTestCaseJ4 ###Starting testCreateCollectionAddReplica
   [junit4]   2> 3313850 INFO  
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[5C3844A526EDE99A]) [ 
   ] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and 
clearing object cache...
   [junit4]   2> 3313850 WARN  (Simulated OverseerAutoScalingTriggerThread) [   
 ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are 
closed, exiting.
   [junit4]   2> 3314224 INFO  (simCloudManagerPool-5835-thread-2) [    ] 
o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 17614562729 
sessionWrapper.createTime 17614560860554084, this.sessionWrapper.createTime 
17614560860554084 
   [junit4]   2> 3314327 INFO  (simCloudManagerPool-5835-thread-5) [    ] 
o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 17614567888 
sessionWrapper.createTime 17614566609793084, this.sessionWrapper.createTime 
17614566609793084 
   [junit4]   2> 3314327 INFO  (simCloudManagerPool-5835-thread-5) [    ] 
o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10102_solr for creating new 
replica of shard shard1
   [junit4]   2> 3314349 INFO  
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[5C3844A526EDE99A]) [ 
   ] o.a.s.SolrTestCaseJ4 ###Ending testCreateCollectionAddReplica
   [junit4]   2> 3314349 INFO  
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[5C3844A526EDE99A]) [ 
   ] 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:      2
   [junit4]   2> ## Min replicas per node:      0
   [junit4]   2> ## Total replicas:             2
   [junit4]   2> ## * testCreateCollectionAddReplica            2
   [junit4]   2> ##             - active           2
   [junit4]   2> ######### Solr op counts ##########
   [junit4]   2> ##             - ADDREPLICA         1
   [junit4]   2> ##             - CREATE             1
   [junit4]   2> ##             - autoscaling        1
   [junit4]   2> ######### Autoscaling event counts ###########
   [junit4]   2> 
   [junit4]   2> 3314413 INFO  
(TEST-TestPolicyCloud.testDataProvider-seed#[5C3844A526EDE99A]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testDataProvider
   [junit4]   2> 3314413 INFO  
(TEST-TestPolicyCloud.testDataProvider-seed#[5C3844A526EDE99A]) [    ] 
o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and clearing 
object cache...
   [junit4]   2> 3314413 WARN  (Simulated OverseerAutoScalingTriggerThread) [   
 ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are 
closed, exiting.
   [junit4]   2> 3316448 INFO  
(TEST-TestPolicyCloud.testDataProvider-seed#[5C3844A526EDE99A]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testDataProvider
   [junit4]   2> 3316448 INFO  
(TEST-TestPolicyCloud.testDataProvider-seed#[5C3844A526EDE99A]) [    ] 
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> ##             - CREATE             1
   [junit4]   2> ######### Autoscaling event counts ###########
   [junit4]   2> 
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestPolicyCloud 
-Dtests.method=testDataProvider -Dtests.seed=5C3844A526EDE99A 
-Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=fi-FI 
-Dtests.timezone=America/Panama -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE 2.10s J2 | TestPolicyCloud.testDataProvider <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Timeout waiting for 
collection to become active
   [junit4]    > Live Nodes: [127.0.0.1:10102_solr, 127.0.0.1:10100_solr, 
127.0.0.1:10104_solr, 127.0.0.1:10101_solr, 127.0.0.1:10103_solr]
   [junit4]    > Last available state: 
DocCollection(policiesTest//clusterstate.json/43)={
   [junit4]    >   "replicationFactor":"1",
   [junit4]    >   "pullReplicas":"0",
   [junit4]    >   "router":{"name":"implicit"},
   [junit4]    >   "maxShardsPerNode":"1",
   [junit4]    >   "autoAddReplicas":"false",
   [junit4]    >   "nrtReplicas":"2",
   [junit4]    >   "tlogReplicas":"0",
   [junit4]    >   "autoCreated":"true",
   [junit4]    >   "shards":{"shard1":{
   [junit4]    >       "replicas":{
   [junit4]    >         "core_node10":{
   [junit4]    >           "core":"policiesTest_shard1_replica_n1",
   [junit4]    >           "SEARCHER.searcher.maxDoc":0,
   [junit4]    >           "SEARCHER.searcher.deletedDocs":0,
   [junit4]    >           "INDEX.sizeInBytes":10240,
   [junit4]    >           "node_name":"127.0.0.1:10104_solr",
   [junit4]    >           "state":"active",
   [junit4]    >           "type":"NRT",
   [junit4]    >           "INDEX.sizeInGB":9.5367431640625E-6,
   [junit4]    >           "SEARCHER.searcher.numDocs":0},
   [junit4]    >         "core_node11":{
   [junit4]    >           "core":"policiesTest_shard1_replica_n2",
   [junit4]    >           "SEARCHER.searcher.maxDoc":0,
   [junit4]    >           "SEARCHER.searcher.deletedDocs":0,
   [junit4]    >           "INDEX.sizeInBytes":10240,
   [junit4]    >           "node_name":"127.0.0.1:10103_solr",
   [junit4]    >           "state":"active",
   [junit4]    >           "type":"NRT",
   [junit4]    >           "INDEX.sizeInGB":9.5367431640625E-6,
   [junit4]    >           "SEARCHER.searcher.numDocs":0}},
   [junit4]    >       "state":"active"}}}
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([5C3844A526EDE99A:6493EC90942FED43]:0)
   [junit4]    >        at 
org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:70)
   [junit4]    >        at 
org.apache.solr.cloud.autoscaling.sim.TestPolicyCloud.testDataProvider(TestPolicyCloud.java:324)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]    > Caused by: java.util.concurrent.TimeoutException: last state: 
DocCollection(policiesTest//clusterstate.json/43)={
   [junit4]    >   "replicationFactor":"1",
   [junit4]    >   "pullReplicas":"0",
   [junit4]    >   "router":{"name":"implicit"},
   [junit4]    >   "maxShardsPerNode":"1",
   [junit4]    >   "autoAddReplicas":"false",
   [junit4]    >   "nrtReplicas":"2",
   [junit4]    >   "tlogReplicas":"0",
   [junit4]    >   "autoCreated":"true",
   [junit4]    >   "shards":{"shard1":{
   [junit4]    >       "replicas":{
   [junit4]    >         "core_node10":{
   [junit4]    >           "core":"policiesTest_shard1_replica_n1",
   [junit4]    >           "SEARCHER.searcher.maxDoc":0,
   [junit4]    >           "SEARCHER.searcher.deletedDocs":0,
   [junit4]    >           "INDEX.sizeInBytes":10240,
   [junit4]    >           "node_name":"127.0.0.1:10104_solr",
   [junit4]    >           "state":"active",
   [junit4]    >           "type":"NRT",
   [junit4]    >           "INDEX.sizeInGB":9.5367431640625E-6,
   [junit4]    >           "SEARCHER.searcher.numDocs":0},
   [junit4]    >         "core_node11":{
   [junit4]    >           "core":"policiesTest_shard1_replica_n2",
   [junit4]    >           "SEARCHER.searcher.maxDoc":0,
   [junit4]    >           "SEARCHER.searcher.deletedDocs":0,
   [junit4]    >           "INDEX.sizeInBytes":10240,
   [junit4]    >           "node_name":"127.0.0.1:10103_solr",
   [junit4]    >           "state":"active",
   [junit4]    >           "type":"NRT",
   [junit4]    >           "INDEX.sizeInGB":9.5367431640625E-6,
   [junit4]    >           "SEARCHER.searcher.numDocs":0}},
   [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> 3316450 WARN  (Simulated OverseerAutoScalingTriggerThread) [   
 ] o.a.s.c.a.OverseerTriggerThread Interrupted
   [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 
org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:189)
 [java/:?]
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.sim.TestPolicyCloud_5C3844A526EDE99A-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene80): {}, 
docValues:{}, maxPointsInLeafNode=295, maxMBSortInHeap=6.282619012049748, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@4f6e71b7),
 locale=fi-FI, timezone=America/Panama
   [junit4]   2> NOTE: Linux 4.4.0-112-generic amd64/Oracle Corporation 
1.8.0_172 (64-bit)/cpus=4,threads=1,free=107863368,total=534773760
   [junit4]   2> NOTE: All tests run in this JVM: [TestStressVersions, 
ConnectionManagerTest, ExplicitHLLTest, TestJoin, UtilsToolTest, 
RuleEngineTest, AddReplicaTest, DistributedQueryComponentOptimizationTest, 
TestCloudSearcherWarming, IndexSchemaRuntimeFieldTest, HdfsThreadLeakTest, 
TestJsonFacetsWithNestedObjects, TestManagedSynonymFilterFactory, 
SolrCloudExampleTest, MultiTermTest, 
DistributedFacetSimpleRefinementLongTailTest, TestExceedMaxTermLength, 
TestRangeQuery, SolrCoreCheckLockOnStartupTest, MoreLikeThisHandlerTest, 
SaslZkACLProviderTest, TestRetrieveFieldsOptimizer, TestSolrCoreSnapshots, 
TestSolrDeletionPolicy1, BasicZkTest, OutputWriterTest, 
TestWordDelimiterFilterFactory, TestHdfsBackupRestoreCore, 
DirectSolrSpellCheckerTest, TermsComponentTest, InfoHandlerTest, 
ConcurrentDeleteAndCreateCollectionTest, HdfsChaosMonkeySafeLeaderTest, 
TestReload, TestNoOpRegenerator, TestTestInjection, 
TestDeleteCollectionOnDownNodes, TestWriterPerf, TestInPlaceUpdatesStandalone, 
PropertiesRequestHandlerTest, TestDistribStateManager, TestJsonFacetRefinement, 
BaseCdcrDistributedZkTest, DeleteInactiveReplicaTest, TestStressRecovery, 
ScriptEngineTest, TestSha256AuthenticationProvider, 
TestSolrCloudWithHadoopAuthPlugin, BlockJoinFacetRandomTest, 
OpenExchangeRatesOrgProviderTest, HdfsDirectoryTest, TestCloudPivotFacet, 
TestGraphMLResponseWriter, SchemaApiFailureTest, TestLargeCluster, 
TestNumericTerms64, TestRemoteStreaming, ZkSolrClientTest, 
AtomicUpdateProcessorFactoryTest, ForceLeaderTest, 
CustomHighlightComponentTest, TestFastLRUCache, DateMathParserTest, 
SolrTestCaseJ4Test, TlogReplayBufferedWhileIndexingTest, 
SolrCloudReportersTest, TestSolrXml, TestSearcherReuse, 
ScheduledMaintenanceTriggerTest, TestSQLHandlerNonCloud, 
TestCorePropertiesReload, ResponseHeaderTest, TestCoreAdminApis, 
UpdateRequestProcessorFactoryTest, RandomizedTaggerTest, MBeansHandlerTest, 
CoreAdminOperationTest, RecoveryAfterSoftCommitTest, TestDynamicLoading, 
ResponseLogComponentTest, TestCollationField, SolrCmdDistributorTest, 
TestReplicaProperties, TestAuthenticationFramework, 
TestSerializedLuceneMatchVersion, SimplePostToolTest, TestValueSourceCache, 
ShardSplitTest, TestDFISimilarityFactory, SpellCheckComponentTest, 
TestInfoStreamLogging, TestSolrFieldCacheBean, TestUseDocValuesAsStored2, 
TestNumericRangeQuery32, OverseerTaskQueueTest, 
ParsingFieldUpdateProcessorsTest, TestDocTermOrdsUninvertLimit, ZkFailoverTest, 
TestBadConfig, MoveReplicaHDFSFailoverTest, AsyncCallRequestStatusResponseTest, 
DistribCursorPagingTest, TestAuthorizationFramework, TestPushWriter, 
AnalyticsQueryTest, TestLeaderElectionWithEmptyReplica, DistanceFunctionTest, 
TestSolr4Spatial, RequestHandlersTest, SolrJmxReporterCloudTest, 
TestConfigOverlay, TestDocumentBuilder, TestSolrCloudWithDelegationTokens, 
TestFuzzyAnalyzedSuggestions, TestSimpleTrackingShardHandler, 
ZkStateReaderTest, URLClassifyProcessorTest, TestUtilizeNode, 
SolrCoreMetricManagerTest, TestReqParamsAPI, CleanupOldIndexTest, 
DistributedSuggestComponentTest, ExitableDirectoryReaderTest, 
SolrIndexSplitterTest, TestManagedSchema, SuggesterFSTTest, 
TestCollectionsAPIViaSolrCloudCluster, SignificantTermsQParserPluginTest, 
TestDynamicFieldResource, TestLFUCache, TestPolicyCloud]
   [junit4] Completed [465/830 (2!)] on J2 in 5.82s, 7 tests, 1 failure <<< 
FAILURES!

[...truncated 49816 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 2311 links (1863 relative) to 3146 anchors in 247 files
     [echo] Validated Links & Anchors via: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-ref-guide/bare-bones-html/

-documentation-lint:
    [jtidy] Checking for broken html (such as invalid tags)...
   [delete] Deleting directory 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = /home/je

[...truncated too long message...]


-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/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 = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

jar-checksums:
    [mkdir] Created dir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/null950567343
     [copy] Copying 238 files to 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/null950567343
   [delete] Deleting directory 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/null950567343

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 93ms :: artifacts dl 29ms
        ---------------------------------------------------------------------
        |                  |            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: 215 minutes 24 seconds
Archiving artifacts
java.lang.InterruptedException: no matches found within 10000
        at hudson.FilePath$34.hasMatch(FilePath.java:2678)
        at hudson.FilePath$34.invoke(FilePath.java:2557)
        at hudson.FilePath$34.invoke(FilePath.java:2547)
        at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2918)
Also:   hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene2
                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:1036)
                at hudson.FilePath.act(FilePath.java:1025)
                at hudson.FilePath.validateAntFileMask(FilePath.java:2547)
                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:1819)
                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:2920)
        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:1142)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        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:1038)
        at hudson.FilePath.act(FilePath.java:1025)
        at hudson.FilePath.validateAntFileMask(FilePath.java:2547)
        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:1819)
        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/**,**/hs_err_pid*". Configuration error?
Recording test results
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to