Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/4266/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC

1 tests failed.
FAILED:  
org.apache.solr.cloud.autoscaling.TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy

Error Message:
Could not find collection : policiesTest

Stack Trace:
org.apache.solr.common.SolrException: Could not find collection : policiesTest
        at 
__randomizedtesting.SeedInfo.seed([A4833CD61F108AE6:34D1294512DC4B1A]:0)
        at 
org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:111)
        at 
org.apache.solr.cloud.SolrCloudTestCase.getCollectionState(SolrCloudTestCase.java:247)
        at 
org.apache.solr.cloud.autoscaling.TestPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy(TestPolicyCloud.java:267)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
org.junit.rules.ExpectedException$ExpectedExceptionStatement.evaluate(ExpectedException.java:110)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 13449 lines...]
   [junit4] Suite: org.apache.solr.cloud.autoscaling.TestPolicyCloud
   [junit4]   2> Creating dataDir: 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TestPolicyCloud_A4833CD61F108AE6-001/init-core-data-001
   [junit4]   2> 3945939 WARN  
(SUITE-TestPolicyCloud-seed#[A4833CD61F108AE6]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=8 numCloses=8
   [junit4]   2> 3945939 INFO  
(SUITE-TestPolicyCloud-seed#[A4833CD61F108AE6]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 3945946 INFO  
(SUITE-TestPolicyCloud-seed#[A4833CD61F108AE6]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) 
w/ MAC_OS_X supressed clientAuth
   [junit4]   2> 3945946 INFO  
(SUITE-TestPolicyCloud-seed#[A4833CD61F108AE6]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 3945947 INFO  
(SUITE-TestPolicyCloud-seed#[A4833CD61F108AE6]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 5 servers in 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TestPolicyCloud_A4833CD61F108AE6-001/tempDir-001
   [junit4]   2> 3945947 INFO  
(SUITE-TestPolicyCloud-seed#[A4833CD61F108AE6]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 3945948 INFO  (Thread-4537) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 3945948 INFO  (Thread-4537) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 3945950 ERROR (Thread-4537) [    ] 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> 3946058 INFO  
(SUITE-TestPolicyCloud-seed#[A4833CD61F108AE6]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:64568
   [junit4]   2> 3946099 INFO  (jetty-launcher-3472-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 3946110 INFO  (jetty-launcher-3472-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 3946111 INFO  (jetty-launcher-3472-thread-3) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 3946113 INFO  (jetty-launcher-3472-thread-4) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 3946113 INFO  (jetty-launcher-3472-thread-5) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 3946115 INFO  (jetty-launcher-3472-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@332973c3{/solr,null,AVAILABLE}
   [junit4]   2> 3946116 INFO  (jetty-launcher-3472-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@6307eeea{/solr,null,AVAILABLE}
   [junit4]   2> 3946119 INFO  (jetty-launcher-3472-thread-4) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@7d61d20d{/solr,null,AVAILABLE}
   [junit4]   2> 3946119 INFO  (jetty-launcher-3472-thread-3) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@617c079d{/solr,null,AVAILABLE}
   [junit4]   2> 3946119 INFO  (jetty-launcher-3472-thread-2) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@398c34e9{HTTP/1.1,[http/1.1]}{127.0.0.1:६४५७१}
   [junit4]   2> 3946119 INFO  (jetty-launcher-3472-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@62be5dee{HTTP/1.1,[http/1.1]}{127.0.0.1:६४५७०}
   [junit4]   2> 3946119 INFO  (jetty-launcher-3472-thread-2) [    ] 
o.e.j.s.Server Started @३९४९७६६ms
   [junit4]   2> 3946119 INFO  (jetty-launcher-3472-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=64571}
   [junit4]   2> 3946119 INFO  (jetty-launcher-3472-thread-3) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@7c83fd42{HTTP/1.1,[http/1.1]}{127.0.0.1:६४५७३}
   [junit4]   2> 3946119 INFO  (jetty-launcher-3472-thread-3) [    ] 
o.e.j.s.Server Started @३९४९७६६ms
   [junit4]   2> 3946119 INFO  (jetty-launcher-3472-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=64573}
   [junit4]   2> 3946120 ERROR (jetty-launcher-3472-thread-3) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 3946119 INFO  (jetty-launcher-3472-thread-4) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@44ca5bb1{HTTP/1.1,[http/1.1]}{127.0.0.1:६४५७२}
   [junit4]   2> 3946120 INFO  (jetty-launcher-3472-thread-4) [    ] 
o.e.j.s.Server Started @३९४९७६७ms
   [junit4]   2> 3946120 INFO  (jetty-launcher-3472-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 3946120 INFO  (jetty-launcher-3472-thread-4) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=64572}
   [junit4]   2> 3946120 INFO  (jetty-launcher-3472-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 3946120 INFO  (jetty-launcher-3472-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 3946120 ERROR (jetty-launcher-3472-thread-4) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 3946119 ERROR (jetty-launcher-3472-thread-2) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 3946121 INFO  (jetty-launcher-3472-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 3946121 INFO  (jetty-launcher-3472-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 3946121 INFO  (jetty-launcher-3472-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 3946121 INFO  (jetty-launcher-3472-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 3946121 INFO  (jetty-launcher-3472-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 3946119 INFO  (jetty-launcher-3472-thread-1) [    ] 
o.e.j.s.Server Started @३९४९७६६ms
   [junit4]   2> 3946121 INFO  (jetty-launcher-3472-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=64570}
   [junit4]   2> 3946121 INFO  (jetty-launcher-3472-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-11-03T08:35:01.850Z
   [junit4]   2> 3946121 ERROR (jetty-launcher-3472-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 3946121 INFO  (jetty-launcher-3472-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 3946121 INFO  (jetty-launcher-3472-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 3946121 INFO  (jetty-launcher-3472-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-11-03T08:35:01.850Z
   [junit4]   2> 3946121 INFO  (jetty-launcher-3472-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 3946122 INFO  (jetty-launcher-3472-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 3946122 INFO  (jetty-launcher-3472-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-11-03T08:35:01.851Z
   [junit4]   2> 3946121 INFO  (jetty-launcher-3472-thread-5) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2968c036{/solr,null,AVAILABLE}
   [junit4]   2> 3946120 INFO  (jetty-launcher-3472-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-11-03T08:35:01.849Z
   [junit4]   2> 3946122 INFO  (jetty-launcher-3472-thread-5) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@737ca3d4{HTTP/1.1,[http/1.1]}{127.0.0.1:६४५७४}
   [junit4]   2> 3946122 INFO  (jetty-launcher-3472-thread-5) [    ] 
o.e.j.s.Server Started @३९४९७६९ms
   [junit4]   2> 3946122 INFO  (jetty-launcher-3472-thread-5) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=64574}
   [junit4]   2> 3946122 ERROR (jetty-launcher-3472-thread-5) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 3946123 INFO  (jetty-launcher-3472-thread-5) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 3946123 INFO  (jetty-launcher-3472-thread-5) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 3946123 INFO  (jetty-launcher-3472-thread-5) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 3946123 INFO  (jetty-launcher-3472-thread-5) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-11-03T08:35:01.852Z
   [junit4]   2> 3946125 INFO  (jetty-launcher-3472-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 3946128 INFO  (jetty-launcher-3472-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 3946129 INFO  (jetty-launcher-3472-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 3946131 INFO  (jetty-launcher-3472-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 3946132 INFO  (jetty-launcher-3472-thread-5) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 3946138 INFO  (jetty-launcher-3472-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64568/solr
   [junit4]   2> 3946139 INFO  (jetty-launcher-3472-thread-4) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64568/solr
   [junit4]   2> 3946141 INFO  (jetty-launcher-3472-thread-5) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64568/solr
   [junit4]   2> 3946141 INFO  (jetty-launcher-3472-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64568/solr
   [junit4]   2> 3946141 INFO  (jetty-launcher-3472-thread-3) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64568/solr
   [junit4]   2> 3946386 INFO  (jetty-launcher-3472-thread-5) 
[n:127.0.0.1:64574_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 3946386 INFO  (jetty-launcher-3472-thread-3) 
[n:127.0.0.1:64573_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 3946386 INFO  (jetty-launcher-3472-thread-1) 
[n:127.0.0.1:64570_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 3946386 INFO  (jetty-launcher-3472-thread-4) 
[n:127.0.0.1:64572_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 3946387 INFO  (jetty-launcher-3472-thread-2) 
[n:127.0.0.1:64571_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 3946388 INFO  (jetty-launcher-3472-thread-5) 
[n:127.0.0.1:64574_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:64574_solr
   [junit4]   2> 3946389 INFO  (jetty-launcher-3472-thread-3) 
[n:127.0.0.1:64573_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:64573_solr
   [junit4]   2> 3946389 INFO  (jetty-launcher-3472-thread-1) 
[n:127.0.0.1:64570_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:64570_solr
   [junit4]   2> 3946389 INFO  (jetty-launcher-3472-thread-4) 
[n:127.0.0.1:64572_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:64572_solr
   [junit4]   2> 3946389 INFO  (jetty-launcher-3472-thread-2) 
[n:127.0.0.1:64571_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:64571_solr
   [junit4]   2> 3946390 INFO  (jetty-launcher-3472-thread-5) 
[n:127.0.0.1:64574_solr    ] o.a.s.c.Overseer Overseer 
(id=98939574791569422-127.0.0.1:64574_solr-n_0000000000) starting
   [junit4]   2> 3946430 INFO  
(zkCallback-3497-thread-1-processing-n:127.0.0.1:64570_solr) 
[n:127.0.0.1:64570_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (4)
   [junit4]   2> 3946434 INFO  
(zkCallback-3495-thread-1-processing-n:127.0.0.1:64571_solr) 
[n:127.0.0.1:64571_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (4)
   [junit4]   2> 3946437 INFO  
(zkCallback-3498-thread-1-processing-n:127.0.0.1:64574_solr) 
[n:127.0.0.1:64574_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (4)
   [junit4]   2> 3946440 INFO  
(zkCallback-3499-thread-1-processing-n:127.0.0.1:64573_solr) 
[n:127.0.0.1:64573_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (4)
   [junit4]   2> 3946442 INFO  
(zkCallback-3496-thread-1-processing-n:127.0.0.1:64572_solr) 
[n:127.0.0.1:64572_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (4)
   [junit4]   2> 3946511 INFO  (jetty-launcher-3472-thread-5) 
[n:127.0.0.1:64574_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:64574_solr
   [junit4]   2> 3946514 INFO  
(zkCallback-3499-thread-1-processing-n:127.0.0.1:64573_solr) 
[n:127.0.0.1:64573_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (4) -> (5)
   [junit4]   2> 3946516 INFO  
(zkCallback-3498-thread-1-processing-n:127.0.0.1:64574_solr) 
[n:127.0.0.1:64574_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (4) -> (5)
   [junit4]   2> 3946516 INFO  
(zkCallback-3495-thread-1-processing-n:127.0.0.1:64571_solr) 
[n:127.0.0.1:64571_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (4) -> (5)
   [junit4]   2> 3946518 INFO  
(zkCallback-3497-thread-1-processing-n:127.0.0.1:64570_solr) 
[n:127.0.0.1:64570_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (4) -> (5)
   [junit4]   2> 3946518 INFO  
(zkCallback-3496-thread-1-processing-n:127.0.0.1:64572_solr) 
[n:127.0.0.1:64572_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (4) -> (5)
   [junit4]   2> 3946530 INFO  (jetty-launcher-3472-thread-4) 
[n:127.0.0.1:64572_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_64572.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2d197217
   [junit4]   2> 3946541 INFO  (jetty-launcher-3472-thread-3) 
[n:127.0.0.1:64573_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_64573.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2d197217
   [junit4]   2> 3946541 INFO  (jetty-launcher-3472-thread-4) 
[n:127.0.0.1:64572_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_64572.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2d197217
   [junit4]   2> 3946541 INFO  (jetty-launcher-3472-thread-4) 
[n:127.0.0.1:64572_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_64572.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2d197217
   [junit4]   2> 3946542 INFO  (jetty-launcher-3472-thread-4) 
[n:127.0.0.1:64572_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TestPolicyCloud_A4833CD61F108AE6-001/tempDir-001/node4/.
   [junit4]   2> 3946561 INFO  (jetty-launcher-3472-thread-3) 
[n:127.0.0.1:64573_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_64573.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2d197217
   [junit4]   2> 3946561 INFO  (jetty-launcher-3472-thread-3) 
[n:127.0.0.1:64573_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_64573.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2d197217
   [junit4]   2> 3946562 INFO  (jetty-launcher-3472-thread-3) 
[n:127.0.0.1:64573_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TestPolicyCloud_A4833CD61F108AE6-001/tempDir-001/node3/.
   [junit4]   2> 3946584 INFO  (jetty-launcher-3472-thread-1) 
[n:127.0.0.1:64570_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_64570.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2d197217
   [junit4]   2> 3946595 INFO  (jetty-launcher-3472-thread-1) 
[n:127.0.0.1:64570_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_64570.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2d197217
   [junit4]   2> 3946595 INFO  (jetty-launcher-3472-thread-1) 
[n:127.0.0.1:64570_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_64570.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2d197217
   [junit4]   2> 3946596 INFO  (jetty-launcher-3472-thread-1) 
[n:127.0.0.1:64570_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TestPolicyCloud_A4833CD61F108AE6-001/tempDir-001/node1/.
   [junit4]   2> 3946630 INFO  (jetty-launcher-3472-thread-5) 
[n:127.0.0.1:64574_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_64574.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2d197217
   [junit4]   2> 3946641 INFO  (jetty-launcher-3472-thread-2) 
[n:127.0.0.1:64571_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_64571.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2d197217
   [junit4]   2> 3946661 INFO  (jetty-launcher-3472-thread-5) 
[n:127.0.0.1:64574_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_64574.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2d197217
   [junit4]   2> 3946661 INFO  (jetty-launcher-3472-thread-5) 
[n:127.0.0.1:64574_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_64574.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2d197217
   [junit4]   2> 3946663 INFO  (jetty-launcher-3472-thread-5) 
[n:127.0.0.1:64574_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TestPolicyCloud_A4833CD61F108AE6-001/tempDir-001/node5/.
   [junit4]   2> 3946665 INFO  (jetty-launcher-3472-thread-2) 
[n:127.0.0.1:64571_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_64571.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2d197217
   [junit4]   2> 3946665 INFO  (jetty-launcher-3472-thread-2) 
[n:127.0.0.1:64571_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_64571.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2d197217
   [junit4]   2> 3946666 INFO  (jetty-launcher-3472-thread-2) 
[n:127.0.0.1:64571_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TestPolicyCloud_A4833CD61F108AE6-001/tempDir-001/node2/.
   [junit4]   2> 3946742 INFO  
(SUITE-TestPolicyCloud-seed#[A4833CD61F108AE6]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
   [junit4]   2> 3946744 INFO  
(SUITE-TestPolicyCloud-seed#[A4833CD61F108AE6]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64568/solr ready
   [junit4]   2> 3946934 INFO  
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[A4833CD61F108AE6]) [ 
   ] o.a.s.SolrTestCaseJ4 ###Starting testCreateCollectionAddReplica
   [junit4]   2> 3946944 INFO  (qtp1400223612-23406) [n:127.0.0.1:64571_solr    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
   [junit4]   2> 3946946 INFO  (qtp1400223612-23406) [n:127.0.0.1:64571_solr    
] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64568/solr ready
   [junit4]   2> 3946947 INFO  (qtp1400223612-23408) [n:127.0.0.1:64571_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 3946949 INFO  (qtp580572421-23414) [n:127.0.0.1:64573_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 3946950 INFO  (qtp313356237-23395) [n:127.0.0.1:64570_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 3946951 INFO  (qtp117947705-23431) [n:127.0.0.1:64572_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 3946953 INFO  (qtp1916437788-23423) [n:127.0.0.1:64574_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 3946954 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from 
client sessionid 0x15f8104e1ae0012, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 3946956 INFO  (qtp1400223612-23406) [n:127.0.0.1:64571_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=20
   [junit4]   2> 3946957 INFO  (qtp1400223612-23409) [n:127.0.0.1:64571_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=1&collection.configName=conf&name=testCreateCollectionAddReplica&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2&policy=c1
 and sendToOCPQueue=true
   [junit4]   2> 3946962 INFO  
(OverseerThreadFactory-10185-thread-1-processing-n:127.0.0.1:64574_solr) 
[n:127.0.0.1:64574_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
testCreateCollectionAddReplica
   [junit4]   2> 3946975 INFO  (qtp313356237-23397) [n:127.0.0.1:64570_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 3946977 INFO  (qtp1400223612-23410) [n:127.0.0.1:64571_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 3946978 INFO  (qtp117947705-23433) [n:127.0.0.1:64572_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 3946979 INFO  (qtp580572421-23415) [n:127.0.0.1:64573_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 3946980 INFO  (qtp1916437788-23425) [n:127.0.0.1:64574_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 3947115 INFO  
(OverseerStateUpdate-98939574791569422-127.0.0.1:64574_solr-n_0000000000) 
[n:127.0.0.1:64574_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"testCreateCollectionAddReplica",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"testCreateCollectionAddReplica_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:64572/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 3947335 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=testCreateCollectionAddReplica_shard1_replica_n1&action=CREATE&numShards=1&collection=testCreateCollectionAddReplica&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 3947336 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 3947451 INFO  
(zkCallback-3496-thread-1-processing-n:127.0.0.1:64572_solr) 
[n:127.0.0.1:64572_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testCreateCollectionAddReplica/state.json] for collection 
[testCreateCollectionAddReplica] has occurred - updating... (live nodes size: 
[5])
   [junit4]   2> 3947452 INFO  
(zkCallback-3496-thread-2-processing-n:127.0.0.1:64572_solr) 
[n:127.0.0.1:64572_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testCreateCollectionAddReplica/state.json] for collection 
[testCreateCollectionAddReplica] has occurred - updating... (live nodes size: 
[5])
   [junit4]   2> 3948379 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node2 
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 8.0.0
   [junit4]   2> 3948389 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node2 
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.s.IndexSchema 
[testCreateCollectionAddReplica_shard1_replica_n1] Schema name=minimal
   [junit4]   2> 3948395 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node2 
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.s.IndexSchema Loaded 
schema minimal/1.1 with uniqueid field id
   [junit4]   2> 3948395 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node2 
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.c.CoreContainer 
Creating SolrCore 'testCreateCollectionAddReplica_shard1_replica_n1' using 
configuration from collection testCreateCollectionAddReplica, trusted=true
   [junit4]   2> 3948396 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node2 
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter 
JMX monitoring for 
'solr_64572.solr.core.testCreateCollectionAddReplica.shard1.replica_n1' 
(registry 'solr.core.testCreateCollectionAddReplica.shard1.replica_n1') enabled 
at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d197217
   [junit4]   2> 3948396 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node2 
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 3948396 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node2 
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.c.SolrCore 
[[testCreateCollectionAddReplica_shard1_replica_n1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TestPolicyCloud_A4833CD61F108AE6-001/tempDir-001/node4/testCreateCollectionAddReplica_shard1_replica_n1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TestPolicyCloud_A4833CD61F108AE6-001/tempDir-001/node4/./testCreateCollectionAddReplica_shard1_replica_n1/data/]
   [junit4]   2> 3948461 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node2 
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 3948461 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node2 
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 3948462 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node2 
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 3948462 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node2 
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 3948463 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node2 
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@5349fdfd[testCreateCollectionAddReplica_shard1_replica_n1] 
main]
   [junit4]   2> 3948464 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node2 
x:testCreateCollectionAddReplica_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 3948465 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node2 
x:testCreateCollectionAddReplica_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 3948465 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node2 
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.h.ReplicationHandler 
Commits will be reserved for 10000ms.
   [junit4]   2> 3948466 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node2 
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.u.UpdateLog Could not 
find max version in index or recent updates, using new clock 1583033199304376320
   [junit4]   2> 3948466 INFO  
(searcherExecutor-10193-thread-1-processing-n:127.0.0.1:64572_solr 
x:testCreateCollectionAddReplica_shard1_replica_n1 s:shard1 
c:testCreateCollectionAddReplica r:core_node2) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node2 
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.c.SolrCore 
[testCreateCollectionAddReplica_shard1_replica_n1] Registered new searcher 
Searcher@5349fdfd[testCreateCollectionAddReplica_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 3948471 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node2 
x:testCreateCollectionAddReplica_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 3948471 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node2 
x:testCreateCollectionAddReplica_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 3948471 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node2 
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.c.SyncStrategy Sync 
replicas to 
http://127.0.0.1:64572/solr/testCreateCollectionAddReplica_shard1_replica_n1/
   [junit4]   2> 3948471 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node2 
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.c.SyncStrategy Sync 
Success - now sync replicas to me
   [junit4]   2> 3948471 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node2 
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.c.SyncStrategy 
http://127.0.0.1:64572/solr/testCreateCollectionAddReplica_shard1_replica_n1/ 
has no replicas
   [junit4]   2> 3948471 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node2 
x:testCreateCollectionAddReplica_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 3948476 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node2 
x:testCreateCollectionAddReplica_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:64572/solr/testCreateCollectionAddReplica_shard1_replica_n1/ 
shard1
   [junit4]   2> 3948589 INFO  
(zkCallback-3496-thread-1-processing-n:127.0.0.1:64572_solr) 
[n:127.0.0.1:64572_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testCreateCollectionAddReplica/state.json] for collection 
[testCreateCollectionAddReplica] has occurred - updating... (live nodes size: 
[5])
   [junit4]   2> 3948589 INFO  
(zkCallback-3496-thread-2-processing-n:127.0.0.1:64572_solr) 
[n:127.0.0.1:64572_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testCreateCollectionAddReplica/state.json] for collection 
[testCreateCollectionAddReplica] has occurred - updating... (live nodes size: 
[5])
   [junit4]   2> 3948651 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node2 
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.c.ZkController I am 
the leader, no recovery necessary
   [junit4]   2> 3948654 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node2 
x:testCreateCollectionAddReplica_shard1_replica_n1] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=testCreateCollectionAddReplica_shard1_replica_n1&action=CREATE&numShards=1&collection=testCreateCollectionAddReplica&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1318
   [junit4]   2> 3948667 INFO  (qtp1400223612-23409) [n:127.0.0.1:64571_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> 3948767 INFO  
(zkCallback-3496-thread-2-processing-n:127.0.0.1:64572_solr) 
[n:127.0.0.1:64572_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testCreateCollectionAddReplica/state.json] for collection 
[testCreateCollectionAddReplica] has occurred - updating... (live nodes size: 
[5])
   [junit4]   2> 3948767 INFO  
(zkCallback-3496-thread-1-processing-n:127.0.0.1:64572_solr) 
[n:127.0.0.1:64572_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testCreateCollectionAddReplica/state.json] for collection 
[testCreateCollectionAddReplica] has occurred - updating... (live nodes size: 
[5])
   [junit4]   2> 3948976 INFO  
(OverseerCollectionConfigSetProcessor-98939574791569422-127.0.0.1:64574_solr-n_0000000000)
 [n:127.0.0.1:64574_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000000 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 3949670 INFO  (qtp1400223612-23409) [n:127.0.0.1:64571_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={replicationFactor=1&collection.configName=conf&name=testCreateCollectionAddReplica&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2&policy=c1}
 status=0 QTime=2713
   [junit4]   2> 3949674 INFO  (qtp1400223612-23404) [n:127.0.0.1:64571_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with 
params 
action=ADDREPLICA&collection=testCreateCollectionAddReplica&shard=shard1&type=NRT&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 3949699 INFO  (qtp1400223612-23407) [n:127.0.0.1:64571_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 3949702 INFO  (qtp580572421-23416) [n:127.0.0.1:64573_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 3949705 INFO  (qtp313356237-23398) [n:127.0.0.1:64570_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 3949707 INFO  (qtp117947705-23436) [n:127.0.0.1:64572_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 3949709 INFO  (qtp1916437788-23426) [n:127.0.0.1:64574_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 3949710 INFO  
(OverseerThreadFactory-10185-thread-2-processing-n:127.0.0.1:64574_solr) 
[n:127.0.0.1:64574_solr    ] o.a.s.c.AddReplicaCmd Node Identified 
127.0.0.1:64572_solr for creating new replica
   [junit4]   2> 3949716 INFO  
(OverseerStateUpdate-98939574791569422-127.0.0.1:64574_solr-n_0000000000) 
[n:127.0.0.1:64574_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"addreplica",
   [junit4]   2>   "collection":"testCreateCollectionAddReplica",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"testCreateCollectionAddReplica_shard1_replica_n3",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:64572/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:64572_solr",
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 3949825 INFO  
(zkCallback-3496-thread-1-processing-n:127.0.0.1:64572_solr) 
[n:127.0.0.1:64572_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testCreateCollectionAddReplica/state.json] for collection 
[testCreateCollectionAddReplica] has occurred - updating... (live nodes size: 
[5])
   [junit4]   2> 3949825 INFO  
(zkCallback-3496-thread-2-processing-n:127.0.0.1:64572_solr) 
[n:127.0.0.1:64572_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testCreateCollectionAddReplica/state.json] for collection 
[testCreateCollectionAddReplica] has occurred - updating... (live nodes size: 
[5])
   [junit4]   2> 3949938 INFO  (qtp117947705-23429) [n:127.0.0.1:64572_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&name=testCreateCollectionAddReplica_shard1_replica_n3&action=CREATE&collection=testCreateCollectionAddReplica&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 3949966 INFO  (qtp117947705-23429) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 8.0.0
   [junit4]   2> 3949977 INFO  (qtp117947705-23429) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.s.IndexSchema 
[testCreateCollectionAddReplica_shard1_replica_n3] Schema name=minimal
   [junit4]   2> 3949983 INFO  (qtp117947705-23429) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.s.IndexSchema Loaded 
schema minimal/1.1 with uniqueid field id
   [junit4]   2> 3949983 INFO  (qtp117947705-23429) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.CoreContainer 
Creating SolrCore 'testCreateCollectionAddReplica_shard1_replica_n3' using 
configuration from collection testCreateCollectionAddReplica, trusted=true
   [junit4]   2> 3949984 INFO  (qtp117947705-23429) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.m.r.SolrJmxReporter 
JMX monitoring for 
'solr_64572.solr.core.testCreateCollectionAddReplica.shard1.replica_n3' 
(registry 'solr.core.testCreateCollectionAddReplica.shard1.replica_n3') enabled 
at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d197217
   [junit4]   2> 3949984 INFO  (qtp117947705-23429) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 3949984 INFO  (qtp117947705-23429) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.SolrCore 
[[testCreateCollectionAddReplica_shard1_replica_n3] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TestPolicyCloud_A4833CD61F108AE6-001/tempDir-001/node4/testCreateCollectionAddReplica_shard1_replica_n3],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TestPolicyCloud_A4833CD61F108AE6-001/tempDir-001/node4/./testCreateCollectionAddReplica_shard1_replica_n3/data/]
   [junit4]   2> 3950066 INFO  
(zkCallback-3496-thread-2-processing-n:127.0.0.1:64572_solr) 
[n:127.0.0.1:64572_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testCreateCollectionAddReplica/state.json] for collection 
[testCreateCollectionAddReplica] has occurred - updating... (live nodes size: 
[5])
   [junit4]   2> 3950066 INFO  
(zkCallback-3496-thread-1-processing-n:127.0.0.1:64572_solr) 
[n:127.0.0.1:64572_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testCreateCollectionAddReplica/state.json] for collection 
[testCreateCollectionAddReplica] has occurred - updating... (live nodes size: 
[5])
   [junit4]   2> 3950073 INFO  (qtp117947705-23429) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 3950073 INFO  (qtp117947705-23429) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 3950075 INFO  (qtp117947705-23429) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 3950075 INFO  (qtp117947705-23429) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 3950076 INFO  (qtp117947705-23429) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@569e8656[testCreateCollectionAddReplica_shard1_replica_n3] 
main]
   [junit4]   2> 3950077 INFO  (qtp117947705-23429) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 3950078 INFO  (qtp117947705-23429) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 3950078 INFO  (qtp117947705-23429) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.h.ReplicationHandler 
Commits will be reserved for 10000ms.
   [junit4]   2> 3950078 INFO  (qtp117947705-23429) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.u.UpdateLog Could not 
find max version in index or recent updates, using new clock 1583033200994680832
   [junit4]   2> 3950079 INFO  
(searcherExecutor-10198-thread-1-processing-n:127.0.0.1:64572_solr 
x:testCreateCollectionAddReplica_shard1_replica_n3 s:shard1 
c:testCreateCollectionAddReplica r:core_node4) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.SolrCore 
[testCreateCollectionAddReplica_shard1_replica_n3] Registered new searcher 
Searcher@569e8656[testCreateCollectionAddReplica_shard1_replica_n3] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 3950081 INFO  (qtp117947705-23429) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.ZkController Core 
needs to recover:testCreateCollectionAddReplica_shard1_replica_n3
   [junit4]   2> 3950082 INFO  
(updateExecutor-3481-thread-1-processing-n:127.0.0.1:64572_solr 
x:testCreateCollectionAddReplica_shard1_replica_n3 s:shard1 
c:testCreateCollectionAddReplica r:core_node4) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] 
o.a.s.u.DefaultSolrCoreState Running recovery
   [junit4]   2> 3950082 INFO  (qtp117947705-23429) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&name=testCreateCollectionAddReplica_shard1_replica_n3&action=CREATE&collection=testCreateCollectionAddReplica&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=144
   [junit4]   2> 3950083 INFO  
(recoveryExecutor-3483-thread-1-processing-n:127.0.0.1:64572_solr 
x:testCreateCollectionAddReplica_shard1_replica_n3 s:shard1 
c:testCreateCollectionAddReplica r:core_node4) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.RecoveryStrategy 
Starting recovery process. recoveringAfterStartup=true
   [junit4]   2> 3950083 INFO  
(recoveryExecutor-3483-thread-1-processing-n:127.0.0.1:64572_solr 
x:testCreateCollectionAddReplica_shard1_replica_n3 s:shard1 
c:testCreateCollectionAddReplica r:core_node4) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.RecoveryStrategy 
###### startupVersions=[[]]
   [junit4]   2> 3950083 INFO  
(recoveryExecutor-3483-thread-1-processing-n:127.0.0.1:64572_solr 
x:testCreateCollectionAddReplica_shard1_replica_n3 s:shard1 
c:testCreateCollectionAddReplica r:core_node4) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.RecoveryStrategy 
Begin buffering updates. core=[testCreateCollectionAddReplica_shard1_replica_n3]
   [junit4]   2> 3950083 INFO  
(recoveryExecutor-3483-thread-1-processing-n:127.0.0.1:64572_solr 
x:testCreateCollectionAddReplica_shard1_replica_n3 s:shard1 
c:testCreateCollectionAddReplica r:core_node4) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.u.UpdateLog Starting 
to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
   [junit4]   2> 3950083 INFO  
(recoveryExecutor-3483-thread-1-processing-n:127.0.0.1:64572_solr 
x:testCreateCollectionAddReplica_shard1_replica_n3 s:shard1 
c:testCreateCollectionAddReplica r:core_node4) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.RecoveryStrategy 
Publishing state of core [testCreateCollectionAddReplica_shard1_replica_n3] as 
recovering, leader is 
[http://127.0.0.1:64572/solr/testCreateCollectionAddReplica_shard1_replica_n1/] 
and I am 
[http://127.0.0.1:64572/solr/testCreateCollectionAddReplica_shard1_replica_n3/]
   [junit4]   2> 3950086 INFO  (qtp1400223612-23404) [n:127.0.0.1:64571_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={action=ADDREPLICA&collection=testCreateCollectionAddReplica&shard=shard1&type=NRT&wt=javabin&version=2}
 status=0 QTime=411
   [junit4]   2> 3950087 INFO  
(recoveryExecutor-3483-thread-1-processing-n:127.0.0.1:64572_solr 
x:testCreateCollectionAddReplica_shard1_replica_n3 s:shard1 
c:testCreateCollectionAddReplica r:core_node4) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.RecoveryStrategy 
Sending prep recovery command to [http://127.0.0.1:64572/solr]; [WaitForState: 
action=PREPRECOVERY&core=testCreateCollectionAddReplica_shard1_replica_n1&nodeName=127.0.0.1:64572_solr&coreNodeName=core_node4&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
   [junit4]   2> 3950089 INFO  (qtp117947705-23432) [n:127.0.0.1:64572_solr    
] o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node4, state: 
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true, 
maxTime: 183 s
   [junit4]   2> 3950089 INFO  (qtp117947705-23432) [n:127.0.0.1:64572_solr    
] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): 
collection=testCreateCollectionAddReplica, shard=shard1, 
thisCore=testCreateCollectionAddReplica_shard1_replica_n1, 
leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true, 
currentState=down, localState=active, nodeName=127.0.0.1:64572_solr, 
coreNodeName=core_node4, onlyIfActiveCheckResult=false, nodeProps: 
core_node4:{"core":"testCreateCollectionAddReplica_shard1_replica_n3","base_url":"http://127.0.0.1:64572/solr","node_name":"127.0.0.1:64572_solr","state":"down","type":"NRT"}
   [junit4]   2> 3950090 INFO  
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[A4833CD61F108AE6]) [ 
   ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
   [junit4]   2> 3950092 INFO  (qtp1400223612-23408) [n:127.0.0.1:64571_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params 
name=testCreateCollectionAddReplica&action=DELETE&wt=javabin&version=2 and 
sendToOCPQueue=true
   [junit4]   2> 3950095 INFO  
(OverseerCollectionConfigSetProcessor-98939574791569422-127.0.0.1:64574_solr-n_0000000000)
 [n:127.0.0.1:64574_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000002 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 3950096 INFO  
(OverseerThreadFactory-10185-thread-3-processing-n:127.0.0.1:64574_solr) 
[n:127.0.0.1:64574_solr    ] o.a.s.c.OverseerCollectionMessageHandler Executing 
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
   [junit4]   2> 3950097 INFO  (qtp117947705-23433) [n:127.0.0.1:64572_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.testCreateCollectionAddReplica.shard1.replica_n1, tag=null
   [junit4]   2> 3950097 INFO  (qtp117947705-23433) [n:127.0.0.1:64572_solr    
] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@2920407f: rootName = 
solr_64572, domain = 
solr.core.testCreateCollectionAddReplica.shard1.replica_n1, service url = null, 
agent id = null] for registry 
solr.core.testCreateCollectionAddReplica.shard1.replica_n1 / 
com.codahale.metrics.MetricRegistry@1b5a9032
   [junit4]   2> 3950105 INFO  (qtp117947705-23433) [n:127.0.0.1:64572_solr    
] o.a.s.c.SolrCore [testCreateCollectionAddReplica_shard1_replica_n1]  CLOSING 
SolrCore org.apache.solr.core.SolrCore@5416177c
   [junit4]   2> 3950105 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.testCreateCollectionAddReplica.shard1.replica_n3, tag=null
   [junit4]   2> 3950105 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr    
] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@ad1feae: rootName = 
solr_64572, domain = 
solr.core.testCreateCollectionAddReplica.shard1.replica_n3, service url = null, 
agent id = null] for registry 
solr.core.testCreateCollectionAddReplica.shard1.replica_n3 / 
com.codahale.metrics.MetricRegistry@635a6c0c
   [junit4]   2> 3950115 INFO  (qtp117947705-23433) [n:127.0.0.1:64572_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.testCreateCollectionAddReplica.shard1.replica_n1, 
tag=1410733948
   [junit4]   2> 3950117 INFO  (qtp117947705-23433) [n:127.0.0.1:64572_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.collection.testCreateCollectionAddReplica.shard1.leader, 
tag=1410733948
   [junit4]   2> 3950117 WARN  (qtp117947705-23435) [n:127.0.0.1:64572_solr    
] o.a.s.c.RecoveryStrategy Stopping recovery for 
core=[testCreateCollectionAddReplica_shard1_replica_n3] 
coreNodeName=[core_node4]
   [junit4]   2> 3950117 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr    
] o.a.s.c.SolrCore Core testCreateCollectionAddReplica_shard1_replica_n3 is not 
yet closed, waiting 100 ms before checking again.
   [junit4]   2> 3950122 ERROR 
(recoveryExecutor-3483-thread-1-processing-n:127.0.0.1:64572_solr 
x:testCreateCollectionAddReplica_shard1_replica_n3 s:shard1 
c:testCreateCollectionAddReplica r:core_node4) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.RecoveryStrategy 
Error while trying to recover. 
core=testCreateCollectionAddReplica_shard1_replica_n3:java.util.concurrent.ExecutionException:
 org.apache.solr.client.solrj.SolrServerException: IOException occured when 
talking to server at: http://127.0.0.1:64572/solr
   [junit4]   2>        at 
java.util.concurrent.FutureTask.report(FutureTask.java:122)
   [junit4]   2>        at 
java.util.concurrent.FutureTask.get(FutureTask.java:192)
   [junit4]   2>        at 
org.apache.solr.cloud.RecoveryStrategy.sendPrepRecoveryCmd(RecoveryStrategy.java:831)
   [junit4]   2>        at 
org.apache.solr.cloud.RecoveryStrategy.doSyncOrReplicateRecovery(RecoveryStrategy.java:568)
   [junit4]   2>        at 
org.apache.solr.cloud.RecoveryStrategy.doRecovery(RecoveryStrategy.java:308)
   [junit4]   2>        at 
org.apache.solr.cloud.RecoveryStrategy.run(RecoveryStrategy.java:292)
   [junit4]   2>        at 
com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176)
   [junit4]   2>        at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
   [junit4]   2>        at 
java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4]   2>        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> Caused by: org.apache.solr.client.solrj.SolrServerException: 
IOException occured when talking to server at: http://127.0.0.1:64572/solr
   [junit4]   2>        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:657)
   [junit4]   2>        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.lambda$httpUriRequest$0(HttpSolrClient.java:299)
   [junit4]   2>        ... 5 more
   [junit4]   2> Caused by: java.net.SocketException: Socket closed
   [junit4]   2>        at java.net.SocketInputStream.socketRead0(Native Method)
   [junit4]   2>        at 
java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
   [junit4]   2>        at 
java.net.SocketInputStream.read(SocketInputStream.java:171)
   [junit4]   2>        at 
java.net.SocketInputStream.read(SocketInputStream.java:141)
   [junit4]   2>        at 
org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
   [junit4]   2>        at 
org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
   [junit4]   2>        at 
org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:282)
   [junit4]   2>        at 
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
   [junit4]   2>        at 
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
   [junit4]   2>        at 
org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
   [junit4]   2>        at 
org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
   [junit4]   2>        at 
org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165)
   [junit4]   2>        at 
org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
   [junit4]   2>        at 
org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
   [junit4]   2>        at 
org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
   [junit4]   2>        at 
org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185)
   [junit4]   2>        at 
org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
   [junit4]   2>        at 
org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:111)
   [junit4]   2>        at 
org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
   [junit4]   2>        at 
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
   [junit4]   2>        at 
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
   [junit4]   2>        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:542)
   [junit4]   2>        ... 6 more
   [junit4]   2> 
   [junit4]   2> 3950122 INFO  
(recoveryExecutor-3483-thread-1-processing-n:127.0.0.1:64572_solr 
x:testCreateCollectionAddReplica_shard1_replica_n3 s:shard1 
c:testCreateCollectionAddReplica r:core_node4) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.RecoveryStrategy 
Replay not started, or was not successful... still buffering updates.
   [junit4]   2> 3950122 INFO  
(recoveryExecutor-3483-thread-1-processing-n:127.0.0.1:64572_solr 
x:testCreateCollectionAddReplica_shard1_replica_n3 s:shard1 
c:testCreateCollectionAddReplica r:core_node4) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.RecoveryStrategy 
RecoveryStrategy has been closed
   [junit4]   2> 3950122 INFO  
(recoveryExecutor-3483-thread-1-processing-n:127.0.0.1:64572_solr 
x:testCreateCollectionAddReplica_shard1_replica_n3 s:shard1 
c:testCreateCollectionAddReplica r:core_node4) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.RecoveryStrategy 
Finished recovery process, successful=[false]
   [junit4]   2> 3950122 INFO  
(recoveryExecutor-3483-thread-1-processing-n:127.0.0.1:64572_solr 
x:testCreateCollectionAddReplica_shard1_replica_n3 s:shard1 
c:testCreateCollectionAddReplica r:core_node4) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.SolrCore 
[testCreateCollectionAddReplica_shard1_replica_n3]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@387fc439
   [junit4]   2> 3950122 INFO  
(recoveryExecutor-3483-thread-1-processing-n:127.0.0.1:64572_solr 
x:testCreateCollectionAddReplica_shard1_replica_n3 s:shard1 
c:testCreateCollectionAddReplica r:core_node4) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.m.SolrMetricManager 
Closing metric reporters for 
registry=solr.core.testCreateCollectionAddReplica.shard1.replica_n3, 
tag=947897401
   [junit4]   2> 3950122 INFO  
(recoveryExecutor-3483-thread-1-processing-n:127.0.0.1:64572_solr 
x:testCreateCollectionAddReplica_shard1_replica_n3 s:shard1 
c:testCreateCollectionAddReplica r:core_node4) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.m.SolrMetricManager 
Closing metric reporters for 
registry=solr.collection.testCreateCollectionAddReplica.shard1.leader, 
tag=947897401
   [junit4]   2> 3950123 WARN  
(recoveryExecutor-3483-thread-1-processing-n:127.0.0.1:64572_solr 
x:testCreateCollectionAddReplica_shard1_replica_n3 s:shard1 
c:testCreateCollectionAddReplica r:core_node4) [n:127.0.0.1:64572_solr 
c:testCreateCollectionAddReplica s:shard1 r:core_node4 
x:testCreateCollectionAddReplica_shard1_replica_n3] o.a.s.c.RecoveryStrategy 
Stopping recovery for core=[testCreateCollectionAddReplica_shard1_replica_n3] 
coreNodeName=[core_node4]
   [junit4]   2> 3950125 WARN  
(zkCallback-3496-thread-1-processing-n:127.0.0.1:64572_solr) 
[n:127.0.0.1:64572_solr    ] o.a.s.c.LeaderElector 
   [junit4]   2> org.apache.solr.common.SolrException: SolrCore not 
found:testCreateCollectionAddReplica_shard1_replica_n3 in []
   [junit4]   2>        at 
org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:310)
   [junit4]   2>        at 
org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:170)
   [junit4]   2>        at 
org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:135)
   [junit4]   2>        at 
org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:56)
   [junit4]   2>        at 
org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:348)
   [junit4]   2>        at 
org.apache.solr.common.cloud.SolrZkClient$1.lambda$process$0(SolrZkClient.java:262)
   [junit4]   2>        at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
   [junit4]   2>        at 
java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4]   2>        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 3950125 INFO  (qtp117947705-23433) [n:127.0.0.1:64572_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=testCreateCollectionAddReplica_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=29
   [junit4]   2> 3950226 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr    
] o.a.s.c.ShardLeaderElectionContextBase No version found for ephemeral leader 
parent node, won't remove previous leader registration.
   [junit4]   2> 3950227 INFO  (qtp117947705-23435) [n:127.0.0.1:64572_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=testCreateCollectionAddReplica_shard1_replica_n3&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=129
   [junit4]   2> 3950962 INFO  (qtp1400223612-23408) [n:127.0.0.1:64571_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={name=testCreateCollectionAddReplica&action=DELETE&wt=javabin&version=2} 
status=0 QTime=870
   [junit4]   2> 3950964 INFO  
(TEST-TestPolicyCloud.testCreateCollectionAddReplica-seed#[A4833CD61F108AE6]) [ 
   ] o.a.s.SolrTestCaseJ4 ###Ending testCreateCollectionAddReplica
   [junit4]   2> 3951230 INFO  
(TEST-TestPolicyCloud.testMetricsTag-seed#[A4833CD61F108AE6]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testMetricsTag
   [junit4]   2> 3951233 ERROR (qtp1400223612-23406) [n:127.0.0.1:64571_solr    
] o.a.s.h.RequestHandlerBase org.apache.solr.api.ApiBag$ExceptionWithErrObject: 
Error in command payload, errors: [{set-cluster-policy=[{cores=<10, node=#ANY}, 
{replica=<2, shard=#EACH, node=#ANY}, {metrics:abc=overseer, replica=0}], 
errorMessages=[Invalid metrics: param in {
   [junit4]   2>   "metrics:abc":"overseer",
   [junit4]   2>   "replica":0} must have at 2 or 3 segments after 'metrics:' 
separated by ':']}], 
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.AutoScalingHandler.processOps(AutoScalingHandler.java:211)
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:145)
   [junit4]   2>        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:735)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:716)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:497)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:382)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
   [junit4]   2>        at 
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:426)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
   [junit4]   2>        at 
org.eclipse.jetty.server.Server.handle(Server.java:534)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
   [junit4]   2>        at 
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
   [junit4]   2>        at 
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
   [junit4]   2>        at 
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 
   [junit4]   2> 3951233 INFO  (qtp1400223612-23406) [n:127.0.0.1:64571_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=400 QTime=2
   [junit4]   2> 3951239 INFO  (qtp1400223612-23411) [n:127.0.0.1:64571_solr    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
   [junit4]   2> 3951241 INFO  (qtp1400223612-23411) [n:127.0.0.1:64571_solr    
] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64568/solr ready
   [junit4]   2> 3951248 INFO  (qtp1400223612-23410) [n:127.0.0.1:64571_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.node:ADMIN./admin/authorization.clientErrors:count}
 status=0 QTime=0
   [junit4]   2> 3951248 INFO  (qtp1400223612-23409) [n:127.0.0.1:64571_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 3951251 INFO  (qtp580572421-23417) [n:127.0.0.1:64573_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.node:ADMIN./admin/authorization.clientErrors:count}
 status=0 QTime=0
   [junit4]   2> 3951252 INFO  (qtp580572421-23418) [n:127.0.0.1:64573_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 3951257 INFO  (qtp313356237-23399) [n:127.0.0.1:64570_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.node:ADMIN./admin/authorization.clientErrors:count}
 status=0 QTime=0
   [junit4]   2> 3951258 INFO  (qtp313356237-23400) [n:127.0.0.1:64570_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 3951261 INFO  (qtp117947705-23436) [n:127.0.0.1:64572_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.node:ADMIN./admin/authorization.clientErrors:count}
 status=0 QTime=0
   [junit4]   2> 3951262 INFO  (qtp117947705-23429) [n:127.0.0.1:64572_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 3951267 INFO  (qtp1916437788-23427) [n:127.0.0.1:64574_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.node:ADMIN./admin/authorization.clientErrors:count}
 status=0 QTime=0
   [junit4]   2> 3951267 INFO  (qtp1916437788-23428) [n:127.0.0.1:64574_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 3951270 INFO  (qtp1400223612-23411) [n:127.0.0.1:64571_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=35
   [junit4]   2> 3951270 INFO  (qtp1400223612-23407) [n:127.0.0.1:64571_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=1&collection.configName=conf&name=metricsTest&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 3951274 INFO  
(OverseerThreadFactory-10185-thread-4-processing-n:127.0.0.1:64574_solr) 
[n:127.0.0.1:64574_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
metricsTest
   [junit4]   2> 3951275 INFO  
(OverseerCollectionConfigSetProcessor-98939574791569422-127.0.0.1:64574_solr-n_0000000000)
 [n:127.0.0.1:64574_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000004 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 3951277 INFO  (qtp1400223612-23404) [n:127.0.0.1:64571_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.node:ADMIN./admin/authorization.clientErrors:count}
 status=0 QTime=0
   [junit4]   2> 3951278 INFO  (qtp1400223612-23408) [n:127.0.0.1:64571_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 3951279 INFO  (qtp580572421-23419) [n:127.0.0.1:64573_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.node:ADMIN./admin/authorization.clientErrors:count}
 status=0 QTime=0
   [junit4]   2> 3951280 INFO  (qtp580572421-23401) [n:127.0.0.1:64573_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 3951281 INFO  (qtp313356237-23393) [n:127.0.0.1:64570_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.node:ADMIN./admin/authorization.clientErrors:count}
 status=0 QTime=0
   [junit4]   2> 3951281 INFO  (qtp313356237-23395) [n:127.0.0.1:64570_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 3951282 INFO  (qtp117947705-23431) [n:127.0.0.1:64572_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.node:ADMIN./admin/authorization.clientErrors:count}
 status=0 QTime=0
   [junit4]   2> 3951283 INFO  (qtp117947705-23434) [n:127.0.0.1:64572_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 3951284 INFO  (qtp1916437788-23421) [n:127.0.0.1:64574_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.node:ADMIN./admin/authorization.clientErrors:count}
 status=0 QTime=0
   [junit4]   2> 3951284 INFO  (qtp1916437788-23421) [n:127.0.0.1:64574_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 3951512 INFO  
(OverseerStateUpdate-98939574791569422-127.0.0.1:64574_solr-n_0000000000) 
[n:127.0.0.1:64574_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"metricsTest",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"metricsTest_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:64574/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 3951737 INFO  (qtp1916437788-23425) [n:127.0.0.1:64574_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=metricsTest_shard1_replica_n1&action=CREATE&numShards=1&collection=metricsTest&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 3951738 INFO  (qtp1916437788-23425) [n:127.0.0.1:64574_solr    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 3951857 INFO  
(zkCallback-3498-thread-1-processing-n:127.0.0.1:64574_solr) 
[n:127.0.0.1:64574_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/metricsTest/state.json] for collection [metricsTest] has 
occurred - updating... (live nodes size: [5])
   [junit4]   2> 3951857 INFO  
(zkCallback-3498-thread-2-processing-n:127.0.0.1:64574_solr) 
[n:127.0.0.1:64574_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/metricsTest/state.json] for collection [metricsTest] has 
occurred - updating... (live nodes size: [5])
   [junit4]   2> 3952760 INFO  (qtp1916437788-23425) [n:127.0.0.1:64574_solr 
c:metricsTest s:shard1 r:core_node2 x:metricsTest_shard1_replica_n1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 3952766 INFO  (qtp1916437788-23425) [n:127.0.0.1:64574_solr 
c:metricsTest s:shard1 r:core_node2 x:metricsTest_shard1_replica_n1] 
o.a.s.s.IndexSchema [metricsTest_shard1_replica_n1] Schema name=minimal
   [junit4]   2> 3952784 INFO  (qtp1916437788-23425) [n:127.0.0.1:64574_solr 
c:metricsTest s:shard1 r:core_node2 x:metricsTest_shard1_replica_n1] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 3952784 INFO  (qtp1916437788-23425) [n:127.0.0.1:64574_solr 
c:metricsTest s:shard1 r:core_node2 x:metricsTest_shard1_replica_n1] 
o.a.s.c.CoreContainer Creating SolrCore 'metricsTest_shard1_replica_n1' using 
configuration from collection metricsTest, trusted=true
   [junit4]   2> 3952785 INFO  (qtp1916437788-23425) [n:127.0.0.1:64574_solr 
c:metricsTest s:shard1 r:core_node2 x:metricsTest_shard1_replica_n1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_64574.solr.core.metricsTest.shard1.replica_n1' (registry 
'solr.core.metricsTest.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2d197217
   [junit4]   2> 3952785 INFO  (qtp1916437788-23425) [n:127.0.0.1:64574_solr 
c:metricsTest s:shard1 r:core_node2 x:metricsTest_shard1_replica_n1] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 3952785 INFO  (qtp1916437788-23425) [n:127.0.0.1:64574_solr 
c:metricsTest s:shard1 r:core_node2 x:metricsTest_shard1_replica_n1] 
o.a.s.c.SolrCore [[metricsTest_shard1_replica_n1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TestPolicyCloud_A4833CD61F108AE6-001/tempDir-001/node5/metricsTest_shard1_replica_n1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TestPolicyCloud_A4833CD61F108AE6-001/tempDir-001/node5/./metricsTest_shard1_replica_n1/data/]
   [junit4]   2> 3952841 INFO  (qtp1916437788-23425) [n:127.0.0.1:64574_solr 
c:metricsTest s:shard1 r:core_node2 x:metricsTest_shard1_replica_n1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 3952841 INFO  (qtp1916437788-23425) [n:127.0.0.1:64574_solr 
c:metricsTest s:shard1 r:core_node2 x:metricsTest_shard1_replica_n1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 3952843 INFO  (qtp1916437788-23425) [n:127.0.0.1:64574_solr 
c:metricsTest s:shard1 r:core_node2 x:metricsTest_shard1_replica_n1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 3952843 INFO  (qtp1916437788-23425) [n:127.0.0.1:64574_solr 
c:metricsTest s:shard1 r:core_node2 x:metricsTest_shard1_replica_n1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 3952844 INFO  (qtp1916437788-23425) [n:127.0.0.1:64574_solr 
c:metricsTest s:shard1 r:core_node2 x:metricsTest_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@57a714f1[metricsTest_shard1_replica_n1] main]
   [junit4]   2> 3952845 INFO  (qtp1916437788-23425) [n:127.0.0.1:64574_solr 
c:metricsTest s:shard1 r:core_node2 x:metricsTest_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 3952845 INFO  (qtp1916437788-23425) [n:127.0.0.1:64574_solr 
c:metricsTest s:shard1 r:core_node2 x:metricsTest_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 3952846 INFO  (qtp1916437788-23425) [n:127.0.0.1:64574_solr 
c:metricsTest s:shard1 r:core_node2 x:metricsTest_shard1_replica_n1] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 3952846 INFO  (qtp1916437788-23425) [n:127.0.0.1:64574_solr 
c:metricsTest s:shard1 r:core_node2 x:metricsTest_shard1_replica_n1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1583033203897139200
   [junit4]   2> 3952846 INFO  
(searcherExecutor-10203-thread-1-processing-n:127.0.0.1:64574_solr 
x:metricsTest_shard1_replica_n1 s:shard1 c:metricsTest r:core_node2) 
[n:127.0.0.1:64574_solr c:metricsTest s:shard1 r:core_node2 
x:metricsTest_shard1_replica_n1] o.a.s.c.SolrCore 
[metricsTest_shard1_replica_n1] Registered new searcher 
Searcher@57a714f1[metricsTest_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 3952851 INFO  (qtp1916437788-23425) [n:127.0.0.1:64574_solr 
c:metricsTest s:shard1 r:core_node2 x:metricsTest_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 3952851 INFO  (qtp1916437788-23425) [n:127.0.0.1:64574_solr 
c:metricsTest s:shard1 r:core_node2 x:metricsTest_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 3952851 INFO  (qtp1916437788-23425) [n:127.0.0.1:64574_solr 
c:metricsTest s:shard1 r:core_node2 x:metricsTest_shard1_replica_n1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:64574/solr/metricsTest_shard1_replica_n1/
   [junit4]   2> 3952851 INFO  (qtp1916437788-23425) [n:127.0.0.1:64574_solr 
c:metricsTest s:shard1 r:core_node2 x:metricsTest_shard1_replica_n1] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 3952851 INFO  (qtp1916437788-23425) [n:127.0.0.1:64574_solr 
c:metricsTest s:shard1 r:core_node2 x:metricsTest_shard1_replica_n1] 
o.a.s.c.SyncStrategy http://127.0.0.1:64574/solr/metricsTest_shard1_replica_n1/ 
has no replicas
   [junit4]   2> 3952851 INFO  (qtp1916437788-23425) [n:127.0.0.1:64574_solr 
c:metricsTest s:shard1 r:core_node2 x:metricsTest_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 3952860 INFO  (qtp1916437788-23425) [n:127.0.0.1:64574_solr 
c:metricsTest s:shard1 r:core_node2 x:metricsTest_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:64574/solr/metricsTest_shard1_replica_n1/ shard1
   [junit4]   2> 3952973 INFO  
(zkCallback-3498-thread-2-processing-n:127.0.0.1:64574_solr) 
[n:127.0.0.1:64574_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/metricsTest/state.json] for collection [metricsTest] has 
occurred - updating... (live nodes size: [5])
   [junit4]   2> 3952973 INFO  
(zkCallback-3498-thread-1-processing-n:127.0.0.1:64574_solr) 
[n:127.0.0.1:64574_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/metricsTest/state.json] for collection [metricsTest] has 
occurred - updating... (live nodes size: [5])
   [junit4]   2> 3953028 INFO  (qtp1916437788-23425) [n:127.0.0.1:64574_solr 
c:metricsTest s:shard1 r:core_node2 x:metricsTest_shard1_replica_n1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 3953032 INFO  (qtp1916437788-23425) [n:127.0.0.1:64574_solr 
c:metricsTest s:shard1 r:core_node2 x:metricsTest_shard1_replica_n1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=metricsTest_shard1_replica_n1&action=CREATE&numShards=1&collection=metricsTest&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1295
   [junit4]   2> 3953037 INFO  (qtp1400223612-23407) [n:127.0.0.1:64571_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> 3953145 INF

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

]   2> 4014100 WARN  
(OverseerAutoScalingTriggerThread-98939574791569422-127.0.0.1:64574_solr-n_0000000000)
 [n:127.0.0.1:64574_solr    ] o.a.s.c.a.OverseerTriggerThread 
OverseerTriggerThread woken up but we are closed, exiting.
   [junit4]   2> 4014102 INFO  (jetty-closer-3473-thread-5) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@2968c036{/solr,null,UNAVAILABLE}
   [junit4]   2> 4014103 ERROR 
(SUITE-TestPolicyCloud-seed#[A4833CD61F108AE6]-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> 4014104 INFO  
(SUITE-TestPolicyCloud-seed#[A4833CD61F108AE6]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:64568 64568
   [junit4]   2> 4014153 INFO  (Thread-4537) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:64568 64568
   [junit4]   2> 4014319 WARN  (Thread-4537) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        18      /solr/clusterprops.json
   [junit4]   2>        18      /solr/aliases.json
   [junit4]   2>        12      /solr/configs/conf
   [junit4]   2>        5       /solr/security.json
   [junit4]   2>        2       /solr/collections/policiesTest/state.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        37      
/solr/collections/testCreateCollectionSplitShard/state.json
   [junit4]   2>        30      /solr/collections/policiesTest/state.json
   [junit4]   2>        30      /solr/collections/perReplicaDataColl/state.json
   [junit4]   2>        18      /solr/clusterstate.json
   [junit4]   2>        8       
/solr/collections/testCreateCollectionAddReplica/state.json
   [junit4]   2>        5       /solr/collections/metricsTest/state.json
   [junit4]   2>        3       
/solr/overseer_elect/election/98939574791569422-127.0.0.1:64574_solr-n_0000000000
   [junit4]   2>        2       
/solr/collections/perReplicaDataColl/leader_elect/shard1/election/98939574791569421-core_node3-n_0000000001
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        64      /solr/collections
   [junit4]   2>        18      /solr/live_nodes
   [junit4]   2> 
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TestPolicyCloud_A4833CD61F108AE6-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {}, 
docValues:{}, maxPointsInLeafNode=339, maxMBSortInHeap=7.359884887562611, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@2c06800),
 locale=hi-IN, timezone=IET
   [junit4]   2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_144 
(64-bit)/cpus=3,threads=1,free=148339816,total=420610048
   [junit4]   2> NOTE: All tests run in this JVM: [TestMacros, 
DeleteInactiveReplicaTest, IndexSchemaTest, SuggesterWFSTTest, 
TestSweetSpotSimilarityFactory, BasicAuthStandaloneTest, TestPrepRecovery, 
TestCopyFieldCollectionResource, HdfsUnloadDistributedZkTest, TestInitQParser, 
ReplicationFactorTest, CreateCollectionCleanupTest, 
TestSlowCompositeReaderWrapper, TestFieldTypeResource, TestSimpleQParserPlugin, 
OverriddenZkACLAndCredentialsProvidersTest, HdfsDirectoryFactoryTest, 
TestReplicationHandlerBackup, StressHdfsTest, ShowFileRequestHandlerTest, 
SuggesterFSTTest, TestQuerySenderNoQuery, WordBreakSolrSpellCheckerTest, 
TestGraphTermsQParserPlugin, TestQuerySenderListener, 
TestSubQueryTransformerCrossCore, TestDistributedStatsComponentCardinality, 
PreAnalyzedUpdateProcessorTest, SpellCheckCollatorTest, QueryParsingTest, 
TestLegacyField, TestConfigSetsAPI, TestWriterPerf, TestDefaultStatsCache, 
TestSimpleTextCodec, IgnoreCommitOptimizeUpdateProcessorFactoryTest, 
OutputWriterTest, TestConfigSetImmutable, CoreAdminRequestStatusTest, 
SuggesterTest, TestSolrXml, DistributedFacetExistsSmallTest, 
DistributedQueryElevationComponentTest, EnumFieldTest, TestCloudDeleteByQuery, 
DistanceFunctionTest, HdfsBasicDistributedZk2Test, SolrMetricManagerTest, 
ZkCLITest, TestCloudJSONFacetJoinDomain, RegexBoostProcessorTest, 
RemoteQueryErrorTest, MoveReplicaTest, LeaderFailoverAfterPartitionTest, 
TestRandomRequestDistribution, RequiredFieldsTest, 
TestSolrCloudWithSecureImpersonation, TestAnalyzeInfixSuggestions, 
NumericFieldsTest, TestSolr4Spatial, DistribJoinFromCollectionTest, 
DistributedFacetPivotSmallTest, TestNumericTerms32, CSVRequestHandlerTest, 
DistributedQueryComponentCustomSortTest, TestHighFrequencyDictionaryFactory, 
RequestHandlersTest, StatsComponentTest, TestRestoreCore, TestSchemaResource, 
TestSolrCloudWithHadoopAuthPlugin, OverseerTest, 
TriLevelCompositeIdRoutingTest, ChaosMonkeyNothingIsSafeTest, 
TestExceedMaxTermLength, TestSurroundQueryParser, TestConfigSets, 
ChangedSchemaMergeTest, LeaderInitiatedRecoveryOnCommitTest, 
DirectoryFactoryTest, NoCacheHeaderTest, TestApiFramework, CleanupOldIndexTest, 
DistributedFacetPivotLongTailTest, ChaosMonkeySafeLeaderWithPullReplicasTest, 
BlockJoinFacetDistribTest, BlockDirectoryTest, TestRangeQuery, 
MergeStrategyTest, TestLazyCores, SolrCloudReportersTest, TestManagedResource, 
AddSchemaFieldsUpdateProcessorFactoryTest, V2ApiIntegrationTest, 
TestRequestStatusCollectionAPI, BadCopyFieldTest, 
FieldAnalysisRequestHandlerTest, TestReloadDeadlock, 
ShufflingReplicaListTransformerTest, CircularListTest, 
TestUniqueKeyFieldResource, DistributedTermsComponentTest, TestJmxIntegration, 
TestOverriddenPrefixQueryForCustomFieldType, TestChildDocTransformer, 
BinaryUpdateRequestHandlerTest, TestMaxTokenLenTokenizer, TestLRUStatsCache, 
HttpPartitionTest, ConfigureRecoveryStrategyTest, TestDownShardTolerantSearch, 
SparseHLLTest, TestFileDictionaryLookup, HdfsRecoverLeaseTest, 
TestManagedSchemaThreadSafety, BadIndexSchemaTest, PrimUtilsTest, 
DisMaxRequestHandlerTest, TestUtils, ShardRoutingTest, CursorMarkTest, 
TestSolrCoreProperties, TestCSVResponseWriter, ImplicitSnitchTest, 
PeerSyncReplicationTest, SpatialFilterTest, TestRestManager, 
TestInPlaceUpdatesDistrib, RecoveryZkTest, TestDocTermOrds, 
TestMinMaxOnMultiValuedField, SuggestComponentContextFilterQueryTest, 
TestXIncludeConfig, CdcrReplicationHandlerTest, 
TestTrackingShardHandlerFactory, RulesTest, DistributedSuggestComponentTest, 
TestComplexPhraseQParserPlugin, TestSizeLimitedDistributedMap, 
SegmentsInfoRequestHandlerTest, DistributedVersionInfoTest, 
SolrRequestParserTest, TestCoreDiscovery, TestPullReplicaErrorHandling, 
SortSpecParsingTest, TestPerFieldSimilarityWithDefaultOverride, 
SuggesterTSTTest, TestPseudoReturnFields, TestMultiWordSynonyms, 
HLLSerializationTest, PrimitiveFieldTypeTest, SpellCheckComponentTest, 
CollectionReloadTest, TestSolrConfigHandlerConcurrent, NodeAddedTriggerTest, 
AutoAddReplicasPlanActionTest, TestLegacyNumericUtils, 
IndexBasedSpellCheckerTest, DistanceUnitsTest, TestRTimerTree, 
DirectUpdateHandlerOptimizeTest, AddReplicaTest, MinimalSchemaTest, 
TestCustomDocTransformer, ZkFailoverTest, TestXmlQParser, 
TestRandomCollapseQParserPlugin, SolrGraphiteReporterTest, 
ConjunctionSolrSpellCheckerTest, TestPHPSerializedResponseWriter, 
TestMaxScoreQueryParser, TestTolerantUpdateProcessorRandomCloud, 
PropertiesRequestHandlerTest, SolrXmlInZkTest, TestCollapseQParserPlugin, 
DefaultValueUpdateProcessorTest, AlternateDirectoryTest, AtomicUpdatesTest, 
TestAtomicUpdateErrorCases, HdfsRestartWhileUpdatingTest, 
TestReplicationHandler, UnloadDistributedZkTest, OpenCloseCoreStressTest, 
LeaderElectionIntegrationTest, ClusterStateUpdateTest, TestRandomFaceting, 
ZkSolrClientTest, ShardRoutingCustomTest, TestDistributedGrouping, 
TestFaceting, TestRecovery, DistributedSpellCheckComponentTest, 
TermVectorComponentDistributedTest, TestReload, SolrCoreTest, 
QueryElevationComponentTest, TestFiltering, HighlighterTest, 
SolrIndexSplitterTest, SignatureUpdateProcessorFactoryTest, 
TestExtendedDismaxParser, CoreAdminHandlerTest, TestFoldingMultitermQuery, 
TestStressLucene, TestTrie, TestCSVLoader, TestUpdate, 
StatelessScriptUpdateProcessorFactoryTest, SortByFunctionTest, 
DocValuesMultiTest, TestSolrDeletionPolicy1, SolrInfoBeanTest, 
XsltUpdateRequestHandlerTest, DebugComponentTest, CacheHeaderTest, 
TestReversedWildcardFilterFactory, TestOmitPositions, 
FileBasedSpellCheckerTest, XmlUpdateRequestHandlerTest, DocumentBuilderTest, 
PathHierarchyTokenizerFactoryTest, TestIndexingPerformance, 
TestSolrQueryParser, FastVectorHighlighterTest, IndexSchemaRuntimeFieldTest, 
TestCollationField, ReturnFieldsTest, MBeansHandlerTest, 
TestAnalyzedSuggestions, UniqFieldsUpdateProcessorFactoryTest, JsonLoaderTest, 
TestPartialUpdateDeduplication, TestLFUCache, HighlighterConfigTest, 
UpdateParamsTest, CopyFieldTest, ResponseLogComponentTest, SolrIndexConfigTest, 
BadComponentTest, TestStressRecovery, TestFuzzyAnalyzedSuggestions, 
TestPhraseSuggestions, SynonymTokenizerTest, TestDFRSimilarityFactory, 
TestLMDirichletSimilarityFactory, TestLMJelinekMercerSimilarityFactory, 
TestBM25SimilarityFactory, TestFastWriter, ResourceLoaderTest, 
OpenExchangeRatesOrgProviderTest, TestFastLRUCache, DateMathParserTest, 
TestSystemIdResolver, TestSuggestSpellingConverter, SpellingQueryConverterTest, 
ClusterStateTest, SliceStateTest, UUIDFieldTest, DistributedMLTComponentTest, 
DistributedIntervalFacetingTest, TestTolerantSearch, TestLuceneIndexBackCompat, 
TestEmbeddedSolrServerAdminHandler, TestEmbeddedSolrServerConstructors, 
TestEmbeddedSolrServerSchemaAPI, ConnectionReuseTest, AssignTest, 
CdcrVersionReplicationTest, CollectionsAPIAsyncDistributedZkTest, 
ConfigSetsAPITest, CustomCollectionTest, DeleteReplicaTest, 
DistribDocExpirationUpdateProcessorTest, LeaderElectionContextKeyTest, 
LeaderFailureAfterFreshStartTest, MigrateRouteKeyTest, 
MissingSegmentRecoveryTest, MoveReplicaHDFSFailoverTest, MoveReplicaHDFSTest, 
MultiThreadedOCPTest, HdfsAutoAddReplicasIntegrationTest, 
SearchRateTriggerTest, TestPolicyCloud]
   [junit4] Completed [590/747 (1!)] on J0 in 68.40s, 7 tests, 1 error <<< 
FAILURES!

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

Reply via email to