lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-7.x-MacOSX (64bit/jdk1.8.0) - Build # 273 - Failure!
Date Tue, 31 Oct 2017 10:54:06 GMT
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-MacOSX/273/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseSerialGC

1 tests failed.
FAILED:  org.apache.solr.cloud.CustomCollectionTest.testCustomCollectionsAPI

Error Message:
Could not find collection : implicitcoll

Stack Trace:
org.apache.solr.common.SolrException: Could not find collection : implicitcoll
	at __randomizedtesting.SeedInfo.seed([24D85E0053680E6F:4E39D06B6EF2B817]: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.CustomCollectionTest.testCustomCollectionsAPI(CustomCollectionTest.java:68)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
	at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 11561 lines...]
   [junit4] JVM J0: stdout was not empty, see: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/temp/junit4-J0-20171031_092905_1572242955450279347941.sysout
   [junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
   [junit4] #
   [junit4] # A fatal error has been detected by the Java Runtime Environment:
   [junit4] #
   [junit4] #  Internal Error (sharedRuntime.cpp:873), pid=14660, tid=0x0000000000009527
   [junit4] #  guarantee(nm != NULL) failed: must have containing nmethod for implicit division-by-zero exceptions
   [junit4] #
   [junit4] # JRE version: Java(TM) SE Runtime Environment (8.0_144-b01) (build 1.8.0_144-b01)
   [junit4] # Java VM: Java HotSpot(TM) 64-Bit Server VM (25.144-b01 mixed mode bsd-amd64 compressed oops)
   [junit4] # Failed to write core dump. Core dumps have been disabled. To enable core dumping, try "ulimit -c unlimited" before starting Java again
   [junit4] #
   [junit4] # An error report file with more information is saved as:
   [junit4] # /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/hs_err_pid14660.log
   [junit4] #
   [junit4] # If you would like to submit a bug report, please visit:
   [junit4] #   http://bugreport.java.com/bugreport/crash.jsp
   [junit4] #
   [junit4] <<< JVM J0: EOF ----

[...truncated 1018 lines...]
   [junit4] Suite: org.apache.solr.cloud.CustomCollectionTest
   [junit4]   2> 2721895 INFO  (SUITE-CustomCollectionTest-seed#[24D85E0053680E6F]-worker) [    ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_24D85E0053680E6F-001/init-core-data-001
   [junit4]   2> 2721896 WARN  (SUITE-CustomCollectionTest-seed#[24D85E0053680E6F]-worker) [    ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
   [junit4]   2> 2721897 INFO  (SUITE-CustomCollectionTest-seed#[24D85E0053680E6F]-worker) [    ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 2721902 INFO  (SUITE-CustomCollectionTest-seed#[24D85E0053680E6F]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) w/ MAC_OS_X supressed clientAuth
   [junit4]   2> 2721907 INFO  (SUITE-CustomCollectionTest-seed#[24D85E0053680E6F]-worker) [    ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_24D85E0053680E6F-001/tempDir-001
   [junit4]   2> 2721907 INFO  (SUITE-CustomCollectionTest-seed#[24D85E0053680E6F]-worker) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2721908 INFO  (Thread-4001) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2721908 INFO  (Thread-4001) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 2721917 ERROR (Thread-4001) [    ] 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> 2722018 INFO  (SUITE-CustomCollectionTest-seed#[24D85E0053680E6F]-worker) [    ] o.a.s.c.ZkTestServer start zk server on port:56552
   [junit4]   2> 2722056 INFO  (jetty-launcher-3399-thread-2) [    ] o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 2722057 INFO  (jetty-launcher-3399-thread-4) [    ] o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 2722057 INFO  (jetty-launcher-3399-thread-1) [    ] o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 2722056 INFO  (jetty-launcher-3399-thread-3) [    ] o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 2722069 INFO  (jetty-launcher-3399-thread-3) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@731ea186{/solr,null,AVAILABLE}
   [junit4]   2> 2722070 INFO  (jetty-launcher-3399-thread-3) [    ] o.e.j.s.AbstractConnector Started ServerConnector@112c5162{SSL,[ssl, http/1.1]}{127.0.0.1:56554}
   [junit4]   2> 2722070 INFO  (jetty-launcher-3399-thread-3) [    ] o.e.j.s.Server Started @2725287ms
   [junit4]   2> 2722070 INFO  (jetty-launcher-3399-thread-3) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=56554}
   [junit4]   2> 2722071 ERROR (jetty-launcher-3399-thread-3) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2722071 INFO  (jetty-launcher-3399-thread-3) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 7.2.0
   [junit4]   2> 2722071 INFO  (jetty-launcher-3399-thread-3) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2722071 INFO  (jetty-launcher-3399-thread-3) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2722071 INFO  (jetty-launcher-3399-thread-3) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-10-31T10:14:30.495Z
   [junit4]   2> 2722074 INFO  (jetty-launcher-3399-thread-2) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@638c44a2{/solr,null,AVAILABLE}
   [junit4]   2> 2722075 INFO  (jetty-launcher-3399-thread-2) [    ] o.e.j.s.AbstractConnector Started ServerConnector@2a1f82e{SSL,[ssl, http/1.1]}{127.0.0.1:56555}
   [junit4]   2> 2722075 INFO  (jetty-launcher-3399-thread-2) [    ] o.e.j.s.Server Started @2725291ms
   [junit4]   2> 2722075 INFO  (jetty-launcher-3399-thread-2) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=56555}
   [junit4]   2> 2722075 ERROR (jetty-launcher-3399-thread-2) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2722076 INFO  (jetty-launcher-3399-thread-2) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 7.2.0
   [junit4]   2> 2722076 INFO  (jetty-launcher-3399-thread-2) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2722076 INFO  (jetty-launcher-3399-thread-2) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2722076 INFO  (jetty-launcher-3399-thread-2) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-10-31T10:14:30.500Z
   [junit4]   2> 2722079 INFO  (jetty-launcher-3399-thread-1) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@484997d1{/solr,null,AVAILABLE}
   [junit4]   2> 2722080 INFO  (jetty-launcher-3399-thread-1) [    ] o.e.j.s.AbstractConnector Started ServerConnector@786a5113{SSL,[ssl, http/1.1]}{127.0.0.1:56558}
   [junit4]   2> 2722080 INFO  (jetty-launcher-3399-thread-1) [    ] o.e.j.s.Server Started @2725296ms
   [junit4]   2> 2722080 INFO  (jetty-launcher-3399-thread-1) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=56558}
   [junit4]   2> 2722080 ERROR (jetty-launcher-3399-thread-1) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2722080 INFO  (jetty-launcher-3399-thread-1) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 7.2.0
   [junit4]   2> 2722080 INFO  (jetty-launcher-3399-thread-1) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2722080 INFO  (jetty-launcher-3399-thread-1) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2722080 INFO  (jetty-launcher-3399-thread-1) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-10-31T10:14:30.504Z
   [junit4]   2> 2722082 INFO  (jetty-launcher-3399-thread-3) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2722083 INFO  (jetty-launcher-3399-thread-2) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2722104 INFO  (jetty-launcher-3399-thread-1) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2722122 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 0x15f71ece0c00001, 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> 2722130 INFO  (jetty-launcher-3399-thread-3) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56552/solr
   [junit4]   2> 2722131 INFO  (jetty-launcher-3399-thread-4) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@69ea1942{/solr,null,AVAILABLE}
   [junit4]   2> 2722134 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 0x15f71ece0c00004, 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> 2722144 INFO  (jetty-launcher-3399-thread-2) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56552/solr
   [junit4]   2> 2722168 INFO  (jetty-launcher-3399-thread-4) [    ] o.e.j.s.AbstractConnector Started ServerConnector@63c7bd8b{SSL,[ssl, http/1.1]}{127.0.0.1:56561}
   [junit4]   2> 2722168 INFO  (jetty-launcher-3399-thread-4) [    ] o.e.j.s.Server Started @2725385ms
   [junit4]   2> 2722169 INFO  (jetty-launcher-3399-thread-4) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=56561}
   [junit4]   2> 2722169 INFO  (jetty-launcher-3399-thread-1) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56552/solr
   [junit4]   2> 2722169 ERROR (jetty-launcher-3399-thread-4) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2722170 INFO  (jetty-launcher-3399-thread-4) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 7.2.0
   [junit4]   2> 2722170 INFO  (jetty-launcher-3399-thread-4) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2722170 INFO  (jetty-launcher-3399-thread-4) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2722170 INFO  (jetty-launcher-3399-thread-4) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-10-31T10:14:30.594Z
   [junit4]   2> 2722189 INFO  (jetty-launcher-3399-thread-4) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2722233 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 0x15f71ece0c00009, 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> 2722236 INFO  (jetty-launcher-3399-thread-4) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56552/solr
   [junit4]   2> 2722302 INFO  (jetty-launcher-3399-thread-1) [n:127.0.0.1:56558_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2722304 INFO  (jetty-launcher-3399-thread-1) [n:127.0.0.1:56558_solr    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:56558_solr
   [junit4]   2> 2722307 INFO  (jetty-launcher-3399-thread-1) [n:127.0.0.1:56558_solr    ] o.a.s.c.Overseer Overseer (id=98922979022340106-127.0.0.1:56558_solr-n_0000000000) starting
   [junit4]   2> 2722314 INFO  (jetty-launcher-3399-thread-4) [n:127.0.0.1:56561_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2722319 INFO  (jetty-launcher-3399-thread-4) [n:127.0.0.1:56561_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56561_solr
   [junit4]   2> 2722324 INFO  (zkCallback-3423-thread-1-processing-n:127.0.0.1:56561_solr) [n:127.0.0.1:56561_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2722324 INFO  (zkCallback-3419-thread-1-processing-n:127.0.0.1:56558_solr) [n:127.0.0.1:56558_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2722342 INFO  (jetty-launcher-3399-thread-1) [n:127.0.0.1:56558_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56558_solr
   [junit4]   2> 2722353 INFO  (zkCallback-3423-thread-1-processing-n:127.0.0.1:56561_solr) [n:127.0.0.1:56561_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2722354 INFO  (zkCallback-3419-thread-1-processing-n:127.0.0.1:56558_solr) [n:127.0.0.1:56558_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2722497 INFO  (jetty-launcher-3399-thread-2) [n:127.0.0.1:56555_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2722505 INFO  (jetty-launcher-3399-thread-3) [n:127.0.0.1:56554_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2722508 INFO  (jetty-launcher-3399-thread-3) [n:127.0.0.1:56554_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2722511 INFO  (jetty-launcher-3399-thread-3) [n:127.0.0.1:56554_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56554_solr
   [junit4]   2> 2722514 INFO  (zkCallback-3423-thread-1-processing-n:127.0.0.1:56561_solr) [n:127.0.0.1:56561_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 2722514 INFO  (zkCallback-3419-thread-1-processing-n:127.0.0.1:56558_solr) [n:127.0.0.1:56558_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 2722514 INFO  (zkCallback-3408-thread-1-processing-n:127.0.0.1:56554_solr) [n:127.0.0.1:56554_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 2722516 INFO  (jetty-launcher-3399-thread-2) [n:127.0.0.1:56555_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2722516 INFO  (zkCallback-3412-thread-1-processing-n:127.0.0.1:56555_solr) [n:127.0.0.1:56555_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 2722523 INFO  (jetty-launcher-3399-thread-2) [n:127.0.0.1:56555_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56555_solr
   [junit4]   2> 2722525 INFO  (zkCallback-3419-thread-1-processing-n:127.0.0.1:56558_solr) [n:127.0.0.1:56558_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 2722525 INFO  (zkCallback-3423-thread-1-processing-n:127.0.0.1:56561_solr) [n:127.0.0.1:56561_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 2722525 INFO  (zkCallback-3408-thread-1-processing-n:127.0.0.1:56554_solr) [n:127.0.0.1:56554_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 2722544 INFO  (zkCallback-3412-thread-1-processing-n:127.0.0.1:56555_solr) [n:127.0.0.1:56555_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 2722730 INFO  (jetty-launcher-3399-thread-4) [n:127.0.0.1:56561_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56561.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@583e2794
   [junit4]   2> 2722735 INFO  (jetty-launcher-3399-thread-1) [n:127.0.0.1:56558_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56558.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@583e2794
   [junit4]   2> 2722764 INFO  (jetty-launcher-3399-thread-3) [n:127.0.0.1:56554_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56554.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@583e2794
   [junit4]   2> 2722766 INFO  (jetty-launcher-3399-thread-4) [n:127.0.0.1:56561_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56561.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@583e2794
   [junit4]   2> 2722767 INFO  (jetty-launcher-3399-thread-4) [n:127.0.0.1:56561_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56561.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@583e2794
   [junit4]   2> 2722768 INFO  (jetty-launcher-3399-thread-4) [n:127.0.0.1:56561_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_24D85E0053680E6F-001/tempDir-001/node4/.
   [junit4]   2> 2722776 INFO  (jetty-launcher-3399-thread-2) [n:127.0.0.1:56555_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56555.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@583e2794
   [junit4]   2> 2722794 INFO  (jetty-launcher-3399-thread-3) [n:127.0.0.1:56554_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56554.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@583e2794
   [junit4]   2> 2722794 INFO  (jetty-launcher-3399-thread-3) [n:127.0.0.1:56554_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56554.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@583e2794
   [junit4]   2> 2722804 INFO  (jetty-launcher-3399-thread-2) [n:127.0.0.1:56555_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56555.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@583e2794
   [junit4]   2> 2722804 INFO  (jetty-launcher-3399-thread-2) [n:127.0.0.1:56555_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56555.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@583e2794
   [junit4]   2> 2722804 INFO  (jetty-launcher-3399-thread-1) [n:127.0.0.1:56558_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56558.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@583e2794
   [junit4]   2> 2722805 INFO  (jetty-launcher-3399-thread-1) [n:127.0.0.1:56558_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56558.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@583e2794
   [junit4]   2> 2722805 INFO  (jetty-launcher-3399-thread-2) [n:127.0.0.1:56555_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_24D85E0053680E6F-001/tempDir-001/node2/.
   [junit4]   2> 2722804 INFO  (jetty-launcher-3399-thread-3) [n:127.0.0.1:56554_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_24D85E0053680E6F-001/tempDir-001/node3/.
   [junit4]   2> 2722807 INFO  (jetty-launcher-3399-thread-1) [n:127.0.0.1:56558_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_24D85E0053680E6F-001/tempDir-001/node1/.
   [junit4]   2> 2722957 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 0x15f71ece0c0000d, 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> 2722964 INFO  (SUITE-CustomCollectionTest-seed#[24D85E0053680E6F]-worker) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 2722965 INFO  (SUITE-CustomCollectionTest-seed#[24D85E0053680E6F]-worker) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:56552/solr ready
   [junit4]   2> 2723373 INFO  (TEST-CustomCollectionTest.testCreateShardRepFactor-seed#[24D85E0053680E6F]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testCreateShardRepFactor
   [junit4]   2> 2723376 INFO  (TEST-CustomCollectionTest.testCreateShardRepFactor-seed#[24D85E0053680E6F]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 2723419 INFO  (qtp843186363-21052) [n:127.0.0.1:56554_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params shards=a,b&replicationFactor=1&collection.configName=conf&name=testCreateShardRepFactor&router.name=implicit&nrtReplicas=1&action=CREATE&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2723423 INFO  (OverseerThreadFactory-9455-thread-1-processing-n:127.0.0.1:56558_solr) [n:127.0.0.1:56558_solr    ] o.a.s.c.CreateCollectionCmd Create collection testCreateShardRepFactor
   [junit4]   2> 2723572 INFO  (OverseerStateUpdate-98922979022340106-127.0.0.1:56558_solr-n_0000000000) [n:127.0.0.1:56558_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"testCreateShardRepFactor",
   [junit4]   2>   "shard":"a",
   [junit4]   2>   "core":"testCreateShardRepFactor_a_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:56561/solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2723585 INFO  (OverseerStateUpdate-98922979022340106-127.0.0.1:56558_solr-n_0000000000) [n:127.0.0.1:56558_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"testCreateShardRepFactor",
   [junit4]   2>   "shard":"b",
   [junit4]   2>   "core":"testCreateShardRepFactor_b_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:56554/solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2723838 INFO  (qtp843186363-21055) [n:127.0.0.1:56554_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=testCreateShardRepFactor_b_replica_n2&action=CREATE&numShards=2&collection=testCreateShardRepFactor&shard=b&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2723839 INFO  (qtp843186363-21055) [n:127.0.0.1:56554_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 2723863 INFO  (qtp169223275-21043) [n:127.0.0.1:56561_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testCreateShardRepFactor_a_replica_n1&action=CREATE&numShards=2&collection=testCreateShardRepFactor&shard=a&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2723864 INFO  (qtp169223275-21043) [n:127.0.0.1:56561_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 2723980 INFO  (zkCallback-3423-thread-1-processing-n:127.0.0.1:56561_solr) [n:127.0.0.1:56561_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2723980 INFO  (zkCallback-3408-thread-1-processing-n:127.0.0.1:56554_solr) [n:127.0.0.1:56554_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2723981 INFO  (zkCallback-3408-thread-2-processing-n:127.0.0.1:56554_solr) [n:127.0.0.1:56554_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2723981 INFO  (zkCallback-3423-thread-2-processing-n:127.0.0.1:56561_solr) [n:127.0.0.1:56561_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2724882 INFO  (qtp843186363-21055) [n:127.0.0.1:56554_solr c:testCreateShardRepFactor s:b r:core_node4 x:testCreateShardRepFactor_b_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
   [junit4]   2> 2724888 INFO  (qtp169223275-21043) [n:127.0.0.1:56561_solr c:testCreateShardRepFactor s:a r:core_node3 x:testCreateShardRepFactor_a_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
   [junit4]   2> 2724889 INFO  (qtp843186363-21055) [n:127.0.0.1:56554_solr c:testCreateShardRepFactor s:b r:core_node4 x:testCreateShardRepFactor_b_replica_n2] o.a.s.s.IndexSchema [testCreateShardRepFactor_b_replica_n2] Schema name=cloud-dynamic
   [junit4]   2> 2724893 INFO  (qtp169223275-21043) [n:127.0.0.1:56561_solr c:testCreateShardRepFactor s:a r:core_node3 x:testCreateShardRepFactor_a_replica_n1] o.a.s.s.IndexSchema [testCreateShardRepFactor_a_replica_n1] Schema name=cloud-dynamic
   [junit4]   2> 2724929 INFO  (qtp843186363-21055) [n:127.0.0.1:56554_solr c:testCreateShardRepFactor s:b r:core_node4 x:testCreateShardRepFactor_b_replica_n2] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2724929 INFO  (qtp843186363-21055) [n:127.0.0.1:56554_solr c:testCreateShardRepFactor s:b r:core_node4 x:testCreateShardRepFactor_b_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'testCreateShardRepFactor_b_replica_n2' using configuration from collection testCreateShardRepFactor, trusted=true
   [junit4]   2> 2724930 INFO  (qtp843186363-21055) [n:127.0.0.1:56554_solr c:testCreateShardRepFactor s:b r:core_node4 x:testCreateShardRepFactor_b_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56554.solr.core.testCreateShardRepFactor.b.replica_n2' (registry 'solr.core.testCreateShardRepFactor.b.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@583e2794
   [junit4]   2> 2724930 INFO  (qtp843186363-21055) [n:127.0.0.1:56554_solr c:testCreateShardRepFactor s:b r:core_node4 x:testCreateShardRepFactor_b_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2724930 INFO  (qtp843186363-21055) [n:127.0.0.1:56554_solr c:testCreateShardRepFactor s:b r:core_node4 x:testCreateShardRepFactor_b_replica_n2] o.a.s.c.SolrCore [[testCreateShardRepFactor_b_replica_n2] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_24D85E0053680E6F-001/tempDir-001/node3/testCreateShardRepFactor_b_replica_n2], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_24D85E0053680E6F-001/tempDir-001/node3/./testCreateShardRepFactor_b_replica_n2/data/]
   [junit4]   2> 2724930 INFO  (qtp169223275-21043) [n:127.0.0.1:56561_solr c:testCreateShardRepFactor s:a r:core_node3 x:testCreateShardRepFactor_a_replica_n1] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2724930 INFO  (qtp169223275-21043) [n:127.0.0.1:56561_solr c:testCreateShardRepFactor s:a r:core_node3 x:testCreateShardRepFactor_a_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'testCreateShardRepFactor_a_replica_n1' using configuration from collection testCreateShardRepFactor, trusted=true
   [junit4]   2> 2724930 INFO  (qtp169223275-21043) [n:127.0.0.1:56561_solr c:testCreateShardRepFactor s:a r:core_node3 x:testCreateShardRepFactor_a_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56561.solr.core.testCreateShardRepFactor.a.replica_n1' (registry 'solr.core.testCreateShardRepFactor.a.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@583e2794
   [junit4]   2> 2724931 INFO  (qtp169223275-21043) [n:127.0.0.1:56561_solr c:testCreateShardRepFactor s:a r:core_node3 x:testCreateShardRepFactor_a_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2724931 INFO  (qtp169223275-21043) [n:127.0.0.1:56561_solr c:testCreateShardRepFactor s:a r:core_node3 x:testCreateShardRepFactor_a_replica_n1] o.a.s.c.SolrCore [[testCreateShardRepFactor_a_replica_n1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_24D85E0053680E6F-001/tempDir-001/node4/testCreateShardRepFactor_a_replica_n1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_24D85E0053680E6F-001/tempDir-001/node4/./testCreateShardRepFactor_a_replica_n1/data/]
   [junit4]   2> 2724988 INFO  (qtp169223275-21043) [n:127.0.0.1:56561_solr c:testCreateShardRepFactor s:a r:core_node3 x:testCreateShardRepFactor_a_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2724988 INFO  (qtp169223275-21043) [n:127.0.0.1:56561_solr c:testCreateShardRepFactor s:a r:core_node3 x:testCreateShardRepFactor_a_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2724990 INFO  (qtp169223275-21043) [n:127.0.0.1:56561_solr c:testCreateShardRepFactor s:a r:core_node3 x:testCreateShardRepFactor_a_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2724990 INFO  (qtp169223275-21043) [n:127.0.0.1:56561_solr c:testCreateShardRepFactor s:a r:core_node3 x:testCreateShardRepFactor_a_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2724991 INFO  (qtp169223275-21043) [n:127.0.0.1:56561_solr c:testCreateShardRepFactor s:a r:core_node3 x:testCreateShardRepFactor_a_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3f4a0347[testCreateShardRepFactor_a_replica_n1] main]
   [junit4]   2> 2724992 INFO  (qtp169223275-21043) [n:127.0.0.1:56561_solr c:testCreateShardRepFactor s:a r:core_node3 x:testCreateShardRepFactor_a_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 2724992 INFO  (qtp169223275-21043) [n:127.0.0.1:56561_solr c:testCreateShardRepFactor s:a r:core_node3 x:testCreateShardRepFactor_a_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2724993 INFO  (qtp169223275-21043) [n:127.0.0.1:56561_solr c:testCreateShardRepFactor s:a r:core_node3 x:testCreateShardRepFactor_a_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 2724993 INFO  (qtp169223275-21043) [n:127.0.0.1:56561_solr c:testCreateShardRepFactor s:a r:core_node3 x:testCreateShardRepFactor_a_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1582767667588104192
   [junit4]   2> 2724994 INFO  (searcherExecutor-9465-thread-1-processing-n:127.0.0.1:56561_solr x:testCreateShardRepFactor_a_replica_n1 s:a c:testCreateShardRepFactor r:core_node3) [n:127.0.0.1:56561_solr c:testCreateShardRepFactor s:a r:core_node3 x:testCreateShardRepFactor_a_replica_n1] o.a.s.c.SolrCore [testCreateShardRepFactor_a_replica_n1] Registered new searcher Searcher@3f4a0347[testCreateShardRepFactor_a_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2724997 INFO  (qtp843186363-21055) [n:127.0.0.1:56554_solr c:testCreateShardRepFactor s:b r:core_node4 x:testCreateShardRepFactor_b_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2724997 INFO  (qtp843186363-21055) [n:127.0.0.1:56554_solr c:testCreateShardRepFactor s:b r:core_node4 x:testCreateShardRepFactor_b_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2724998 INFO  (qtp843186363-21055) [n:127.0.0.1:56554_solr c:testCreateShardRepFactor s:b r:core_node4 x:testCreateShardRepFactor_b_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2724999 INFO  (qtp843186363-21055) [n:127.0.0.1:56554_solr c:testCreateShardRepFactor s:b r:core_node4 x:testCreateShardRepFactor_b_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2725000 INFO  (qtp169223275-21043) [n:127.0.0.1:56561_solr c:testCreateShardRepFactor s:a r:core_node3 x:testCreateShardRepFactor_a_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2725000 INFO  (qtp169223275-21043) [n:127.0.0.1:56561_solr c:testCreateShardRepFactor s:a r:core_node3 x:testCreateShardRepFactor_a_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2725000 INFO  (qtp843186363-21055) [n:127.0.0.1:56554_solr c:testCreateShardRepFactor s:b r:core_node4 x:testCreateShardRepFactor_b_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@41c75524[testCreateShardRepFactor_b_replica_n2] main]
   [junit4]   2> 2725000 INFO  (qtp169223275-21043) [n:127.0.0.1:56561_solr c:testCreateShardRepFactor s:a r:core_node3 x:testCreateShardRepFactor_a_replica_n1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:56561/solr/testCreateShardRepFactor_a_replica_n1/
   [junit4]   2> 2725000 INFO  (qtp169223275-21043) [n:127.0.0.1:56561_solr c:testCreateShardRepFactor s:a r:core_node3 x:testCreateShardRepFactor_a_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 2725000 INFO  (qtp169223275-21043) [n:127.0.0.1:56561_solr c:testCreateShardRepFactor s:a r:core_node3 x:testCreateShardRepFactor_a_replica_n1] o.a.s.c.SyncStrategy https://127.0.0.1:56561/solr/testCreateShardRepFactor_a_replica_n1/ has no replicas
   [junit4]   2> 2725000 INFO  (qtp169223275-21043) [n:127.0.0.1:56561_solr c:testCreateShardRepFactor s:a r:core_node3 x:testCreateShardRepFactor_a_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
   [junit4]   2> 2725002 INFO  (qtp843186363-21055) [n:127.0.0.1:56554_solr c:testCreateShardRepFactor s:b r:core_node4 x:testCreateShardRepFactor_b_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 2725002 INFO  (qtp843186363-21055) [n:127.0.0.1:56554_solr c:testCreateShardRepFactor s:b r:core_node4 x:testCreateShardRepFactor_b_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2725003 INFO  (qtp843186363-21055) [n:127.0.0.1:56554_solr c:testCreateShardRepFactor s:b r:core_node4 x:testCreateShardRepFactor_b_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 2725004 INFO  (searcherExecutor-9464-thread-1-processing-n:127.0.0.1:56554_solr x:testCreateShardRepFactor_b_replica_n2 s:b c:testCreateShardRepFactor r:core_node4) [n:127.0.0.1:56554_solr c:testCreateShardRepFactor s:b r:core_node4 x:testCreateShardRepFactor_b_replica_n2] o.a.s.c.SolrCore [testCreateShardRepFactor_b_replica_n2] Registered new searcher Searcher@41c75524[testCreateShardRepFactor_b_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2725004 INFO  (qtp843186363-21055) [n:127.0.0.1:56554_solr c:testCreateShardRepFactor s:b r:core_node4 x:testCreateShardRepFactor_b_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1582767667599638528
   [junit4]   2> 2725006 INFO  (qtp169223275-21043) [n:127.0.0.1:56561_solr c:testCreateShardRepFactor s:a r:core_node3 x:testCreateShardRepFactor_a_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:56561/solr/testCreateShardRepFactor_a_replica_n1/ a
   [junit4]   2> 2725011 INFO  (qtp843186363-21055) [n:127.0.0.1:56554_solr c:testCreateShardRepFactor s:b r:core_node4 x:testCreateShardRepFactor_b_replica_n2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2725012 INFO  (qtp843186363-21055) [n:127.0.0.1:56554_solr c:testCreateShardRepFactor s:b r:core_node4 x:testCreateShardRepFactor_b_replica_n2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2725012 INFO  (qtp843186363-21055) [n:127.0.0.1:56554_solr c:testCreateShardRepFactor s:b r:core_node4 x:testCreateShardRepFactor_b_replica_n2] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:56554/solr/testCreateShardRepFactor_b_replica_n2/
   [junit4]   2> 2725012 INFO  (qtp843186363-21055) [n:127.0.0.1:56554_solr c:testCreateShardRepFactor s:b r:core_node4 x:testCreateShardRepFactor_b_replica_n2] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 2725012 INFO  (qtp843186363-21055) [n:127.0.0.1:56554_solr c:testCreateShardRepFactor s:b r:core_node4 x:testCreateShardRepFactor_b_replica_n2] o.a.s.c.SyncStrategy https://127.0.0.1:56554/solr/testCreateShardRepFactor_b_replica_n2/ has no replicas
   [junit4]   2> 2725012 INFO  (qtp843186363-21055) [n:127.0.0.1:56554_solr c:testCreateShardRepFactor s:b r:core_node4 x:testCreateShardRepFactor_b_replica_n2] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
   [junit4]   2> 2725017 INFO  (qtp843186363-21055) [n:127.0.0.1:56554_solr c:testCreateShardRepFactor s:b r:core_node4 x:testCreateShardRepFactor_b_replica_n2] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:56554/solr/testCreateShardRepFactor_b_replica_n2/ b
   [junit4]   2> 2725120 INFO  (zkCallback-3408-thread-2-processing-n:127.0.0.1:56554_solr) [n:127.0.0.1:56554_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2725120 INFO  (zkCallback-3423-thread-2-processing-n:127.0.0.1:56561_solr) [n:127.0.0.1:56561_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2725120 INFO  (zkCallback-3423-thread-1-processing-n:127.0.0.1:56561_solr) [n:127.0.0.1:56561_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2725120 INFO  (zkCallback-3408-thread-1-processing-n:127.0.0.1:56554_solr) [n:127.0.0.1:56554_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2725134 INFO  (qtp843186363-21055) [n:127.0.0.1:56554_solr c:testCreateShardRepFactor s:b r:core_node4 x:testCreateShardRepFactor_b_replica_n2] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2725136 INFO  (qtp843186363-21055) [n:127.0.0.1:56554_solr c:testCreateShardRepFactor s:b r:core_node4 x:testCreateShardRepFactor_b_replica_n2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=testCreateShardRepFactor_b_replica_n2&action=CREATE&numShards=2&collection=testCreateShardRepFactor&shard=b&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1297
   [junit4]   2> 2725179 INFO  (qtp169223275-21043) [n:127.0.0.1:56561_solr c:testCreateShardRepFactor s:a r:core_node3 x:testCreateShardRepFactor_a_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2725181 INFO  (qtp169223275-21043) [n:127.0.0.1:56561_solr c:testCreateShardRepFactor s:a r:core_node3 x:testCreateShardRepFactor_a_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testCreateShardRepFactor_a_replica_n1&action=CREATE&numShards=2&collection=testCreateShardRepFactor&shard=a&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1317
   [junit4]   2> 2725187 INFO  (qtp843186363-21052) [n:127.0.0.1:56554_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> 2725288 INFO  (zkCallback-3423-thread-1-processing-n:127.0.0.1:56561_solr) [n:127.0.0.1:56561_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2725288 INFO  (zkCallback-3423-thread-2-processing-n:127.0.0.1:56561_solr) [n:127.0.0.1:56561_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2725288 INFO  (zkCallback-3408-thread-1-processing-n:127.0.0.1:56554_solr) [n:127.0.0.1:56554_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2725288 INFO  (zkCallback-3408-thread-2-processing-n:127.0.0.1:56554_solr) [n:127.0.0.1:56554_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2725438 INFO  (OverseerCollectionConfigSetProcessor-98922979022340106-127.0.0.1:56558_solr-n_0000000000) [n:127.0.0.1:56558_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> 2726193 INFO  (qtp843186363-21052) [n:127.0.0.1:56554_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={shards=a,b&replicationFactor=1&collection.configName=conf&name=testCreateShardRepFactor&router.name=implicit&nrtReplicas=1&action=CREATE&wt=javabin&version=2} status=0 QTime=2774
   [junit4]   2> 2726194 INFO  (qtp843186363-21048) [n:127.0.0.1:56554_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :createshard with params action=CREATESHARD&collection=testCreateShardRepFactor&shard=x&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2726197 INFO  (OverseerThreadFactory-9455-thread-2-processing-n:127.0.0.1:56558_solr) [n:127.0.0.1:56558_solr    ] o.a.s.c.CreateShardCmd Create shard invoked: {
   [junit4]   2>   "collection":"testCreateShardRepFactor",
   [junit4]   2>   "shard":"x",
   [junit4]   2>   "operation":"createshard"}
   [junit4]   2> 2726303 INFO  (zkCallback-3423-thread-2-processing-n:127.0.0.1:56561_solr) [n:127.0.0.1:56561_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2726303 INFO  (zkCallback-3408-thread-2-processing-n:127.0.0.1:56554_solr) [n:127.0.0.1:56554_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2726303 INFO  (zkCallback-3423-thread-1-processing-n:127.0.0.1:56561_solr) [n:127.0.0.1:56561_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2726303 INFO  (zkCallback-3408-thread-1-processing-n:127.0.0.1:56554_solr) [n:127.0.0.1:56554_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2727208 INFO  (OverseerThreadFactory-9455-thread-2-processing-n:127.0.0.1:56558_solr) [n:127.0.0.1:56558_solr    ] o.a.s.c.CreateShardCmd Creating replica testCreateShardRepFactor_x_replica_n5 as part of slice x of collection testCreateShardRepFactor on 127.0.0.1:56558_solr
   [junit4]   2> 2727210 INFO  (OverseerThreadFactory-9455-thread-2-processing-n:127.0.0.1:56558_solr) [n:127.0.0.1:56558_solr    ] o.a.s.c.AddReplicaCmd Node Identified 127.0.0.1:56558_solr for creating new replica
   [junit4]   2> 2727212 INFO  (OverseerStateUpdate-98922979022340106-127.0.0.1:56558_solr-n_0000000000) [n:127.0.0.1:56558_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"addreplica",
   [junit4]   2>   "collection":"testCreateShardRepFactor",
   [junit4]   2>   "shard":"x",
   [junit4]   2>   "core":"testCreateShardRepFactor_x_replica_n5",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:56558/solr",
   [junit4]   2>   "node_name":"127.0.0.1:56558_solr",
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 2727323 INFO  (zkCallback-3423-thread-1-processing-n:127.0.0.1:56561_solr) [n:127.0.0.1:56561_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2727323 INFO  (zkCallback-3423-thread-2-processing-n:127.0.0.1:56561_solr) [n:127.0.0.1:56561_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2727323 INFO  (zkCallback-3408-thread-1-processing-n:127.0.0.1:56554_solr) [n:127.0.0.1:56554_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2727323 INFO  (zkCallback-3408-thread-2-processing-n:127.0.0.1:56554_solr) [n:127.0.0.1:56554_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2727457 INFO  (qtp83404244-21071) [n:127.0.0.1:56558_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&name=testCreateShardRepFactor_x_replica_n5&action=CREATE&collection=testCreateShardRepFactor&shard=x&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2727458 INFO  (qtp83404244-21071) [n:127.0.0.1:56558_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 2727473 INFO  (qtp83404244-21071) [n:127.0.0.1:56558_solr c:testCreateShardRepFactor s:x r:core_node6 x:testCreateShardRepFactor_x_replica_n5] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
   [junit4]   2> 2727479 INFO  (qtp83404244-21071) [n:127.0.0.1:56558_solr c:testCreateShardRepFactor s:x r:core_node6 x:testCreateShardRepFactor_x_replica_n5] o.a.s.s.IndexSchema [testCreateShardRepFactor_x_replica_n5] Schema name=cloud-dynamic
   [junit4]   2> 2727495 INFO  (qtp83404244-21071) [n:127.0.0.1:56558_solr c:testCreateShardRepFactor s:x r:core_node6 x:testCreateShardRepFactor_x_replica_n5] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2727495 INFO  (qtp83404244-21071) [n:127.0.0.1:56558_solr c:testCreateShardRepFactor s:x r:core_node6 x:testCreateShardRepFactor_x_replica_n5] o.a.s.c.CoreContainer Creating SolrCore 'testCreateShardRepFactor_x_replica_n5' using configuration from collection testCreateShardRepFactor, trusted=true
   [junit4]   2> 2727496 INFO  (qtp83404244-21071) [n:127.0.0.1:56558_solr c:testCreateShardRepFactor s:x r:core_node6 x:testCreateShardRepFactor_x_replica_n5] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56558.solr.core.testCreateShardRepFactor.x.replica_n5' (registry 'solr.core.testCreateShardRepFactor.x.replica_n5') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@583e2794
   [junit4]   2> 2727496 INFO  (qtp83404244-21071) [n:127.0.0.1:56558_solr c:testCreateShardRepFactor s:x r:core_node6 x:testCreateShardRepFactor_x_replica_n5] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2727496 INFO  (qtp83404244-21071) [n:127.0.0.1:56558_solr c:testCreateShardRepFactor s:x r:core_node6 x:testCreateShardRepFactor_x_replica_n5] o.a.s.c.SolrCore [[testCreateShardRepFactor_x_replica_n5] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_24D85E0053680E6F-001/tempDir-001/node1/testCreateShardRepFactor_x_replica_n5], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_24D85E0053680E6F-001/tempDir-001/node1/./testCreateShardRepFactor_x_replica_n5/data/]
   [junit4]   2> 2727569 INFO  (qtp83404244-21071) [n:127.0.0.1:56558_solr c:testCreateShardRepFactor s:x r:core_node6 x:testCreateShardRepFactor_x_replica_n5] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2727570 INFO  (qtp83404244-21071) [n:127.0.0.1:56558_solr c:testCreateShardRepFactor s:x r:core_node6 x:testCreateShardRepFactor_x_replica_n5] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2727570 INFO  (zkCallback-3423-thread-2-processing-n:127.0.0.1:56561_solr) [n:127.0.0.1:56561_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2727570 INFO  (zkCallback-3423-thread-1-processing-n:127.0.0.1:56561_solr) [n:127.0.0.1:56561_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2727570 INFO  (zkCallback-3408-thread-2-processing-n:127.0.0.1:56554_solr) [n:127.0.0.1:56554_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2727570 INFO  (zkCallback-3408-thread-1-processing-n:127.0.0.1:56554_solr) [n:127.0.0.1:56554_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2727570 INFO  (zkCallback-3419-thread-1-processing-n:127.0.0.1:56558_solr) [n:127.0.0.1:56558_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2727571 INFO  (zkCallback-3419-thread-2-processing-n:127.0.0.1:56558_solr) [n:127.0.0.1:56558_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2727572 INFO  (qtp83404244-21071) [n:127.0.0.1:56558_solr c:testCreateShardRepFactor s:x r:core_node6 x:testCreateShardRepFactor_x_replica_n5] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2727572 INFO  (qtp83404244-21071) [n:127.0.0.1:56558_solr c:testCreateShardRepFactor s:x r:core_node6 x:testCreateShardRepFactor_x_replica_n5] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2727573 INFO  (qtp83404244-21071) [n:127.0.0.1:56558_solr c:testCreateShardRepFactor s:x r:core_node6 x:testCreateShardRepFactor_x_replica_n5] o.a.s.s.SolrIndexSearcher Opening [Searcher@1cb374ef[testCreateShardRepFactor_x_replica_n5] main]
   [junit4]   2> 2727575 INFO  (qtp83404244-21071) [n:127.0.0.1:56558_solr c:testCreateShardRepFactor s:x r:core_node6 x:testCreateShardRepFactor_x_replica_n5] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 2727575 INFO  (qtp83404244-21071) [n:127.0.0.1:56558_solr c:testCreateShardRepFactor s:x r:core_node6 x:testCreateShardRepFactor_x_replica_n5] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2727575 INFO  (qtp83404244-21071) [n:127.0.0.1:56558_solr c:testCreateShardRepFactor s:x r:core_node6 x:testCreateShardRepFactor_x_replica_n5] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 2727576 INFO  (qtp83404244-21071) [n:127.0.0.1:56558_solr c:testCreateShardRepFactor s:x r:core_node6 x:testCreateShardRepFactor_x_replica_n5] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1582767670296576000
   [junit4]   2> 2727576 INFO  (searcherExecutor-9474-thread-1-processing-n:127.0.0.1:56558_solr x:testCreateShardRepFactor_x_replica_n5 s:x c:testCreateShardRepFactor r:core_node6) [n:127.0.0.1:56558_solr c:testCreateShardRepFactor s:x r:core_node6 x:testCreateShardRepFactor_x_replica_n5] o.a.s.c.SolrCore [testCreateShardRepFactor_x_replica_n5] Registered new searcher Searcher@1cb374ef[testCreateShardRepFactor_x_replica_n5] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2727581 INFO  (qtp83404244-21071) [n:127.0.0.1:56558_solr c:testCreateShardRepFactor s:x r:core_node6 x:testCreateShardRepFactor_x_replica_n5] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2727581 INFO  (qtp83404244-21071) [n:127.0.0.1:56558_solr c:testCreateShardRepFactor s:x r:core_node6 x:testCreateShardRepFactor_x_replica_n5] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2727581 INFO  (qtp83404244-21071) [n:127.0.0.1:56558_solr c:testCreateShardRepFactor s:x r:core_node6 x:testCreateShardRepFactor_x_replica_n5] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:56558/solr/testCreateShardRepFactor_x_replica_n5/
   [junit4]   2> 2727581 INFO  (qtp83404244-21071) [n:127.0.0.1:56558_solr c:testCreateShardRepFactor s:x r:core_node6 x:testCreateShardRepFactor_x_replica_n5] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 2727581 INFO  (qtp83404244-21071) [n:127.0.0.1:56558_solr c:testCreateShardRepFactor s:x r:core_node6 x:testCreateShardRepFactor_x_replica_n5] o.a.s.c.SyncStrategy https://127.0.0.1:56558/solr/testCreateShardRepFactor_x_replica_n5/ has no replicas
   [junit4]   2> 2727582 INFO  (qtp83404244-21071) [n:127.0.0.1:56558_solr c:testCreateShardRepFactor s:x r:core_node6 x:testCreateShardRepFactor_x_replica_n5] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
   [junit4]   2> 2727587 INFO  (qtp83404244-21071) [n:127.0.0.1:56558_solr c:testCreateShardRepFactor s:x r:core_node6 x:testCreateShardRepFactor_x_replica_n5] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:56558/solr/testCreateShardRepFactor_x_replica_n5/ x
   [junit4]   2> 2727699 INFO  (zkCallback-3408-thread-1-processing-n:127.0.0.1:56554_solr) [n:127.0.0.1:56554_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2727699 INFO  (zkCallback-3408-thread-2-processing-n:127.0.0.1:56554_solr) [n:127.0.0.1:56554_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2727699 INFO  (zkCallback-3419-thread-2-processing-n:127.0.0.1:56558_solr) [n:127.0.0.1:56558_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2727699 INFO  (zkCallback-3419-thread-3-processing-n:127.0.0.1:56558_solr) [n:127.0.0.1:56558_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2727699 INFO  (zkCallback-3423-thread-2-processing-n:127.0.0.1:56561_solr) [n:127.0.0.1:56561_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2727699 INFO  (zkCallback-3423-thread-1-processing-n:127.0.0.1:56561_solr) [n:127.0.0.1:56561_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2727758 INFO  (qtp83404244-21071) [n:127.0.0.1:56558_solr c:testCreateShardRepFactor s:x r:core_node6 x:testCreateShardRepFactor_x_replica_n5] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2727760 INFO  (qtp83404244-21071) [n:127.0.0.1:56558_solr c:testCreateShardRepFactor s:x r:core_node6 x:testCreateShardRepFactor_x_replica_n5] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&name=testCreateShardRepFactor_x_replica_n5&action=CREATE&collection=testCreateShardRepFactor&shard=x&wt=javabin&version=2&replicaType=NRT} status=0 QTime=302
   [junit4]   2> 2727760 INFO  (OverseerThreadFactory-9455-thread-2-processing-n:127.0.0.1:56558_solr) [n:127.0.0.1:56558_solr    ] o.a.s.c.CreateShardCmd Finished create command on all shards for collection: testCreateShardRepFactor
   [junit4]   2> 2727762 INFO  (qtp843186363-21048) [n:127.0.0.1:56554_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CREATESHARD&collection=testCreateShardRepFactor&shard=x&wt=javabin&version=2} status=0 QTime=1568
   [junit4]   2> 2727764 INFO  (TEST-CustomCollectionTest.testCreateShardRepFactor-seed#[24D85E0053680E6F]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testCreateShardRepFactor
   [junit4]   2> 2727900 INFO  (zkCallback-3419-thread-1-processing-n:127.0.0.1:56558_solr) [n:127.0.0.1:56558_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2727900 INFO  (zkCallback-3419-thread-3-processing-n:127.0.0.1:56558_solr) [n:127.0.0.1:56558_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2727902 INFO  (zkCallback-3408-thread-1-processing-n:127.0.0.1:56554_solr) [n:127.0.0.1:56554_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2727902 INFO  (zkCallback-3423-thread-2-processing-n:127.0.0.1:56561_solr) [n:127.0.0.1:56561_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2727902 INFO  (zkCallback-3408-thread-2-processing-n:127.0.0.1:56554_solr) [n:127.0.0.1:56554_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2727904 INFO  (zkCallback-3423-thread-1-processing-n:127.0.0.1:56561_solr) [n:127.0.0.1:56561_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateShardRepFactor/state.json] for collection [testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2727983 INFO  (TEST-CustomCollectionTest.testRouteFieldForImplicitRouter-seed#[24D85E0053680E6F]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testRouteFieldForImplicitRouter
   [junit4]   2> 2727984 INFO  (TEST-CustomCollectionTest.testRouteFieldForImplicitRouter-seed#[24D85E0053680E6F]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 2727987 INFO  (qtp843186363-21050) [n:127.0.0.1:56554_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=testCreateShardRepFactor&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2727991 INFO  (OverseerThreadFactory-9455-thread-3-processing-n:127.0.0.1:56558_solr) [n:127.0.0.1:56558_solr    ] o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
   [junit4]   2> 2727992 INFO  (OverseerCollectionConfigSetProcessor-98922979022340106-127.0.0.1:56558_solr-n_0000000000) [n:127.0.0.1:56558_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> 2727998 INFO  (qtp843186363-21051) [n:127.0.0.1:56554_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.testCreateShardRepFactor.b.replica_n2, tag=null
   [junit4]   2> 2727998 INFO  (qtp169223275-21044) [n:127.0.0.1:56561_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.testCreateShardRepFactor.a.replica_n1, tag=null
   [junit4]   2> 2727998 INFO  (qtp843186363-21051) [n:127.0.0.1:56554_solr    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@20db8f1d: rootName = solr_56554, domain = solr.core.testCreateShardRepFactor.b.replica_n2, service url = null, agent id = null] for registry solr.core.testCreateShardRepFactor.b.replica_n2 / com.codahale.metrics.MetricRegistry@4b4e573b
   [junit4]   2> 2728000 INFO  (qtp169223275-21044) [n:127.0.0.1:56561_solr    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4d9a31d8: rootName = solr_56561, domain = solr.core.testCreateShardRepFactor.a.replica_n1, service url = null, agent id = null] for registry solr.core.testCreateShardRepFactor.a.replica_n1 / com.codahale.metrics.MetricRegistry@46a80d4b
   [junit4]   2> 2728001 INFO  (qtp83404244-21072) [n:127.0.0.1:56558_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.testCreateShardRepFactor.x.replica_n5, tag=null
   [junit4]   2> 2728001 INFO  (qtp83404244-21072) [n:127.0.0.1:56558_solr    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@48dc8c92: rootName = solr_56558, domain = solr.core.testCreateShardRepFactor.x.replica_n5, service url = null, agent id = null] for registry solr.core.testCreateShardRepFactor.x.replica_n5 / com.codahale.metrics.MetricRegistry@2dfd39d8
   [junit4]   2> 2728123 INFO  (qtp169223275-21044) [n:127.0.0.1:56561_solr    ] o.a.s.c.SolrCore [testCreateShardRepFactor_a_replica_n1]  CLOSING SolrCore org.apache.solr.core.SolrCore@37f8c364
   [junit4]   2> 2728123 INFO  (qtp169223275-21044) [n:127.0.0.1:56561_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.testCreateShardRepFactor.a.replica_n1, tag=939049828
   [junit4]   2> 2728123 INFO  (qtp169223275-21044) [n:127.0.0.1:56561_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.testCreateShardRepFactor.a.leader, tag=939049828
   [junit4]   2> 2728123 INFO  (qtp843186363-21051) [n:127.0.0.1:56554_solr    ] o.a.s.c.SolrCore [testCreateShardRepFactor_b_replica_n2]  CLOSING SolrCore org.apache.solr.core.SolrCore@494a76ef
   [junit4]   2> 2728126 INFO  (qtp843186363-21051) [n:127.0.0.1:56554_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.testCreateShardRepFactor.b.replica_n2, tag=1229616879
   [junit4]   2> 2728126 INFO  (qtp843186363-21051) [n:127.0.0.1:56554_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.testCreateShardRepFactor.b.leader, tag=1229616879
   [junit4]   2> 2728126 INFO  (qtp83404244-21072) [n:127.0.0.1:56558_solr    ] o.a.s.c.SolrCore [testCreateShardRepFactor_x_replica_n5]  CLOSING SolrCore org.apache.solr.core.SolrCore@1db390b4
   [junit4]   2> 2728126 INFO  (qtp83404244-21072) [n:127.0.0.1:56558_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.testCreateShardRepFactor.x.replica_n5, tag=498307252
   [junit4]   2> 2728128 INFO  (qtp83404244-21072) [n:127.0.0.1:56558_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.testCreateShardRepFactor.x.leader, tag=498307252
   [junit4]   2> 2728136 INFO  (qtp169223275-21044) [n:127.0.0.1:56561_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=testCreateShardRepFactor_a_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=141
   [junit4]   2> 2728136 INFO  (qtp843186363-21051) [n:127.0.0.1:56554_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=testCreateShardRepFactor_b_replica_n2&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=143
   [junit4]   2> 2728137 INFO  (qtp83404244-21072) [n:127.0.0.1:56558_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=testCreateShardRepFactor_x_replica_n5&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=136
   [junit4]   2> 2728862 INFO  (qtp843186363-21050) [n:127.0.0.1:56554_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=testCreateShardRepFactor&action=DELETE&wt=javabin&version=2} status=0 QTime=875
   [junit4]   2> 2728863 INFO  (qtp843186363-21054) [n:127.0.0.1:56554_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params shards=a,b,c,d&replicationFactor=5&collection.configName=conf&maxShardsPerNode=6&router.field=shard_s&name=withShardField&router.name=implicit&nrtReplicas=5&action=CREATE&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2728866 INFO  (OverseerThreadFactory-9455-thread-4-processing-n:127.0.0.1:56558_solr) [n:127.0.0.1:56558_solr    ] o.a.s.c.CreateCollectionCmd Create collection withShardField
   [junit4]   2> 2728866 INFO  (OverseerCollectionConfigSetProcessor-98922979022340106-127.0.0.1:56558_solr-n_0000000000) [n:127.0.0.1:56558_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> 2728867 WARN  (OverseerThreadFactory-9455-thread-4-processing-n:127.0.0.1:56558_solr) [n:127.0.0.1:56558_solr    ] o.a.s.c.CreateCollectionCmd Specified number of replicas of 5 on collection withShardField is higher than the number of Solr instances currently live or live and part of your createNodeSet(4). It's unusual to run two replica of the same slice on the same Solr-instance.
   [junit4]   2> 2729098 INFO  (OverseerStateUpdate-98922979022340106-127.0.0.1:56558_solr-n_0000000000) [n:127.0.0.1:56558_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"a",
   [junit4]   2>   "core":"withShardField_a_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:56555/solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2729102 INFO  (OverseerStateUpdate-98922979022340106-127.0.0.1:56558_solr-n_0000000000) [n:127.0.0.1:56558_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"a",
   [junit4]   2>   "core":"withShardField_a_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:56558/solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2729105 INFO  (OverseerStateUpdate-98922979022340106-127.0.0.1:56558_solr-n_0000000000) [n:127.0.0.1:56558_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"a",
   [junit4]   2>   "core":"withShardField_a_replica_n4",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:56561/solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2729109 INFO  (OverseerStateUpdate-98922979022340106-127.0.0.1:56558_solr-n_0000000000) [n:127.0.0.1:56558_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"a",
   [junit4]   2>   "core":"withShardField_a_replica_n6",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:56554/solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2729111 INFO  (OverseerStateUpdate-98922979022340106-127.0.0.1:56558_solr-n_0000000000) [n:127.0.0.1:56558_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"a",
   [junit4]   2>   "core":"withShardField_a_replica_n8",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:56555/solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2729119 INFO  (OverseerStateUpdate-98922979022340106-127.0.0.1:56558_solr-n_0000000000) [n:127.0.0.1:56558_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"b",
   [junit4]   2>   "core":"withShardField_b_replica_n10",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:56558/solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2729122 INFO  (OverseerStateUpdate-98922979022340106-127.0.0.1:56558_solr-n_0000000000) [n:127.0.0.1:56558_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"b",
   [junit4]   2>   "core":"withShardField_b_replica_n12",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:56561/solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2729126 INFO  (OverseerStateUpdate-98922979022340106-127.0.0.1:56558_solr-n_0000000000) [n:127.0.0.1:56558_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"b",
   [junit4]   2>   "core":"withShardField_b_replica_n14",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:56554/solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2729130 INFO  (OverseerStateUpdate-98922979022340106-127.0.0.1:56558_solr-n_0000000000) [n:127.0.0.1:56558_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"b",
   [junit4]   2>   "core":"withShardField_b_replica_n16",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:56555/solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2729133 INFO  (OverseerStateUpdate-98922979022340106-127.0.0.1:56558_solr-n_0000000000) [n:127.0.0.1:56558_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"b",
   [junit4]   2>   "core":"withShardField_b_replica_n18",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:56558/solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2729137 INFO  (OverseerStateUpdate-98922979022340106-127.0.0.1:56558_solr-n_0000000000) [n:127.0.0.1:56558_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"c",
   [junit4]   2>   "core":"withShardField_c_replica_n20",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:56561/solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2729140 INFO  (OverseerStateUpdate-98922979022340106-127.0.0.1:56558_solr-n_0000000000) [n:127.0.0.1:56558_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"c",
   [junit4]   2>   "core":"withShardField_c_replica_n22",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:56554/solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2729144 INFO  (OverseerStateUpdate-98922979022340106-127.0.0.1:56558_solr-n_0000000000) [n:127.0.0.1:56558_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"c",
   [junit4]   2>   "core":"withShardField_c_replica_n24",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:56555/solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2729147 INFO  (OverseerStateUpdate-98922979022340106-127.0.0.1:56558_solr-n_0000000000) [n:127.0.0.1:56558_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"c",
   [junit4]   2>   "core":"withShardField_c_replica_n26",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:56558/solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2729151 INFO  (OverseerStateUpdate-98922979022340106-127.0.0.1:56558_solr-n_0000000000) [n:127.0.0.1:56558_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"c",
   [junit4]   2>   "core":"withShardField_c_replica_n28",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:56561/solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2729154 INFO  (OverseerStateUpdate-98922979022340106-127.0.0.1:56558_solr-n_0000000000) [n:127.0.0.1:56558_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"d",
   [junit4]   2>   "core":"withShardField_d_replica_n30",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:56554/solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2729158 INFO  (OverseerStateUpdate-98922979022340106-127.0.0.1:56558_solr-n_0000000000) [n:127.0.0.1:56558_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"d",
   [junit4]   2>   "core":"withShardField_d_replica_n32",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:56555/solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2729161 INFO  (OverseerStateUpdate-98922979022340106-127.0.0.1:56558_solr-n_0000000000) [n:127.0.0.1:56558_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"d",
   [junit4]   2>   "core":"withShardField_d_replica_n34",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:56558/solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2729164 INFO  (OverseerStateUpdate-98922979022340106-127.0.0.1:56558_solr-n_0000000000) [n:127.0.0.1:56558_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"d",
   [junit4]   2>   "core":"withShardField_d_replica_n36",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:56561/solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2729168 INFO  (OverseerStateUpdate-98922979022340106-127.0.0.1:56558_solr-n_0000000000) [n:127.0.0.1:56558_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"d",
   [junit4]   2>   "core":"withShardField_d_replica_n38",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:56554/solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2729384 INFO  (qtp83404244-21074) [n:127.0.0.1:56558_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=withShardField_a_replica_n2&action=CREATE&numShards=4&collection=withShardField&shard=a&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2729391 INFO  (qtp843186363-21055) [n:127.0.0.1:56554_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node17&collection.configName=conf&newCollection=true&name=withShardField_b_replica_n14&action=CREATE&numShards=4&collection=withShardField&shard=b&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2729403 INFO  (qtp843186363-21052) [n:127.0.0.1:56554_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node25&collection.configName=conf&newCollection=true&name=withShardField_c_replica_n22&action=CREATE&numShards=4&collection=withShardField&shard=c&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2729406 INFO  (qtp843186363-21050) [n:127.0.0.1:56554_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node33&collection.configName=conf&newCollection=true&name=withShardField_d_replica_n30&action=CREATE&numShards=4&collection=withShardField&shard=d&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2729406 INFO  (qtp83404244-21069) [n:127.0.0.1:56558_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node29&collection.configName=conf&newCollection=true&name=withShardField_c_replica_n26&action=CREATE&numShards=4&collection=withShardField&shard=c&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2729412 INFO  (qtp83404244-21072) [n:127.0.0.1:56558_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node37&collection.configName=conf&newCollection=true&name=withShardField_d_replica_n34&action=CREATE&numShards=4&collection=withShardField&shard=d&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2729414 INFO  (qtp83404244-21071) [n:127.0.0.1:56558_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node13&collection.configName=conf&newCollection=true&name=withShardField_b_replica_n10&action=CREATE&numShards=4&collection=withShardField&shard=b&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2729416 INFO  (qtp83404244-21070) [n:127.0.0.1:56558_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node21&collection.configName=conf&newCollection=true&name=withShardField_b_replica_n18&action=CREATE&numShards=4&collection=withShardField&shard=b&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2729417 INFO  (qtp843186363-21048) [n:127.0.0.1:56554_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName

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

core-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/apacheds-core-api-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/apacheds-core-avl-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/apacheds-core-shared-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/apacheds-i18n-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/apacheds-interceptor-kerberos-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/apacheds-interceptors-admin-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/apacheds-interceptors-authn-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/apacheds-interceptors-authz-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/apacheds-interceptors-changelog-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/apacheds-interceptors-collective-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/apacheds-interceptors-event-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/apacheds-interceptors-exception-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/apacheds-interceptors-journal-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/apacheds-interceptors-normalization-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/apacheds-interceptors-operational-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/apacheds-interceptors-referral-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/apacheds-interceptors-schema-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/apacheds-interceptors-subtree-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/apacheds-interceptors-trigger-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/apacheds-jdbm-partition-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/apacheds-jdbm1-2.0.0-M2.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/apacheds-kerberos-codec-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/apacheds-ldif-partition-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/apacheds-mavibot-partition-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/apacheds-protocol-kerberos-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/apacheds-protocol-ldap-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/apacheds-protocol-shared-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/apacheds-xdbm-partition-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/api-all-1.0.0-M20.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/bcprov-jdk15-1.45.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/byte-buddy-1.6.2.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/commons-collections-3.2.2.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/commons-math3-3.6.1.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/ehcache-core-2.4.4.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/hadoop-common-2.7.4-tests.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/hadoop-hdfs-2.7.4-tests.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/hadoop-minikdc-2.7.4.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/htrace-core-3.2.0-incubating.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/jersey-core-1.9.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/jersey-server-1.9.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/jetty-6.1.26.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/jetty-sslengine-6.1.26.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/jetty-util-6.1.26.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/mina-core-2.0.0-M5.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/mockito-core-2.6.2.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/netty-all-4.0.36.Final.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/test-lib/objenesis-2.5.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/lucene/build/analysis/icu/lucene-analyzers-icu-7.2.0-SNAPSHOT.jar:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/contrib/solr-analysis-extras/classes/java:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/lucene/build/queryparser/classes/test:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/lucene/build/backward-codecs/classes/test:/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/contrib/analysis-extras/lib/icu4j-59.1.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-launcher.jar:/Users/jenkins/.ant/lib/ivy-2.3.0.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-antlr.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-bcel.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-bsf.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-log4j.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-oro.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-regexp.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-resolver.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-xalan2.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-commons-logging.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-commons-net.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jai.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-javamail.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jdepend.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jmf.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jsch.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-junit.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-junit4.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-netrexx.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-swing.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-testutil.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant.jar:/Library/Java/JavaVirtualMachines/jdk1.8.0_144.jdk/Contents/Home/lib/tools.jar:/Users/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.5.3.jar com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/temp/junit4-J0-20171031_092905_1575504524047590430819.events @/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/temp/junit4-J0-20171031_092905_1576233902521393578675.suites -stdin
   [junit4] ERROR: JVM J0 ended with an exception: Forked process returned with error code: 134. Very likely a JVM crash.  See process stdout at: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/temp/junit4-J0-20171031_092905_1572242955450279347941.sysout
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1519)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:126)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:982)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:979)
   [junit4] 	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4] 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
   [junit4] 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   [junit4] 	at java.lang.Thread.run(Thread.java:748)

BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/build.xml:826: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/build.xml:770: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/build.xml:59: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build.xml:262: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/common-build.xml:556: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/lucene/common-build.xml:1494: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/lucene/common-build.xml:1021: At least one slave process threw an exception, first: Forked process returned with error code: 134. Very likely a JVM crash.  See process stdout at: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/temp/junit4-J0-20171031_092905_1572242955450279347941.sysout

Total time: 101 minutes 18 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any

Mime
View raw message