Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/21415/
Java: 32bit/jdk1.8.0_162 -server -XX:+UseConcMarkSweepGC

4 tests failed.
FAILED:  
org.apache.solr.cloud.autoscaling.ComputePlanActionTest.testSelectedCollections

Error Message:
The operations computed by ComputePlanAction should not be 
nullSolrClientNodeStateProvider.DEBUG{AFTER_ACTION=[compute_plan, null], 
BEFORE_ACTION=[compute_plan, null]}

Stack Trace:
java.lang.AssertionError: The operations computed by ComputePlanAction should 
not be nullSolrClientNodeStateProvider.DEBUG{AFTER_ACTION=[compute_plan, null], 
BEFORE_ACTION=[compute_plan, null]}
        at 
__randomizedtesting.SeedInfo.seed([8CE5D4B0CE38D203:B64B3169F05C0B6D]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at org.junit.Assert.assertNotNull(Assert.java:526)
        at 
org.apache.solr.cloud.autoscaling.ComputePlanActionTest.testSelectedCollections(ComputePlanActionTest.java:469)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)


FAILED:  
org.apache.solr.cloud.autoscaling.HdfsAutoAddReplicasIntegrationTest.testSimple

Error Message:
Waiting for collection testSimple1 null Live Nodes: [127.0.0.1:39977_solr, 
127.0.0.1:41761_solr] Last available state: 
DocCollection(testSimple1//collections/testSimple1/state.json/16)={   
"pullReplicas":"0",   "replicationFactor":"2",   "shards":{     "shard1":{      
 "range":"80000000-ffffffff",       "state":"active",       "replicas":{        
 "core_node3":{           
"dataDir":"hdfs://localhost.localdomain:37315/data/testSimple1/core_node3/data/",
           "base_url":"https://127.0.0.1:41761/solr";,           
"node_name":"127.0.0.1:41761_solr",           "type":"NRT",           
"ulogDir":"hdfs://localhost.localdomain:37315/data/testSimple1/core_node3/data/tlog",
           "core":"testSimple1_shard1_replica_n1",           
"shared_storage":"true",           "state":"active",           
"leader":"true"},         "core_node5":{           
"dataDir":"hdfs://localhost.localdomain:37315/data/testSimple1/core_node5/data/",
           "base_url":"https://127.0.0.1:41761/solr";,           
"node_name":"127.0.0.1:41761_solr",           "type":"NRT",           
"ulogDir":"hdfs://localhost.localdomain:37315/data/testSimple1/core_node5/data/tlog",
           "core":"testSimple1_shard1_replica_n2",           
"shared_storage":"true",           "state":"active"}}},     "shard2":{       
"range":"0-7fffffff",       "state":"active",       "replicas":{         
"core_node7":{           
"dataDir":"hdfs://localhost.localdomain:37315/data/testSimple1/core_node7/data/",
           "base_url":"https://127.0.0.1:41761/solr";,           
"node_name":"127.0.0.1:41761_solr",           "type":"NRT",           
"ulogDir":"hdfs://localhost.localdomain:37315/data/testSimple1/core_node7/data/tlog",
           "core":"testSimple1_shard2_replica_n4",           
"shared_storage":"true",           "state":"active",           
"leader":"true"},         "core_node8":{           
"dataDir":"hdfs://localhost.localdomain:37315/data/testSimple1/core_node8/data/",
           "base_url":"https://127.0.0.1:43435/solr";,           
"node_name":"127.0.0.1:43435_solr",           "type":"NRT",           
"ulogDir":"hdfs://localhost.localdomain:37315/data/testSimple1/core_node8/data/tlog",
           "core":"testSimple1_shard2_replica_n6",           
"shared_storage":"true",           "state":"down"}}}},   
"router":{"name":"compositeId"},   "maxShardsPerNode":"2",   
"autoAddReplicas":"true",   "nrtReplicas":"2",   "tlogReplicas":"0"}

Stack Trace:
java.lang.AssertionError: Waiting for collection testSimple1
null
Live Nodes: [127.0.0.1:39977_solr, 127.0.0.1:41761_solr]
Last available state: 
DocCollection(testSimple1//collections/testSimple1/state.json/16)={
  "pullReplicas":"0",
  "replicationFactor":"2",
  "shards":{
    "shard1":{
      "range":"80000000-ffffffff",
      "state":"active",
      "replicas":{
        "core_node3":{
          
"dataDir":"hdfs://localhost.localdomain:37315/data/testSimple1/core_node3/data/",
          "base_url":"https://127.0.0.1:41761/solr";,
          "node_name":"127.0.0.1:41761_solr",
          "type":"NRT",
          
"ulogDir":"hdfs://localhost.localdomain:37315/data/testSimple1/core_node3/data/tlog",
          "core":"testSimple1_shard1_replica_n1",
          "shared_storage":"true",
          "state":"active",
          "leader":"true"},
        "core_node5":{
          
"dataDir":"hdfs://localhost.localdomain:37315/data/testSimple1/core_node5/data/",
          "base_url":"https://127.0.0.1:41761/solr";,
          "node_name":"127.0.0.1:41761_solr",
          "type":"NRT",
          
"ulogDir":"hdfs://localhost.localdomain:37315/data/testSimple1/core_node5/data/tlog",
          "core":"testSimple1_shard1_replica_n2",
          "shared_storage":"true",
          "state":"active"}}},
    "shard2":{
      "range":"0-7fffffff",
      "state":"active",
      "replicas":{
        "core_node7":{
          
"dataDir":"hdfs://localhost.localdomain:37315/data/testSimple1/core_node7/data/",
          "base_url":"https://127.0.0.1:41761/solr";,
          "node_name":"127.0.0.1:41761_solr",
          "type":"NRT",
          
"ulogDir":"hdfs://localhost.localdomain:37315/data/testSimple1/core_node7/data/tlog",
          "core":"testSimple1_shard2_replica_n4",
          "shared_storage":"true",
          "state":"active",
          "leader":"true"},
        "core_node8":{
          
"dataDir":"hdfs://localhost.localdomain:37315/data/testSimple1/core_node8/data/",
          "base_url":"https://127.0.0.1:43435/solr";,
          "node_name":"127.0.0.1:43435_solr",
          "type":"NRT",
          
"ulogDir":"hdfs://localhost.localdomain:37315/data/testSimple1/core_node8/data/tlog",
          "core":"testSimple1_shard2_replica_n6",
          "shared_storage":"true",
          "state":"down"}}}},
  "router":{"name":"compositeId"},
  "maxShardsPerNode":"2",
  "autoAddReplicas":"true",
  "nrtReplicas":"2",
  "tlogReplicas":"0"}
        at 
__randomizedtesting.SeedInfo.seed([8CE5D4B0CE38D203:B456F04EE9CB06D2]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at 
org.apache.solr.cloud.SolrCloudTestCase.waitForState(SolrCloudTestCase.java:269)
        at 
org.apache.solr.cloud.autoscaling.AutoAddReplicasIntegrationTest.testSimple(AutoAddReplicasIntegrationTest.java:103)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)


FAILED:  
org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testMetricTrigger

Error Message:


Stack Trace:
java.lang.AssertionError
        at 
__randomizedtesting.SeedInfo.seed([8CE5D4B0CE38D203:36E9E33F91D0044C]:0)
        at org.junit.Assert.fail(Assert.java:92)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at org.junit.Assert.assertNull(Assert.java:551)
        at org.junit.Assert.assertNull(Assert.java:562)
        at 
org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testMetricTrigger(TriggerIntegrationTest.java:1575)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)


FAILED:  org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest.testHistory

Error Message:
expected:<5> but was:<0>

Stack Trace:
java.lang.AssertionError: expected:<5> but was:<0>
        at 
__randomizedtesting.SeedInfo.seed([8CE5D4B0CE38D203:E119704D74702D04]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.failNotEquals(Assert.java:647)
        at org.junit.Assert.assertEquals(Assert.java:128)
        at org.junit.Assert.assertEquals(Assert.java:472)
        at org.junit.Assert.assertEquals(Assert.java:456)
        at 
org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest.testHistory(AutoscalingHistoryHandlerTest.java:244)
        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 12269 lines...]
   [junit4] Suite: org.apache.solr.cloud.autoscaling.ComputePlanActionTest
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_8CE5D4B0CE38D203-001/init-core-data-001
   [junit4]   2> 352186 WARN  
(SUITE-ComputePlanActionTest-seed#[8CE5D4B0CE38D203]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=7 numCloses=7
   [junit4]   2> 352186 INFO  
(SUITE-ComputePlanActionTest-seed#[8CE5D4B0CE38D203]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 352187 INFO  
(SUITE-ComputePlanActionTest-seed#[8CE5D4B0CE38D203]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 352187 INFO  
(SUITE-ComputePlanActionTest-seed#[8CE5D4B0CE38D203]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 352187 INFO  
(SUITE-ComputePlanActionTest-seed#[8CE5D4B0CE38D203]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_8CE5D4B0CE38D203-001/tempDir-001
   [junit4]   2> 352187 INFO  
(SUITE-ComputePlanActionTest-seed#[8CE5D4B0CE38D203]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 352188 INFO  (Thread-620) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 352188 INFO  (Thread-620) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 352190 ERROR (Thread-620) [    ] o.a.z.s.ZooKeeperServer 
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action 
on ERROR or SHUTDOWN server state changes
   [junit4]   2> 352288 INFO  
(SUITE-ComputePlanActionTest-seed#[8CE5D4B0CE38D203]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:38341
   [junit4]   2> 352290 INFO  (zkConnectionManagerCallback-723-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 352292 INFO  (jetty-launcher-720-thread-1) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-22T05:27:37+08:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 352292 INFO  (jetty-launcher-720-thread-1) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 352292 INFO  (jetty-launcher-720-thread-1) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 352292 INFO  (jetty-launcher-720-thread-1) [    ] 
o.e.j.s.session Scavenging every 660000ms
   [junit4]   2> 352292 INFO  (jetty-launcher-720-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@200e4c{/solr,null,AVAILABLE}
   [junit4]   2> 352293 INFO  (jetty-launcher-720-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@2b0030{HTTP/1.1,[http/1.1]}{127.0.0.1:38747}
   [junit4]   2> 352293 INFO  (jetty-launcher-720-thread-1) [    ] 
o.e.j.s.Server Started @354676ms
   [junit4]   2> 352293 INFO  (jetty-launcher-720-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=38747}
   [junit4]   2> 352293 ERROR (jetty-launcher-720-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 352293 INFO  (jetty-launcher-720-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 352293 INFO  (jetty-launcher-720-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 352293 INFO  (jetty-launcher-720-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 352293 INFO  (jetty-launcher-720-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-02-08T02:03:48.641Z
   [junit4]   2> 352294 INFO  (zkConnectionManagerCallback-725-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 352294 INFO  (jetty-launcher-720-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 352301 INFO  (jetty-launcher-720-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38341/solr
   [junit4]   2> 352302 INFO  (zkConnectionManagerCallback-729-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 352303 INFO  
(zkConnectionManagerCallback-731-thread-1-processing-n:127.0.0.1:38747_solr) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 352330 INFO  (jetty-launcher-720-thread-1) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 352330 INFO  (jetty-launcher-720-thread-1) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:38747_solr
   [junit4]   2> 352331 INFO  (jetty-launcher-720-thread-1) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.Overseer Overseer 
(id=72111241084928003-127.0.0.1:38747_solr-n_0000000000) starting
   [junit4]   2> 352333 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 352334 DEBUG 
(OverseerAutoScalingTriggerThread-72111241084928003-127.0.0.1:38747_solr-n_0000000000)
 [n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread Adding 
.autoAddReplicas trigger
   [junit4]   2> 352334 INFO  (jetty-launcher-720-thread-1) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:38747_solr
   [junit4]   2> 352335 DEBUG 
(OverseerAutoScalingTriggerThread-72111241084928003-127.0.0.1:38747_solr-n_0000000000)
 [n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread Refreshing 
/autoscaling.json with znode version 1
   [junit4]   2> 352335 DEBUG 
(OverseerAutoScalingTriggerThread-72111241084928003-127.0.0.1:38747_solr-n_0000000000)
 [n:127.0.0.1:38747_solr    ] o.a.s.c.a.NodeLostTrigger Initial livenodes: []
   [junit4]   2> 352335 DEBUG 
(OverseerAutoScalingTriggerThread-72111241084928003-127.0.0.1:38747_solr-n_0000000000)
 [n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread Current 
znodeVersion 1, lastZnodeVersion -1
   [junit4]   2> 352335 DEBUG 
(OverseerAutoScalingTriggerThread-72111241084928003-127.0.0.1:38747_solr-n_0000000000)
 [n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread Processed trigger 
updates upto znodeVersion 1
   [junit4]   2> 352335 INFO  
(zkCallback-730-thread-1-processing-n:127.0.0.1:38747_solr) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 352336 DEBUG 
(OverseerAutoScalingTriggerThread-72111241084928003-127.0.0.1:38747_solr-n_0000000000)
 [n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeAdded markers
   [junit4]   2> 352336 DEBUG 
(OverseerAutoScalingTriggerThread-72111241084928003-127.0.0.1:38747_solr-n_0000000000)
 [n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread Current 
znodeVersion 1, lastZnodeVersion 1
   [junit4]   2> 352336 DEBUG (ScheduledTrigger-1700-thread-1) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: 
.auto_add_replicas with currently live nodes: 1
   [junit4]   2> 352406 INFO  (jetty-launcher-720-thread-1) 
[n:127.0.0.1:38747_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_38747.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1ad7b0c
   [junit4]   2> 352412 INFO  (jetty-launcher-720-thread-1) 
[n:127.0.0.1:38747_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_38747.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1ad7b0c
   [junit4]   2> 352412 INFO  (jetty-launcher-720-thread-1) 
[n:127.0.0.1:38747_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_38747.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1ad7b0c
   [junit4]   2> 352413 INFO  (jetty-launcher-720-thread-1) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_8CE5D4B0CE38D203-001/tempDir-001/node1/.
   [junit4]   2> 352440 INFO  (zkConnectionManagerCallback-735-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 352442 INFO  (zkConnectionManagerCallback-739-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 352443 INFO  
(SUITE-ComputePlanActionTest-seed#[8CE5D4B0CE38D203]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 352443 INFO  
(SUITE-ComputePlanActionTest-seed#[8CE5D4B0CE38D203]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38341/solr ready
   [junit4]   2> 352448 INFO  
(TEST-ComputePlanActionTest.testNodeAdded-seed#[8CE5D4B0CE38D203]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testNodeAdded
   [junit4]   2> 352449 DEBUG 
(zkCallback-730-thread-1-processing-n:127.0.0.1:38747_solr) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread Refreshing 
/autoscaling.json with znode version 2
   [junit4]   2> 352449 DEBUG 
(OverseerAutoScalingTriggerThread-72111241084928003-127.0.0.1:38747_solr-n_0000000000)
 [n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread Processed trigger 
updates upto znodeVersion 2
   [junit4]   2> 352449 INFO  
(TEST-ComputePlanActionTest.testNodeAdded-seed#[8CE5D4B0CE38D203]) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 352449 DEBUG 
(OverseerAutoScalingTriggerThread-72111241084928003-127.0.0.1:38747_solr-n_0000000000)
 [n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeLost markers
   [junit4]   2> 352449 DEBUG 
(OverseerAutoScalingTriggerThread-72111241084928003-127.0.0.1:38747_solr-n_0000000000)
 [n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeAdded markers
   [junit4]   2> 352449 DEBUG 
(OverseerAutoScalingTriggerThread-72111241084928003-127.0.0.1:38747_solr-n_0000000000)
 [n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread Current 
znodeVersion 2, lastZnodeVersion 2
   [junit4]   2> 352452 INFO  (qtp5775301-3136) [n:127.0.0.1:38747_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 352452 DEBUG (qtp5775301-3130) [n:127.0.0.1:38747_solr    ] 
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 352453 INFO  (qtp5775301-3130) [n:127.0.0.1:38747_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=2
   [junit4]   2> 352453 DEBUG 
(zkCallback-730-thread-1-processing-n:127.0.0.1:38747_solr) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread Refreshing 
/autoscaling.json with znode version 3
   [junit4]   2> 352453 DEBUG 
(OverseerAutoScalingTriggerThread-72111241084928003-127.0.0.1:38747_solr-n_0000000000)
 [n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread Processed trigger 
updates upto znodeVersion 3
   [junit4]   2> 352453 DEBUG 
(OverseerAutoScalingTriggerThread-72111241084928003-127.0.0.1:38747_solr-n_0000000000)
 [n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeLost markers
   [junit4]   2> 352453 DEBUG 
(OverseerAutoScalingTriggerThread-72111241084928003-127.0.0.1:38747_solr-n_0000000000)
 [n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeAdded markers
   [junit4]   2> 352453 DEBUG 
(OverseerAutoScalingTriggerThread-72111241084928003-127.0.0.1:38747_solr-n_0000000000)
 [n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread Current 
znodeVersion 3, lastZnodeVersion 3
   [junit4]   2> 352455 INFO  (qtp5775301-3135) [n:127.0.0.1:38747_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 352455 DEBUG (qtp5775301-3134) [n:127.0.0.1:38747_solr    ] 
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 352455 INFO  (qtp5775301-3134) [n:127.0.0.1:38747_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=2
   [junit4]   2> 352455 DEBUG 
(zkCallback-730-thread-1-processing-n:127.0.0.1:38747_solr) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread Refreshing 
/autoscaling.json with znode version 4
   [junit4]   2> 352455 DEBUG 
(OverseerAutoScalingTriggerThread-72111241084928003-127.0.0.1:38747_solr-n_0000000000)
 [n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread Processed trigger 
updates upto znodeVersion 4
   [junit4]   2> 352456 DEBUG 
(OverseerAutoScalingTriggerThread-72111241084928003-127.0.0.1:38747_solr-n_0000000000)
 [n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeLost markers
   [junit4]   2> 352456 DEBUG 
(OverseerAutoScalingTriggerThread-72111241084928003-127.0.0.1:38747_solr-n_0000000000)
 [n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeAdded markers
   [junit4]   2> 352456 DEBUG 
(OverseerAutoScalingTriggerThread-72111241084928003-127.0.0.1:38747_solr-n_0000000000)
 [n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread Current 
znodeVersion 4, lastZnodeVersion 4
   [junit4]   2> 352457 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 352457 DEBUG (qtp5775301-3136) [n:127.0.0.1:38747_solr    ] 
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 352458 INFO  (qtp5775301-3136) [n:127.0.0.1:38747_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=1
   [junit4]   2> 352458 DEBUG 
(zkCallback-730-thread-1-processing-n:127.0.0.1:38747_solr) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread Refreshing 
/autoscaling.json with znode version 5
   [junit4]   2> 352458 DEBUG 
(zkCallback-730-thread-1-processing-n:127.0.0.1:38747_solr) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: 
[127.0.0.1:38747_solr]
   [junit4]   2> 352458 DEBUG 
(zkCallback-730-thread-1-processing-n:127.0.0.1:38747_solr) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger 
node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=1, 
actions=[{name=compute_plan, class=solr.ComputePlanAction}, {name=test, 
class=org.apache.solr.cloud.autoscaling.ComputePlanActionTest$AssertingTriggerAction}],
 enabled=true}
   [junit4]   2> 352458 DEBUG 
(OverseerAutoScalingTriggerThread-72111241084928003-127.0.0.1:38747_solr-n_0000000000)
 [n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread Processed trigger 
updates upto znodeVersion 5
   [junit4]   2> 352459 DEBUG (ScheduledTrigger-1700-thread-1) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.a.NodeAddedTrigger Running 
NodeAddedTrigger node_added_trigger
   [junit4]   2> 352459 DEBUG (ScheduledTrigger-1700-thread-1) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
   [junit4]   2> 352459 INFO  (qtp5775301-3135) [n:127.0.0.1:38747_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 352460 DEBUG (qtp5775301-3134) [n:127.0.0.1:38747_solr    ] 
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 352460 INFO  (qtp5775301-3134) [n:127.0.0.1:38747_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=1
   [junit4]   2> 352460 DEBUG 
(zkCallback-730-thread-1-processing-n:127.0.0.1:38747_solr) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread Refreshing 
/autoscaling.json with znode version 6
   [junit4]   2> 352460 INFO  (qtp5775301-3136) [n:127.0.0.1:38747_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=2&collection.configName=conf&name=testNodeAdded&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 352460 DEBUG 
(zkCallback-730-thread-1-processing-n:127.0.0.1:38747_solr) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: 
[127.0.0.1:38747_solr]
   [junit4]   2> 352460 DEBUG 
(zkCallback-730-thread-1-processing-n:127.0.0.1:38747_solr) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger 
node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=1, 
actions=[{name=compute_plan, class=solr.ComputePlanAction}, {name=test, 
class=org.apache.solr.cloud.autoscaling.ComputePlanActionTest$AssertingTriggerAction}],
 enabled=true}
   [junit4]   2> 352468 DEBUG 
(OverseerAutoScalingTriggerThread-72111241084928003-127.0.0.1:38747_solr-n_0000000000)
 [n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeLost markers
   [junit4]   2> 352468 DEBUG 
(OverseerAutoScalingTriggerThread-72111241084928003-127.0.0.1:38747_solr-n_0000000000)
 [n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread Current 
znodeVersion 6, lastZnodeVersion 5
   [junit4]   2> 352468 DEBUG 
(OverseerAutoScalingTriggerThread-72111241084928003-127.0.0.1:38747_solr-n_0000000000)
 [n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread Processed trigger 
updates upto znodeVersion 6
   [junit4]   2> 352468 DEBUG 
(OverseerAutoScalingTriggerThread-72111241084928003-127.0.0.1:38747_solr-n_0000000000)
 [n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeLost markers
   [junit4]   2> 352468 DEBUG 
(OverseerAutoScalingTriggerThread-72111241084928003-127.0.0.1:38747_solr-n_0000000000)
 [n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread Current 
znodeVersion 6, lastZnodeVersion 6
   [junit4]   2> 352470 INFO  
(OverseerThreadFactory-1702-thread-1-processing-n:127.0.0.1:38747_solr) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
testNodeAdded
   [junit4]   2> 352470 WARN  
(OverseerThreadFactory-1702-thread-1-processing-n:127.0.0.1:38747_solr) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.a.c.CreateCollectionCmd Specified number 
of replicas of 2 on collection testNodeAdded is higher than the number of Solr 
instances currently live or live and part of your createNodeSet(1). It's 
unusual to run two replica of the same slice on the same Solr-instance.
   [junit4]   2> 352471 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 352472 INFO  
(OverseerThreadFactory-1702-thread-1-processing-n:127.0.0.1:38747_solr) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.s.c.a.PolicyHelper returnSession, 
curr-time 818589257 sessionWrapper.createTime 818589257491885, 
this.sessionWrapper.createTime 818589257491885 
   [junit4]   2> 352473 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.Overseer processMessage: queueSize: 1, 
message = {
   [junit4]   2>   "name":"testNodeAdded",
   [junit4]   2>   "fromApi":"true",
   [junit4]   2>   "replicationFactor":"2",
   [junit4]   2>   "collection.configName":"conf",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "nrtReplicas":"2",
   [junit4]   2>   "stateFormat":"2",
   [junit4]   2>   "operation":"create"} current state version: 0
   [junit4]   2> 352473 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.o.ClusterStateMutator building a new 
cName: testNodeAdded
   [junit4]   2> 352473 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.o.ZkStateWriter going to create_collection 
/collections/testNodeAdded/state.json
   [junit4]   2> 352575 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.Overseer processMessage: queueSize: 1, 
message = {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"testNodeAdded",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"testNodeAdded_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:38747/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} current state version: 0
   [junit4]   2> 352575 INFO  
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"testNodeAdded",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"testNodeAdded_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:38747/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 352575 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
   [junit4]   2>   "range":"80000000-7fffffff",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "replicas":{}}
   [junit4]   2> 352575 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.o.SliceMutator New Slice: shard1:{
   [junit4]   2>   "range":"80000000-7fffffff",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "replicas":{"core_node3":{
   [junit4]   2>       "core":"testNodeAdded_shard1_replica_n1",
   [junit4]   2>       "base_url":"http://127.0.0.1:38747/solr";,
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":null,
   [junit4]   2>       "type":"NRT"}}}
   [junit4]   2> 352576 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.Overseer processMessage: queueSize: 2, 
message = {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"testNodeAdded",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"testNodeAdded_shard1_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:38747/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} current state version: 0
   [junit4]   2> 352576 INFO  
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"testNodeAdded",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"testNodeAdded_shard1_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:38747/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 352576 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
   [junit4]   2>   "range":"80000000-7fffffff",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "replicas":{"core_node3":{
   [junit4]   2>       "core":"testNodeAdded_shard1_replica_n1",
   [junit4]   2>       "base_url":"http://127.0.0.1:38747/solr";,
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":null,
   [junit4]   2>       "type":"NRT"}}}
   [junit4]   2> 352576 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.o.SliceMutator New Slice: shard1:{
   [junit4]   2>   "range":"80000000-7fffffff",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "replicas":{
   [junit4]   2>     "core_node3":{
   [junit4]   2>       "core":"testNodeAdded_shard1_replica_n1",
   [junit4]   2>       "base_url":"http://127.0.0.1:38747/solr";,
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":null,
   [junit4]   2>       "type":"NRT"},
   [junit4]   2>     "core_node4":{
   [junit4]   2>       "core":"testNodeAdded_shard1_replica_n2",
   [junit4]   2>       "base_url":"http://127.0.0.1:38747/solr";,
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":null,
   [junit4]   2>       "type":"NRT"}}}
   [junit4]   2> 352676 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.o.ZkStateWriter going to update_collection 
/collections/testNodeAdded/state.json version: 0
   [junit4]   2> 352778 INFO  (qtp5775301-3129) [n:127.0.0.1:38747_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=testNodeAdded_shard1_replica_n2&action=CREATE&numShards=1&collection=testNodeAdded&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 352778 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testNodeAdded_shard1_replica_n1&action=CREATE&numShards=1&collection=testNodeAdded&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 352778 INFO  (qtp5775301-3129) [n:127.0.0.1:38747_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 352780 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.Overseer processMessage: queueSize: 2, 
message = {
   [junit4]   2>   "core":"testNodeAdded_shard1_replica_n1",
   [junit4]   2>   "core_node_name":"core_node3",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:38747/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:38747_solr",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"testNodeAdded",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"state"} current state version: 0
   [junit4]   2> 352780 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 
message={
   [junit4]   2>   "core":"testNodeAdded_shard1_replica_n1",
   [junit4]   2>   "core_node_name":"core_node3",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:38747/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:38747_solr",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"testNodeAdded",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 352781 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.o.ReplicaMutator Will update state for 
replica: 
core_node3:{"core":"testNodeAdded_shard1_replica_n1","base_url":"http://127.0.0.1:38747/solr","node_name":"127.0.0.1:38747_solr","state":"down","type":"NRT"}
   [junit4]   2> 352781 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.o.ReplicaMutator Collection is now: 
DocCollection(testNodeAdded//collections/testNodeAdded/state.json/1)={
   [junit4]   2>   "pullReplicas":"0",
   [junit4]   2>   "replicationFactor":"2",
   [junit4]   2>   "router":{"name":"compositeId"},
   [junit4]   2>   "maxShardsPerNode":"1",
   [junit4]   2>   "autoAddReplicas":"false",
   [junit4]   2>   "nrtReplicas":"2",
   [junit4]   2>   "tlogReplicas":"0",
   [junit4]   2>   "shards":{"shard1":{
   [junit4]   2>       "range":"80000000-7fffffff",
   [junit4]   2>       "state":"active",
   [junit4]   2>       "replicas":{
   [junit4]   2>         "core_node3":{
   [junit4]   2>           "core":"testNodeAdded_shard1_replica_n1",
   [junit4]   2>           "base_url":"http://127.0.0.1:38747/solr";,
   [junit4]   2>           "node_name":"127.0.0.1:38747_solr",
   [junit4]   2>           "state":"down",
   [junit4]   2>           "type":"NRT"},
   [junit4]   2>         "core_node4":{
   [junit4]   2>           "core":"testNodeAdded_shard1_replica_n2",
   [junit4]   2>           "base_url":"http://127.0.0.1:38747/solr";,
   [junit4]   2>           "state":"down",
   [junit4]   2>           "node_name":null,
   [junit4]   2>           "type":"NRT"}}}}}
   [junit4]   2> 352781 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.Overseer processMessage: queueSize: 2, 
message = {
   [junit4]   2>   "core":"testNodeAdded_shard1_replica_n2",
   [junit4]   2>   "core_node_name":"core_node4",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:38747/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:38747_solr",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"testNodeAdded",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"state"} current state version: 0
   [junit4]   2> 352781 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 
message={
   [junit4]   2>   "core":"testNodeAdded_shard1_replica_n2",
   [junit4]   2>   "core_node_name":"core_node4",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:38747/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:38747_solr",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"testNodeAdded",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 352781 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.o.ReplicaMutator Will update state for 
replica: 
core_node4:{"core":"testNodeAdded_shard1_replica_n2","base_url":"http://127.0.0.1:38747/solr","node_name":"127.0.0.1:38747_solr","state":"down","type":"NRT"}
   [junit4]   2> 352781 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.o.ReplicaMutator Collection is now: 
DocCollection(testNodeAdded//collections/testNodeAdded/state.json/1)={
   [junit4]   2>   "pullReplicas":"0",
   [junit4]   2>   "replicationFactor":"2",
   [junit4]   2>   "router":{"name":"compositeId"},
   [junit4]   2>   "maxShardsPerNode":"1",
   [junit4]   2>   "autoAddReplicas":"false",
   [junit4]   2>   "nrtReplicas":"2",
   [junit4]   2>   "tlogReplicas":"0",
   [junit4]   2>   "shards":{"shard1":{
   [junit4]   2>       "range":"80000000-7fffffff",
   [junit4]   2>       "state":"active",
   [junit4]   2>       "replicas":{
   [junit4]   2>         "core_node3":{
   [junit4]   2>           "core":"testNodeAdded_shard1_replica_n1",
   [junit4]   2>           "base_url":"http://127.0.0.1:38747/solr";,
   [junit4]   2>           "node_name":"127.0.0.1:38747_solr",
   [junit4]   2>           "state":"down",
   [junit4]   2>           "type":"NRT"},
   [junit4]   2>         "core_node4":{
   [junit4]   2>           "core":"testNodeAdded_shard1_replica_n2",
   [junit4]   2>           "base_url":"http://127.0.0.1:38747/solr";,
   [junit4]   2>           "node_name":"127.0.0.1:38747_solr",
   [junit4]   2>           "state":"down",
   [junit4]   2>           "type":"NRT"}}}}}
   [junit4]   2> 352881 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.o.ZkStateWriter going to update_collection 
/collections/testNodeAdded/state.json version: 1
   [junit4]   2> 352882 INFO  
(zkCallback-730-thread-2-processing-n:127.0.0.1:38747_solr) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has 
occurred - updating... (live nodes size: [1])
   [junit4]   2> 352882 INFO  
(zkCallback-730-thread-1-processing-n:127.0.0.1:38747_solr) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has 
occurred - updating... (live nodes size: [1])
   [junit4]   2> 353460 DEBUG (ScheduledTrigger-1700-thread-3) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.a.NodeAddedTrigger Running 
NodeAddedTrigger node_added_trigger
   [junit4]   2> 353460 DEBUG (ScheduledTrigger-1700-thread-3) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
   [junit4]   2> 353790 INFO  (qtp5775301-3129) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 353790 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 353799 INFO  (qtp5775301-3129) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] 
o.a.s.s.IndexSchema [testNodeAdded_shard1_replica_n2] Schema name=minimal
   [junit4]   2> 353799 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] 
o.a.s.s.IndexSchema [testNodeAdded_shard1_replica_n1] Schema name=minimal
   [junit4]   2> 353804 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 353804 INFO  (qtp5775301-3129) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 353804 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] 
o.a.s.c.CoreContainer Creating SolrCore 'testNodeAdded_shard1_replica_n1' using 
configuration from collection testNodeAdded, trusted=true
   [junit4]   2> 353804 INFO  (qtp5775301-3129) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] 
o.a.s.c.CoreContainer Creating SolrCore 'testNodeAdded_shard1_replica_n2' using 
configuration from collection testNodeAdded, trusted=true
   [junit4]   2> 353806 INFO  (qtp5775301-3129) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_38747.solr.core.testNodeAdded.shard1.replica_n2' (registry 
'solr.core.testNodeAdded.shard1.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1ad7b0c
   [junit4]   2> 353806 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_38747.solr.core.testNodeAdded.shard1.replica_n1' (registry 
'solr.core.testNodeAdded.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1ad7b0c
   [junit4]   2> 353806 INFO  (qtp5775301-3129) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 353806 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 353806 INFO  (qtp5775301-3129) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] 
o.a.s.c.SolrCore [[testNodeAdded_shard1_replica_n2] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_8CE5D4B0CE38D203-001/tempDir-001/node1/testNodeAdded_shard1_replica_n2],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_8CE5D4B0CE38D203-001/tempDir-001/node1/./testNodeAdded_shard1_replica_n2/data/]
   [junit4]   2> 353806 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] 
o.a.s.c.SolrCore [[testNodeAdded_shard1_replica_n1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_8CE5D4B0CE38D203-001/tempDir-001/node1/testNodeAdded_shard1_replica_n1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_8CE5D4B0CE38D203-001/tempDir-001/node1/./testNodeAdded_shard1_replica_n1/data/]
   [junit4]   2> 353847 INFO  (qtp5775301-3129) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 353847 INFO  (qtp5775301-3129) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 353847 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 353847 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 353848 INFO  (qtp5775301-3129) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 353848 INFO  (qtp5775301-3129) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 353848 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 353848 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 353849 INFO  (qtp5775301-3129) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@8179ec[testNodeAdded_shard1_replica_n2] main]
   [junit4]   2> 353849 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@61f65c[testNodeAdded_shard1_replica_n1] main]
   [junit4]   2> 353850 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 353850 INFO  (qtp5775301-3129) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 353850 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 353850 INFO  (qtp5775301-3129) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 353850 INFO  (qtp5775301-3129) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 353850 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 353851 INFO  
(searcherExecutor-1706-thread-1-processing-n:127.0.0.1:38747_solr 
x:testNodeAdded_shard1_replica_n2 s:shard1 c:testNodeAdded r:core_node4) 
[n:127.0.0.1:38747_solr c:testNodeAdded s:shard1 r:core_node4 
x:testNodeAdded_shard1_replica_n2] o.a.s.c.SolrCore 
[testNodeAdded_shard1_replica_n2] Registered new searcher 
Searcher@8179ec[testNodeAdded_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 353851 INFO  
(searcherExecutor-1705-thread-1-processing-n:127.0.0.1:38747_solr 
x:testNodeAdded_shard1_replica_n1 s:shard1 c:testNodeAdded r:core_node3) 
[n:127.0.0.1:38747_solr c:testNodeAdded s:shard1 r:core_node3 
x:testNodeAdded_shard1_replica_n1] o.a.s.c.SolrCore 
[testNodeAdded_shard1_replica_n1] Registered new searcher 
Searcher@61f65c[testNodeAdded_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 353851 INFO  (qtp5775301-3129) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1591796490776346624
   [junit4]   2> 353851 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1591796490776346624
   [junit4]   2> 353855 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] 
o.a.s.c.ZkShardTerms Failed to save terms, version is not match, retrying
   [junit4]   2> 353856 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 353856 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 353856 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:38747/solr/testNodeAdded_shard1_replica_n1/
   [junit4]   2> 353856 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.Overseer processMessage: queueSize: 1, 
message = {
   [junit4]   2>   "operation":"leader",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"testNodeAdded"} current state version: 0
   [junit4]   2> 353856 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] 
o.a.s.u.PeerSync PeerSync: core=testNodeAdded_shard1_replica_n1 
url=http://127.0.0.1:38747/solr START 
replicas=[http://127.0.0.1:38747/solr/testNodeAdded_shard1_replica_n2/] 
nUpdates=100
   [junit4]   2> 353857 INFO  (qtp5775301-3132) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] 
o.a.s.c.S.Request [testNodeAdded_shard1_replica_n2]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 353957 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.o.ZkStateWriter going to update_collection 
/collections/testNodeAdded/state.json version: 2
   [junit4]   2> 353957 INFO  
(zkCallback-730-thread-1-processing-n:127.0.0.1:38747_solr) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has 
occurred - updating... (live nodes size: [1])
   [junit4]   2> 353957 INFO  
(zkCallback-730-thread-3-processing-n:127.0.0.1:38747_solr) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has 
occurred - updating... (live nodes size: [1])
   [junit4]   2> 354157 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] 
o.a.s.u.PeerSync PeerSync: core=testNodeAdded_shard1_replica_n1 
url=http://127.0.0.1:38747/solr DONE.  We have no versions.  sync failed.
   [junit4]   2> 354157 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] 
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the 
next candidate
   [junit4]   2> 354157 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we 
can't sync in that case - we were active before, so become leader anyway
   [junit4]   2> 354157 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 354161 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:38747/solr/testNodeAdded_shard1_replica_n1/ shard1
   [junit4]   2> 354161 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.Overseer processMessage: queueSize: 1, 
message = {
   [junit4]   2>   "operation":"leader",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"testNodeAdded",
   [junit4]   2>   "base_url":"http://127.0.0.1:38747/solr";,
   [junit4]   2>   "core":"testNodeAdded_shard1_replica_n1",
   [junit4]   2>   "state":"active"} current state version: 0
   [junit4]   2> 354261 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.o.ZkStateWriter going to update_collection 
/collections/testNodeAdded/state.json version: 3
   [junit4]   2> 354262 INFO  
(zkCallback-730-thread-1-processing-n:127.0.0.1:38747_solr) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has 
occurred - updating... (live nodes size: [1])
   [junit4]   2> 354262 INFO  
(zkCallback-730-thread-3-processing-n:127.0.0.1:38747_solr) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has 
occurred - updating... (live nodes size: [1])
   [junit4]   2> 354311 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 354313 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.Overseer processMessage: queueSize: 1, 
message = {
   [junit4]   2>   "core":"testNodeAdded_shard1_replica_n1",
   [junit4]   2>   "core_node_name":"core_node3",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:38747/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:38747_solr",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"testNodeAdded",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"state"} current state version: 0
   [junit4]   2> 354313 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 
message={
   [junit4]   2>   "core":"testNodeAdded_shard1_replica_n1",
   [junit4]   2>   "core_node_name":"core_node3",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:38747/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:38747_solr",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"testNodeAdded",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 354313 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.o.ReplicaMutator Will update state for 
replica: 
core_node3:{"core":"testNodeAdded_shard1_replica_n1","base_url":"http://127.0.0.1:38747/solr","node_name":"127.0.0.1:38747_solr","state":"active","type":"NRT","leader":"true"}
   [junit4]   2> 354313 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.o.ReplicaMutator Collection is now: 
DocCollection(testNodeAdded//collections/testNodeAdded/state.json/4)={
   [junit4]   2>   "pullReplicas":"0",
   [junit4]   2>   "replicationFactor":"2",
   [junit4]   2>   "router":{"name":"compositeId"},
   [junit4]   2>   "maxShardsPerNode":"1",
   [junit4]   2>   "autoAddReplicas":"false",
   [junit4]   2>   "nrtReplicas":"2",
   [junit4]   2>   "tlogReplicas":"0",
   [junit4]   2>   "shards":{"shard1":{
   [junit4]   2>       "range":"80000000-7fffffff",
   [junit4]   2>       "state":"active",
   [junit4]   2>       "replicas":{
   [junit4]   2>         "core_node3":{
   [junit4]   2>           "core":"testNodeAdded_shard1_replica_n1",
   [junit4]   2>           "base_url":"http://127.0.0.1:38747/solr";,
   [junit4]   2>           "node_name":"127.0.0.1:38747_solr",
   [junit4]   2>           "state":"active",
   [junit4]   2>           "type":"NRT",
   [junit4]   2>           "leader":"true"},
   [junit4]   2>         "core_node4":{
   [junit4]   2>           "core":"testNodeAdded_shard1_replica_n2",
   [junit4]   2>           "base_url":"http://127.0.0.1:38747/solr";,
   [junit4]   2>           "node_name":"127.0.0.1:38747_solr",
   [junit4]   2>           "state":"down",
   [junit4]   2>           "type":"NRT"}}}}}
   [junit4]   2> 354348 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testNodeAdded_shard1_replica_n1&action=CREATE&numShards=1&collection=testNodeAdded&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1570
   [junit4]   2> 354413 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.o.ZkStateWriter going to update_collection 
/collections/testNodeAdded/state.json version: 4
   [junit4]   2> 354414 INFO  
(zkCallback-730-thread-3-processing-n:127.0.0.1:38747_solr) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has 
occurred - updating... (live nodes size: [1])
   [junit4]   2> 354414 INFO  
(zkCallback-730-thread-1-processing-n:127.0.0.1:38747_solr) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has 
occurred - updating... (live nodes size: [1])
   [junit4]   2> 354460 DEBUG (ScheduledTrigger-1700-thread-3) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.a.NodeAddedTrigger Running 
NodeAddedTrigger node_added_trigger
   [junit4]   2> 354460 DEBUG (ScheduledTrigger-1700-thread-3) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
   [junit4]   2> 354857 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.Overseer processMessage: queueSize: 1, 
message = {
   [junit4]   2>   "core":"testNodeAdded_shard1_replica_n2",
   [junit4]   2>   "core_node_name":"core_node4",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:38747/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:38747_solr",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"testNodeAdded",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"state"} current state version: 0
   [junit4]   2> 354857 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 
message={
   [junit4]   2>   "core":"testNodeAdded_shard1_replica_n2",
   [junit4]   2>   "core_node_name":"core_node4",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:38747/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:38747_solr",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"testNodeAdded",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 354857 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.o.ReplicaMutator Will update state for 
replica: 
core_node4:{"core":"testNodeAdded_shard1_replica_n2","base_url":"http://127.0.0.1:38747/solr","node_name":"127.0.0.1:38747_solr","state":"active","type":"NRT"}
   [junit4]   2> 354857 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.o.ReplicaMutator Collection is now: 
DocCollection(testNodeAdded//collections/testNodeAdded/state.json/5)={
   [junit4]   2>   "pullReplicas":"0",
   [junit4]   2>   "replicationFactor":"2",
   [junit4]   2>   "router":{"name":"compositeId"},
   [junit4]   2>   "maxShardsPerNode":"1",
   [junit4]   2>   "autoAddReplicas":"false",
   [junit4]   2>   "nrtReplicas":"2",
   [junit4]   2>   "tlogReplicas":"0",
   [junit4]   2>   "shards":{"shard1":{
   [junit4]   2>       "range":"80000000-7fffffff",
   [junit4]   2>       "state":"active",
   [junit4]   2>       "replicas":{
   [junit4]   2>         "core_node3":{
   [junit4]   2>           "core":"testNodeAdded_shard1_replica_n1",
   [junit4]   2>           "base_url":"http://127.0.0.1:38747/solr";,
   [junit4]   2>           "node_name":"127.0.0.1:38747_solr",
   [junit4]   2>           "state":"active",
   [junit4]   2>           "type":"NRT",
   [junit4]   2>           "leader":"true"},
   [junit4]   2>         "core_node4":{
   [junit4]   2>           "core":"testNodeAdded_shard1_replica_n2",
   [junit4]   2>           "base_url":"http://127.0.0.1:38747/solr";,
   [junit4]   2>           "node_name":"127.0.0.1:38747_solr",
   [junit4]   2>           "state":"active",
   [junit4]   2>           "type":"NRT"}}}}}
   [junit4]   2> 354857 INFO  (qtp5775301-3129) [n:127.0.0.1:38747_solr 
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=testNodeAdded_shard1_replica_n2&action=CREATE&numShards=1&collection=testNodeAdded&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=2079
   [junit4]   2> 354859 INFO  (qtp5775301-3136) [n:127.0.0.1:38747_solr    ] 
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 
30 seconds. Check all shard replicas
   [junit4]   2> 354958 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.o.ZkStateWriter going to update_collection 
/collections/testNodeAdded/state.json version: 5
   [junit4]   2> 354958 INFO  
(zkCallback-730-thread-3-processing-n:127.0.0.1:38747_solr) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has 
occurred - updating... (live nodes size: [1])
   [junit4]   2> 354958 INFO  
(zkCallback-730-thread-1-processing-n:127.0.0.1:38747_solr) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has 
occurred - updating... (live nodes size: [1])
   [junit4]   2> 355461 DEBUG (ScheduledTrigger-1700-thread-3) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.a.NodeAddedTrigger Running 
NodeAddedTrigger node_added_trigger
   [junit4]   2> 355461 DEBUG (ScheduledTrigger-1700-thread-3) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
   [junit4]   2> 355859 INFO  (qtp5775301-3136) [n:127.0.0.1:38747_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={replicationFactor=2&collection.configName=conf&name=testNodeAdded&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2}
 status=0 QTime=3399
   [junit4]   2> 355864 INFO  (qtp5775301-3135) [n:127.0.0.1:38747_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.core.testNodeAdded.shard1.replica_n1:INDEX.sizeInBytes&key=solr.core.testNodeAdded.shard1.replica_n2:INDEX.sizeInBytes}
 status=0 QTime=0
   [junit4]   2> 355866 INFO  (qtp5775301-3166) [n:127.0.0.1:38747_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 355866 DEBUG (qtp5775301-3134) [n:127.0.0.1:38747_solr    ] 
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 355866 INFO  (qtp5775301-3134) [n:127.0.0.1:38747_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=5
   [junit4]   2> 355867 DEBUG 
(zkCallback-730-thread-1-processing-n:127.0.0.1:38747_solr) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread Refreshing 
/autoscaling.json with znode version 7
   [junit4]   2> 355867 DEBUG 
(zkCallback-730-thread-1-processing-n:127.0.0.1:38747_solr) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: 
[127.0.0.1:38747_solr]
   [junit4]   2> 355867 DEBUG 
(zkCallback-730-thread-1-processing-n:127.0.0.1:38747_solr) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger 
node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=1, 
actions=[{name=compute_plan, class=solr.ComputePlanAction}, {name=test, 
class=org.apache.solr.cloud.autoscaling.ComputePlanActionTest$AssertingTriggerAction}],
 enabled=true}
   [junit4]   2> 355867 DEBUG 
(OverseerAutoScalingTriggerThread-72111241084928003-127.0.0.1:38747_solr-n_0000000000)
 [n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread Processed trigger 
updates upto znodeVersion 7
   [junit4]   2> 355867 DEBUG 
(OverseerAutoScalingTriggerThread-72111241084928003-127.0.0.1:38747_solr-n_0000000000)
 [n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeLost markers
   [junit4]   2> 355867 DEBUG 
(OverseerAutoScalingTriggerThread-72111241084928003-127.0.0.1:38747_solr-n_0000000000)
 [n:127.0.0.1:38747_solr    ] o.a.s.c.a.OverseerTriggerThread Current 
znodeVersion 7, lastZnodeVersion 7
   [junit4]   2> 355867 INFO  
(TEST-ComputePlanActionTest.testNodeAdded-seed#[8CE5D4B0CE38D203]) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-22T05:27:37+08:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 355868 INFO  
(TEST-ComputePlanActionTest.testNodeAdded-seed#[8CE5D4B0CE38D203]) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 355868 INFO  
(TEST-ComputePlanActionTest.testNodeAdded-seed#[8CE5D4B0CE38D203]) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 355868 INFO  
(TEST-ComputePlanActionTest.testNodeAdded-seed#[8CE5D4B0CE38D203]) [    ] 
o.e.j.s.session Scavenging every 660000ms
   [junit4]   2> 355868 INFO  
(TEST-ComputePlanActionTest.testNodeAdded-seed#[8CE5D4B0CE38D203]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@18d2962{/solr,null,AVAILABLE}
   [junit4]   2> 355868 INFO  
(TEST-ComputePlanActionTest.testNodeAdded-seed#[8CE5D4B0CE38D203]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@9885f6{HTTP/1.1,[http/1.1]}{127.0.0.1:43873}
   [junit4]   2> 355868 INFO  
(TEST-ComputePlanActionTest.testNodeAdded-seed#[8CE5D4B0CE38D203]) [    ] 
o.e.j.s.Server Started @358252ms
   [junit4]   2> 355869 INFO  
(TEST-ComputePlanActionTest.testNodeAdded-seed#[8CE5D4B0CE38D203]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=43873}
   [junit4]   2> 355869 ERROR 
(TEST-ComputePlanActionTest.testNodeAdded-seed#[8CE5D4B0CE38D203]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 355869 INFO  
(TEST-ComputePlanActionTest.testNodeAdded-seed#[8CE5D4B0CE38D203]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 355869 INFO  
(TEST-ComputePlanActionTest.testNodeAdded-seed#[8CE5D4B0CE38D203]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 355869 INFO  
(TEST-ComputePlanActionTest.testNodeAdded-seed#[8CE5D4B0CE38D203]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 355869 INFO  
(TEST-ComputePlanActionTest.testNodeAdded-seed#[8CE5D4B0CE38D203]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-02-08T02:03:52.217Z
   [junit4]   2> 355870 INFO  (zkConnectionManagerCallback-742-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 355871 INFO  
(TEST-ComputePlanActionTest.testNodeAdded-seed#[8CE5D4B0CE38D203]) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 355886 INFO  
(TEST-ComputePlanActionTest.testNodeAdded-seed#[8CE5D4B0CE38D203]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38341/solr
   [junit4]   2> 355887 INFO  (zkConnectionManagerCallback-746-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 355888 INFO  
(zkConnectionManagerCallback-748-thread-1-processing-n:127.0.0.1:43873_solr) 
[n:127.0.0.1:43873_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 355890 INFO  
(TEST-ComputePlanActionTest.testNodeAdded-seed#[8CE5D4B0CE38D203]) 
[n:127.0.0.1:43873_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 355891 INFO  
(TEST-ComputePlanActionTest.testNodeAdded-seed#[8CE5D4B0CE38D203]) 
[n:127.0.0.1:43873_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 355892 INFO  
(TEST-ComputePlanActionTest.testNodeAdded-seed#[8CE5D4B0CE38D203]) 
[n:127.0.0.1:43873_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:43873_solr
   [junit4]   2> 355892 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.Overseer processMessage: queueSize: 1, 
message = {
   [junit4]   2>   "operation":"downnode",
   [junit4]   2>   "node_name":"127.0.0.1:43873_solr"} current state version: 0
   [junit4]   2> 355892 DEBUG 
(OverseerStateUpdate-72111241084928003-127.0.0.1:38747_solr-n_0000000000) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.o.NodeMutator DownNode state invoked for 
node: 127.0.0.1:43873_solr
   [junit4]   2> 355892 INFO  
(zkCallback-730-thread-1-processing-n:127.0.0.1:38747_solr) 
[n:127.0.0.1:38747_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 355892 INFO  (zkCallback-738-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 355892 INFO  (zkCallback-738-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 355892 INFO  
(zkCallback-747-thread-1-processing-n:127.0.0.1:43873_solr) 
[n:127.0.0.1:43873_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 355983 INFO  
(TEST-ComputePlanActionTest.testNodeAdded-seed#[8CE5D4B0CE38D203]) 
[n:127.0.0.1:43873_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_43873.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1ad7b0c
   [junit4]   2> 355991 INFO  
(TEST-ComputePlanActionTest.testNodeAdded-seed#[8CE5D4B0CE38D203]) 
[n:127.0.0.1:43873_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_43873.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1ad7b0c
   [junit4]   2> 355991 INFO  
(TEST-ComputePlanActionTest.testNodeAdded-seed#[8CE5D4B0CE38D203]) 
[n:127.0.0.1:43873_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_43873.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1ad7b0c
   [junit4]   2> 355992 INFO  
(TEST-ComputePlanActionTest.testNodeAdded-seed#[8CE5D4B0CE38D203]) 
[n:127.0.0.1:43873_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.au

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

.ServletContextHandler@c7a0b7{/solr,null,UNAVAILABLE}
   [junit4]   2> 3456692 INFO  (jetty-closer-9472-thread-1) [    ] 
o.e.j.s.session Stopped scavenging
   [junit4]   2> 3458175 INFO  (jetty-closer-9472-thread-12) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@185d7cd{/solr,null,UNAVAILABLE}
   [junit4]   2> 3458175 INFO  (jetty-closer-9472-thread-12) [    ] 
o.e.j.s.session Stopped scavenging
   [junit4]   2> 3458175 ERROR 
(SUITE-TriggerIntegrationTest-seed#[8CE5D4B0CE38D203]-worker) [    ] 
o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper 
server won't take any action on ERROR or SHUTDOWN server state changes
   [junit4]   2> 3458176 INFO  
(SUITE-TriggerIntegrationTest-seed#[8CE5D4B0CE38D203]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:37395 37395
   [junit4]   2> 3458437 INFO  (Thread-7585) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:37395 37395
   [junit4]   2> 3458438 WARN  (Thread-7585) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        22      /solr/clusterprops.json
   [junit4]   2>        22      /solr/aliases.json
   [junit4]   2>        21      /solr/security.json
   [junit4]   2>        21      /solr/collections/collection1/terms/shard1
   [junit4]   2>        7       /solr/configs/conf
   [junit4]   2>        2       /solr/collections/testMetricTrigger/terms/shard1
   [junit4]   2>        2       /solr/collections/testMetricTrigger/terms/shard2
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        69      /solr/collections/collection1/state.json
   [junit4]   2>        22      /solr/clusterstate.json
   [junit4]   2>        13      /solr/collections/testMetricTrigger/state.json
   [junit4]   2>        6       /solr/autoscaling.json
   [junit4]   2>        3       
/solr/overseer_elect/election/72111432842280992-127.0.0.1:45759_solr-n_0000000009
   [junit4]   2>        3       
/solr/overseer_elect/election/72111432842281008-127.0.0.1:40631_solr-n_0000000014
   [junit4]   2>        3       
/solr/collections/collection1/leader_elect/shard1/election/72111432842280974-core_node4-n_0000000000
   [junit4]   2>        2       
/solr/overseer_elect/election/72111432842281014-127.0.0.1:40029_solr-n_0000000016
   [junit4]   2>        2       
/solr/overseer_elect/election/72111432842280986-127.0.0.1:34685_solr-n_0000000007
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        22      /solr/live_nodes
   [junit4]   2>        22      /solr/collections
   [junit4]   2>        6       /solr/overseer/queue-work
   [junit4]   2>        6       /solr/autoscaling/events/.auto_add_replicas
   [junit4]   2>        6       /solr/overseer/queue
   [junit4]   2>        6       /solr/overseer/collection-queue-work
   [junit4]   2>        2       /solr/autoscaling/events/node_lost_triggerMR
   [junit4]   2>        2       /solr/autoscaling/events/node_added_triggerCTOOR
   [junit4]   2> 
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.TriggerIntegrationTest_8CE5D4B0CE38D203-001
   [junit4]   2> NOTE: test params are: 
codec=DummyCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=DUMMY,
 chunkSize=4, maxDocsPerChunk=488, blockSize=6), 
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=DUMMY, 
chunkSize=4, blockSize=6)), 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@65b225),
 locale=sv-SE, timezone=America/Managua
   [junit4]   2> NOTE: Linux 4.13.0-32-generic i386/Oracle Corporation 
1.8.0_162 (32-bit)/cpus=8,threads=1,free=187586248,total=518979584
   [junit4]   2> NOTE: All tests run in this JVM: [InfixSuggestersTest, 
TestEmbeddedSolrServerConstructors, TestLegacyTerms, URLClassifyProcessorTest, 
TestRangeQuery, CollectionsAPISolrJTest, SolrTestCaseJ4Test, 
BaseCdcrDistributedZkTest, ConnectionManagerTest, 
PreAnalyzedUpdateProcessorTest, TestNRTOpen, TestChildDocTransformer, 
TestSolrCLIRunExample, HighlighterMaxOffsetTest, SpellPossibilityIteratorTest, 
TestStressReorder, TestComplexPhraseLeadingWildcard, PreAnalyzedFieldTest, 
HdfsTlogReplayBufferedWhileIndexingTest, QueryResultKeyTest, 
TestExclusionRuleCollectionAccess, TestDeleteCollectionOnDownNodes, 
TestPivotHelperCode, LargeFieldTest, ExecutePlanActionTest, ShardSplitTest, 
TestUninvertingReader, RecoveryZkTest, UninvertDocValuesMergePolicyTest, 
HttpSolrCallGetCoreTest, CdcrReplicationDistributedZkTest, 
BasicDistributedZk2Test, TestCoreBackup, TestLMJelinekMercerSimilarityFactory, 
TestQuerySenderListener, TestSchemaNameResource, TestPayloadScoreQParserPlugin, 
TestSimpleTextCodec, TestMacroExpander, TestNoOpRegenerator, OverseerRolesTest, 
TestFaceting, SearchHandlerTest, TestJsonRequest, SchemaApiFailureTest, 
TestFieldCache, TestFileDictionaryLookup, TestRealTimeGet, NoCacheHeaderTest, 
CoreMergeIndexesAdminHandlerTest, SolrMetricManagerTest, 
DeleteInactiveReplicaTest, TestDynamicFieldCollectionResource, 
TestSolrFieldCacheBean, HdfsDirectoryTest, TestCloudPivotFacet, 
TestSystemCollAutoCreate, UniqFieldsUpdateProcessorFactoryTest, 
SpellCheckComponentTest, AnalysisAfterCoreReloadTest, MultiTermTest, 
SolrGangliaReporterTest, TestQueryTypes, TestPullReplicaErrorHandling, 
UUIDUpdateProcessorFallbackTest, TestConfigSetImmutable, 
TestAnalyzedSuggestions, TestFieldResource, TermVectorComponentTest, 
CacheHeaderTest, TestReqParamsAPI, LegacyCloudClusterPropTest, TestLazyCores, 
ConnectionReuseTest, TestNestedDocsSort, ReplaceNodeNoTargetTest, 
TestCloudSearcherWarming, RestartWhileUpdatingTest, TestJmxIntegration, 
TestPerFieldSimilarityWithDefaultOverride, TestRestManager, 
TestSQLHandlerNonCloud, TimeZoneUtilsTest, DistributedFacetPivotLargeTest, 
RegexBoostProcessorTest, TestSubQueryTransformerDistrib, 
DistributedSpellCheckComponentTest, ConcurrentDeleteAndCreateCollectionTest, 
SuggesterTSTTest, AnalyticsQueryTest, TriLevelCompositeIdRoutingTest, 
EnumFieldTest, TestPolicyCloud, TestImplicitCoreProperties, 
HLLSerializationTest, MoreLikeThisHandlerTest, TestNamedUpdateProcessors, 
TestCoreDiscovery, ForceLeaderTest, UnloadDistributedZkTest, 
TestMaxScoreQueryParser, TestUseDocValuesAsStored, TestLargeCluster, 
TestCloudDeleteByQuery, AutoscalingHistoryHandlerTest, JvmMetricsTest, 
TestTrieFacet, HttpPartitionTest, TestReloadDeadlock, SolrCmdDistributorTest, 
TestLeaderInitiatedRecoveryThread, PeerSyncReplicationTest, 
TestSkipOverseerOperations, TestTolerantUpdateProcessorRandomCloud, 
DisMaxRequestHandlerTest, MetricTriggerTest, CurrencyFieldTypeTest, 
DistributedDebugComponentTest, TestReplicaProperties, DeleteReplicaTest, 
ResponseLogComponentTest, FileBasedSpellCheckerTest, TestSmileRequest, 
TestCoreContainer, ShowFileRequestHandlerTest, 
DistributedQueryElevationComponentTest, SolrIndexSplitterTest, 
CoreAdminHandlerTest, DocValuesTest, SuggesterTest, TestStressLucene, 
SpatialFilterTest, TestCSVLoader, PolyFieldTest, WordBreakSolrSpellCheckerTest, 
TestPseudoReturnFields, TestUpdate, TestAtomicUpdateErrorCases, 
TestWordDelimiterFilterFactory, QueryEqualityTest, 
DirectUpdateHandlerOptimizeTest, StatelessScriptUpdateProcessorFactoryTest, 
DocValuesMultiTest, DistanceFunctionTest, SolrInfoBeanTest, DebugComponentTest, 
LukeRequestHandlerTest, DirectSolrSpellCheckerTest, TestOmitPositions, 
TermsComponentTest, TestValueSourceCache, PathHierarchyTokenizerFactoryTest, 
RequiredFieldsTest, FieldAnalysisRequestHandlerTest, TestSolrQueryParser, 
FastVectorHighlighterTest, IndexSchemaRuntimeFieldTest, LoggingHandlerTest, 
TestCollationField, ReturnFieldsTest, UpdateRequestProcessorFactoryTest, 
TestCSVResponseWriter, MBeansHandlerTest, TestPartialUpdateDeduplication, 
PingRequestHandlerTest, HighlighterConfigTest, UpdateParamsTest, 
AlternateDirectoryTest, TestSolrIndexConfig, TestQuerySenderNoQuery, 
CopyFieldTest, SolrIndexConfigTest, BadComponentTest, TestSolrDeletionPolicy2, 
SampleTest, NumericFieldsTest, MinimalSchemaTest, TestFuzzyAnalyzedSuggestions, 
ExternalFileFieldSortTest, TestSolrCoreProperties, TestPostingsSolrHighlighter, 
NotRequiredUniqueKeyTest, TestPhraseSuggestions, TestCharFilters, 
TestCodecSupport, TestXIncludeConfig, EchoParamsTest, TestDFRSimilarityFactory, 
TestBM25SimilarityFactory, TestFastWriter, TestFastOutputStream, 
OpenExchangeRatesOrgProviderTest, PluginInfoTest, TestFastLRUCache, 
DateMathParserTest, TestSystemIdResolver, TestSuggestSpellingConverter, 
DateFieldTest, SpellingQueryConverterTest, DOMUtilTest, ClusterStateTest, 
TestUtils, TestDocumentBuilder, SliceStateTest, SystemInfoHandlerTest, 
UUIDFieldTest, FileUtilsTest, CircularListTest, DistributedMLTComponentTest, 
DistributedIntervalFacetingTest, TestHighlightDedupGrouping, 
TestTolerantSearch, TestEmbeddedSolrServerSchemaAPI, TestJettySolrRunner, 
ActionThrottleTest, AliasIntegrationTest, CleanupOldIndexTest, 
ConfigSetsAPITest, CreateCollectionCleanupTest, CreateRoutedAliasTest, 
DeleteStatusTest, DistribJoinFromCollectionTest, DistributedQueueTest, 
DocValuesNotIndexedTest, HttpPartitionOnCommitTest, 
LeaderFailureAfterFreshStartTest, MigrateRouteKeyTest, MoveReplicaHDFSTest, 
OverriddenZkACLAndCredentialsProvidersTest, OverseerTaskQueueTest, 
RemoteQueryErrorTest, ReplicationFactorTest, RollingRestartTest, 
SaslZkACLProviderTest, SolrCLIZkUtilsTest, SolrCloudExampleTest, 
TestAuthenticationFramework, TestCloudJSONFacetJoinDomain, 
TestCloudPseudoReturnFields, TestConfigSetsAPIExclusivity, TestCryptoKeys, 
TestLockTree, TestMiniSolrCloudClusterSSL, TestOnReconnectListenerSupport, 
TestPrepRecovery, TestRequestForwarding, TestSSLRandomization, 
TestSegmentSorting, TestStressLiveNodes, TestUtilizeNode, 
VMParamsZkACLAndCredentialsProvidersTest, ZkShardTermsTest, 
CollectionTooManyReplicasTest, CollectionsAPIAsyncDistributedZkTest, 
TestCollectionsAPIViaSolrCloudCluster, TestHdfsCloudBackupRestore, 
TestRequestStatusCollectionAPI, AutoScalingHandlerTest, SearchRateTriggerTest, 
TriggerIntegrationTest]
   [junit4] Completed [599/775 (4!)] on J2 in 180.24s, 14 tests, 1 failure <<< 
FAILURES!

[...truncated 44487 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to