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-Windows (64bit/jdk1.8.0_144) - Build # 226 - Failure!
Date Mon, 02 Oct 2017 09:33:55 GMT
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Windows/226/
Java: 64bit/jdk1.8.0_144 -XX:+UseCompressedOops -XX:+UseParallelGC

1 tests failed.
FAILED:  org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI

Error Message:
Error from server at http://127.0.0.1:62408/solr/awhollynewcollection_0: {"awhollynewcollection_0":7}

Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:62408/solr/awhollynewcollection_0: {"awhollynewcollection_0":7}
	at __randomizedtesting.SeedInfo.seed([B36F42A0BB758B11:FB1A3614BD46A484]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:627)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:253)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:242)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1121)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:862)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:967)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:967)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:967)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:967)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:967)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:793)
	at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:178)
	at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:942)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI(CollectionsAPIDistributedZkTest.java:460)
	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 12403 lines...]
   [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
   [junit4]   2> 1222135 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[B36F42A0BB758B11]-worker) [    ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CollectionsAPIDistributedZkTest_B36F42A0BB758B11-001\init-core-data-001
   [junit4]   2> 1222143 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[B36F42A0BB758B11]-worker) [    ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 1222147 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[B36F42A0BB758B11]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 1222153 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[B36F42A0BB758B11]-worker) [    ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CollectionsAPIDistributedZkTest_B36F42A0BB758B11-001\tempDir-001
   [junit4]   2> 1222153 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[B36F42A0BB758B11]-worker) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1222153 INFO  (Thread-2396) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1222154 INFO  (Thread-2396) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1222161 ERROR (Thread-2396) [    ] 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> 1222254 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[B36F42A0BB758B11]-worker) [    ] o.a.s.c.ZkTestServer start zk server on port:62404
   [junit4]   2> 1222280 INFO  (jetty-launcher-2539-thread-1) [    ] o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 1222281 INFO  (jetty-launcher-2539-thread-3) [    ] o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 1222281 INFO  (jetty-launcher-2539-thread-1) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5792cff{/solr,null,AVAILABLE}
   [junit4]   2> 1222281 INFO  (jetty-launcher-2539-thread-3) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7927e348{/solr,null,AVAILABLE}
   [junit4]   2> 1222283 INFO  (jetty-launcher-2539-thread-1) [    ] o.e.j.s.AbstractConnector Started ServerConnector@5c6f1170{HTTP/1.1,[http/1.1]}{127.0.0.1:62408}
   [junit4]   2> 1222283 INFO  (jetty-launcher-2539-thread-1) [    ] o.e.j.s.Server Started @1230701ms
   [junit4]   2> 1222283 INFO  (jetty-launcher-2539-thread-1) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=62408}
   [junit4]   2> 1222283 ERROR (jetty-launcher-2539-thread-1) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1222283 INFO  (jetty-launcher-2539-thread-3) [    ] o.e.j.s.AbstractConnector Started ServerConnector@cc06df7{HTTP/1.1,[http/1.1]}{127.0.0.1:62411}
   [junit4]   2> 1222283 INFO  (jetty-launcher-2539-thread-3) [    ] o.e.j.s.Server Started @1230701ms
   [junit4]   2> 1222283 INFO  (jetty-launcher-2539-thread-3) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=62411}
   [junit4]   2> 1222283 ERROR (jetty-launcher-2539-thread-3) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1222283 INFO  (jetty-launcher-2539-thread-3) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 7.1.0
   [junit4]   2> 1222283 INFO  (jetty-launcher-2539-thread-1) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 7.1.0
   [junit4]   2> 1222283 INFO  (jetty-launcher-2539-thread-3) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1222283 INFO  (jetty-launcher-2539-thread-1) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1222283 INFO  (jetty-launcher-2539-thread-3) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config dir: null
   [junit4]   2> 1222283 INFO  (jetty-launcher-2539-thread-3) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-10-02T08:46:58.443Z
   [junit4]   2> 1222283 INFO  (jetty-launcher-2539-thread-4) [    ] o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 1222283 INFO  (jetty-launcher-2539-thread-1) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config dir: null
   [junit4]   2> 1222284 INFO  (jetty-launcher-2539-thread-4) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3cabce6{/solr,null,AVAILABLE}
   [junit4]   2> 1222284 INFO  (jetty-launcher-2539-thread-1) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-10-02T08:46:58.444Z
   [junit4]   2> 1222285 INFO  (jetty-launcher-2539-thread-4) [    ] o.e.j.s.AbstractConnector Started ServerConnector@5ca77958{HTTP/1.1,[http/1.1]}{127.0.0.1:62414}
   [junit4]   2> 1222285 INFO  (jetty-launcher-2539-thread-4) [    ] o.e.j.s.Server Started @1230703ms
   [junit4]   2> 1222285 INFO  (jetty-launcher-2539-thread-4) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=62414}
   [junit4]   2> 1222285 ERROR (jetty-launcher-2539-thread-4) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1222285 INFO  (jetty-launcher-2539-thread-4) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 7.1.0
   [junit4]   2> 1222285 INFO  (jetty-launcher-2539-thread-4) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1222285 INFO  (jetty-launcher-2539-thread-4) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config dir: null
   [junit4]   2> 1222285 INFO  (jetty-launcher-2539-thread-4) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-10-02T08:46:58.445Z
   [junit4]   2> 1222286 INFO  (jetty-launcher-2539-thread-2) [    ] o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 1222286 INFO  (jetty-launcher-2539-thread-2) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6047d3ac{/solr,null,AVAILABLE}
   [junit4]   2> 1222287 INFO  (jetty-launcher-2539-thread-2) [    ] o.e.j.s.AbstractConnector Started ServerConnector@478c4d97{HTTP/1.1,[http/1.1]}{127.0.0.1:62417}
   [junit4]   2> 1222287 INFO  (jetty-launcher-2539-thread-2) [    ] o.e.j.s.Server Started @1230706ms
   [junit4]   2> 1222287 INFO  (jetty-launcher-2539-thread-2) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=62417}
   [junit4]   2> 1222287 ERROR (jetty-launcher-2539-thread-2) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1222287 INFO  (jetty-launcher-2539-thread-2) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 7.1.0
   [junit4]   2> 1222287 INFO  (jetty-launcher-2539-thread-2) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1222287 INFO  (jetty-launcher-2539-thread-2) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config dir: null
   [junit4]   2> 1222287 INFO  (jetty-launcher-2539-thread-2) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-10-02T08:46:58.447Z
   [junit4]   2> 1222315 INFO  (jetty-launcher-2539-thread-4) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1222315 INFO  (jetty-launcher-2539-thread-2) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1222318 INFO  (jetty-launcher-2539-thread-3) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1222318 INFO  (jetty-launcher-2539-thread-1) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1222333 INFO  (jetty-launcher-2539-thread-4) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62404/solr
   [junit4]   2> 1222334 INFO  (jetty-launcher-2539-thread-1) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62404/solr
   [junit4]   2> 1222336 INFO  (jetty-launcher-2539-thread-2) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62404/solr
   [junit4]   2> 1222342 INFO  (jetty-launcher-2539-thread-3) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62404/solr
   [junit4]   2> 1222345 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 0x15edc44510e0006, 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> 1222347 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 0x15edc44510e0007, 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> 1222349 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 0x15edc44510e0008, 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> 1222501 INFO  (jetty-launcher-2539-thread-3) [n:127.0.0.1:62411_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1222501 INFO  (jetty-launcher-2539-thread-1) [n:127.0.0.1:62408_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1222502 INFO  (jetty-launcher-2539-thread-4) [n:127.0.0.1:62414_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1222502 INFO  (jetty-launcher-2539-thread-3) [n:127.0.0.1:62411_solr    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:62411_solr
   [junit4]   2> 1222503 INFO  (jetty-launcher-2539-thread-1) [n:127.0.0.1:62408_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:62408_solr
   [junit4]   2> 1222503 INFO  (jetty-launcher-2539-thread-3) [n:127.0.0.1:62411_solr    ] o.a.s.c.Overseer Overseer (id=98758427824488460-127.0.0.1:62411_solr-n_0000000000) starting
   [junit4]   2> 1222504 INFO  (jetty-launcher-2539-thread-4) [n:127.0.0.1:62414_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:62414_solr
   [junit4]   2> 1222506 INFO  (zkCallback-2561-thread-1-processing-n:127.0.0.1:62411_solr) [n:127.0.0.1:62411_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1222506 INFO  (zkCallback-2559-thread-1-processing-n:127.0.0.1:62408_solr) [n:127.0.0.1:62408_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1222507 INFO  (zkCallback-2558-thread-1-processing-n:127.0.0.1:62414_solr) [n:127.0.0.1:62414_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1222511 INFO  (zkCallback-2561-thread-1-processing-n:127.0.0.1:62411_solr) [n:127.0.0.1:62411_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1222511 INFO  (zkCallback-2559-thread-1-processing-n:127.0.0.1:62408_solr) [n:127.0.0.1:62408_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1222511 INFO  (zkCallback-2558-thread-1-processing-n:127.0.0.1:62414_solr) [n:127.0.0.1:62414_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1222521 INFO  (jetty-launcher-2539-thread-2) [n:127.0.0.1:62417_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1222526 INFO  (jetty-launcher-2539-thread-3) [n:127.0.0.1:62411_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:62411_solr
   [junit4]   2> 1222582 INFO  (jetty-launcher-2539-thread-2) [n:127.0.0.1:62417_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1222595 INFO  (zkCallback-2558-thread-1-processing-n:127.0.0.1:62414_solr) [n:127.0.0.1:62414_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1222595 INFO  (zkCallback-2561-thread-1-processing-n:127.0.0.1:62411_solr) [n:127.0.0.1:62411_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1222595 INFO  (zkCallback-2559-thread-1-processing-n:127.0.0.1:62408_solr) [n:127.0.0.1:62408_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1222595 INFO  (zkCallback-2560-thread-1-processing-n:127.0.0.1:62417_solr) [n:127.0.0.1:62417_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1222600 INFO  (jetty-launcher-2539-thread-2) [n:127.0.0.1:62417_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:62417_solr
   [junit4]   2> 1222608 INFO  (zkCallback-2558-thread-1-processing-n:127.0.0.1:62414_solr) [n:127.0.0.1:62414_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1222608 INFO  (zkCallback-2561-thread-1-processing-n:127.0.0.1:62411_solr) [n:127.0.0.1:62411_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1222614 INFO  (zkCallback-2560-thread-1-processing-n:127.0.0.1:62417_solr) [n:127.0.0.1:62417_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1222619 INFO  (zkCallback-2559-thread-1-processing-n:127.0.0.1:62408_solr) [n:127.0.0.1:62408_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1222651 INFO  (jetty-launcher-2539-thread-3) [n:127.0.0.1:62411_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4380cb1f
   [junit4]   2> 1222658 INFO  (jetty-launcher-2539-thread-3) [n:127.0.0.1:62411_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4380cb1f
   [junit4]   2> 1222658 INFO  (jetty-launcher-2539-thread-3) [n:127.0.0.1:62411_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4380cb1f
   [junit4]   2> 1222659 INFO  (jetty-launcher-2539-thread-3) [n:127.0.0.1:62411_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CollectionsAPIDistributedZkTest_B36F42A0BB758B11-001\tempDir-001\node3
   [junit4]   2> 1222683 INFO  (jetty-launcher-2539-thread-1) [n:127.0.0.1:62408_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4380cb1f
   [junit4]   2> 1222690 INFO  (jetty-launcher-2539-thread-1) [n:127.0.0.1:62408_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4380cb1f
   [junit4]   2> 1222690 INFO  (jetty-launcher-2539-thread-1) [n:127.0.0.1:62408_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4380cb1f
   [junit4]   2> 1222692 INFO  (jetty-launcher-2539-thread-1) [n:127.0.0.1:62408_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CollectionsAPIDistributedZkTest_B36F42A0BB758B11-001\tempDir-001\node1
   [junit4]   2> 1222759 INFO  (jetty-launcher-2539-thread-4) [n:127.0.0.1:62414_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4380cb1f
   [junit4]   2> 1222768 WARN  (jetty-launcher-2539-thread-4) [n:127.0.0.1:62414_solr    ] o.a.s.m.r.j.JmxMetricsReporter Unable to register gauge
   [junit4]   2> javax.management.InstanceNotFoundException: solr:dom1=jvm,name0=memory,name1=pools,name2=PS-Eden-Space,name=init
   [junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1095)
   [junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:427)
   [junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:415)
   [junit4]   2> 	at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:546)
   [junit4]   2> 	at org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter$JmxListener.registerMBean(JmxMetricsReporter.java:530)
   [junit4]   2> 	at org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter$JmxListener.onGaugeAdded(JmxMetricsReporter.java:565)
   [junit4]   2> 	at org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter.lambda$start$0(JmxMetricsReporter.java:738)
   [junit4]   2> 	at java.util.HashMap.forEach(HashMap.java:1288)
   [junit4]   2> 	at org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter.start(JmxMetricsReporter.java:728)
   [junit4]   2> 	at org.apache.solr.metrics.reporters.SolrJmxReporter.doInit(SolrJmxReporter.java:109)
   [junit4]   2> 	at org.apache.solr.metrics.SolrMetricReporter.init(SolrMetricReporter.java:70)
   [junit4]   2> 	at org.apache.solr.metrics.SolrMetricManager.loadReporter(SolrMetricManager.java:849)
   [junit4]   2> 	at org.apache.solr.metrics.SolrMetricManager.loadReporters(SolrMetricManager.java:817)
   [junit4]   2> 	at org.apache.solr.core.CoreContainer.load(CoreContainer.java:544)
   [junit4]   2> 	at org.apache.solr.servlet.SolrDispatchFilter.createCoreContainer(SolrDispatchFilter.java:263)
   [junit4]   2> 	at org.apache.solr.servlet.SolrDispatchFilter.init(SolrDispatchFilter.java:183)
   [junit4]   2> 	at org.eclipse.jetty.servlet.FilterHolder.initialize(FilterHolder.java:139)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler.initialize(ServletHandler.java:873)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler.updateMappings(ServletHandler.java:1596)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler.setFilterMappings(ServletHandler.java:1659)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler.addFilterMapping(ServletHandler.java:1316)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler.addFilterWithMapping(ServletHandler.java:1145)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletContextHandler.addFilter(ServletContextHandler.java:448)
   [junit4]   2> 	at org.apache.solr.client.solrj.embedded.JettySolrRunner$1.lifeCycleStarted(JettySolrRunner.java:306)
   [junit4]   2> 	at org.eclipse.jetty.util.component.AbstractLifeCycle.setStarted(AbstractLifeCycle.java:179)
   [junit4]   2> 	at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:69)
   [junit4]   2> 	at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:394)
   [junit4]   2> 	at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:367)
   [junit4]   2> 	at org.apache.solr.cloud.MiniSolrCloudCluster.startJettySolrRunner(MiniSolrCloudCluster.java:384)
   [junit4]   2> 	at org.apache.solr.cloud.MiniSolrCloudCluster.lambda$new$0(MiniSolrCloudCluster.java:247)
   [junit4]   2> 	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4]   2> 	at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 1222772 INFO  (jetty-launcher-2539-thread-2) [n:127.0.0.1:62417_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4380cb1f
   [junit4]   2> 1222777 INFO  (jetty-launcher-2539-thread-4) [n:127.0.0.1:62414_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4380cb1f
   [junit4]   2> 1222778 INFO  (jetty-launcher-2539-thread-4) [n:127.0.0.1:62414_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4380cb1f
   [junit4]   2> 1222780 INFO  (jetty-launcher-2539-thread-4) [n:127.0.0.1:62414_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CollectionsAPIDistributedZkTest_B36F42A0BB758B11-001\tempDir-001\node2
   [junit4]   2> 1222788 INFO  (jetty-launcher-2539-thread-2) [n:127.0.0.1:62417_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4380cb1f
   [junit4]   2> 1222788 INFO  (jetty-launcher-2539-thread-2) [n:127.0.0.1:62417_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4380cb1f
   [junit4]   2> 1222790 INFO  (jetty-launcher-2539-thread-2) [n:127.0.0.1:62417_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CollectionsAPIDistributedZkTest_B36F42A0BB758B11-001\tempDir-001\node4
   [junit4]   2> 1222916 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 0x15edc44510e000d, 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> 1222928 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[B36F42A0BB758B11]-worker) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1222930 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[B36F42A0BB758B11]-worker) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:62404/solr ready
   [junit4]   2> 1222999 INFO  (TEST-CollectionsAPIDistributedZkTest.testTooManyReplicas-seed#[B36F42A0BB758B11]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testTooManyReplicas
   [junit4]   2> 1223001 INFO  (TEST-CollectionsAPIDistributedZkTest.testTooManyReplicas-seed#[B36F42A0BB758B11]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1223010 INFO  (qtp1576490968-13577) [n:127.0.0.1:62411_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=10&collection.configName=conf&name=collection&nrtReplicas=10&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1223027 INFO  (OverseerThreadFactory-4672-thread-1-processing-n:127.0.0.1:62411_solr) [n:127.0.0.1:62411_solr    ] o.a.s.c.CreateCollectionCmd Create collection collection
   [junit4]   2> 1223027 WARN  (OverseerThreadFactory-4672-thread-1-processing-n:127.0.0.1:62411_solr) [n:127.0.0.1:62411_solr    ] o.a.s.c.CreateCollectionCmd Specified number of replicas of 10 on collection collection 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> 1223028 ERROR (OverseerThreadFactory-4672-thread-1-processing-n:127.0.0.1:62411_solr) [n:127.0.0.1:62411_solr    ] o.a.s.c.OverseerCollectionMessageHandler Collection: collection operation: create failed:org.apache.solr.common.SolrException: Cannot create collection collection. Value of maxShardsPerNode is 1, and the number of nodes currently live or live and part of your createNodeSet is 4. This allows a maximum of 4 to be created. Value of numShards is 2, value of nrtReplicas is 10, value of tlogReplicas is 0 and value of pullReplicas is 0. This requires 20 shards to be created (higher than the allowed number)
   [junit4]   2> 	at org.apache.solr.cloud.CreateCollectionCmd.call(CreateCollectionCmd.java:164)
   [junit4]   2> 	at org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:228)
   [junit4]   2> 	at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:464)
   [junit4]   2> 	at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 
   [junit4]   2> 1223034 INFO  (qtp1576490968-13577) [n:127.0.0.1:62411_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=10&collection.configName=conf&name=collection&nrtReplicas=10&action=CREATE&numShards=2&wt=javabin&version=2} status=400 QTime=23
   [junit4]   2> 1223036 INFO  (TEST-CollectionsAPIDistributedZkTest.testTooManyReplicas-seed#[B36F42A0BB758B11]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testTooManyReplicas
   [junit4]   2> 1223080 INFO  (TEST-CollectionsAPIDistributedZkTest.deletePartiallyCreatedCollection-seed#[B36F42A0BB758B11]) [    ] o.a.s.SolrTestCaseJ4 ###Starting deletePartiallyCreatedCollection
   [junit4]   2> 1223082 INFO  (TEST-CollectionsAPIDistributedZkTest.deletePartiallyCreatedCollection-seed#[B36F42A0BB758B11]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1223084 INFO  (qtp1576490968-13574) [n:127.0.0.1:62411_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf&name=halfdeletedcollection&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1223088 INFO  (OverseerCollectionConfigSetProcessor-98758427824488460-127.0.0.1:62411_solr-n_0000000000) [n:127.0.0.1:62411_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> 1223090 INFO  (OverseerThreadFactory-4672-thread-2-processing-n:127.0.0.1:62411_solr) [n:127.0.0.1:62411_solr    ] o.a.s.c.CreateCollectionCmd Create collection halfdeletedcollection
   [junit4]   2> 1223090 WARN  (OverseerThreadFactory-4672-thread-2-processing-n:127.0.0.1:62411_solr) [n:127.0.0.1:62411_solr    ] o.a.s.c.CreateCollectionCmd It is unusual to create a collection (halfdeletedcollection) without cores.
   [junit4]   2> 1223201 INFO  (qtp1576490968-13574) [n:127.0.0.1:62411_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> 1223202 INFO  (qtp1576490968-13574) [n:127.0.0.1:62411_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf&name=halfdeletedcollection&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&wt=javabin&version=2} status=0 QTime=117
   [junit4]   2> 1223205 INFO  (qtp1576490968-13573) [n:127.0.0.1:62411_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params dataDir=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CollectionsAPIDistributedZkTest_B36F42A0BB758B11-001\tempDir-002&action=ADDREPLICA&collection=halfdeletedcollection&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1223208 INFO  (OverseerCollectionConfigSetProcessor-98758427824488460-127.0.0.1:62411_solr-n_0000000000) [n:127.0.0.1:62411_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> 1223210 INFO  (OverseerThreadFactory-4672-thread-3-processing-n:127.0.0.1:62411_solr) [n:127.0.0.1:62411_solr    ] o.a.s.c.AddReplicaCmd Node Identified 127.0.0.1:62408_solr for creating new replica
   [junit4]   2> 1223213 INFO  (OverseerStateUpdate-98758427824488460-127.0.0.1:62411_solr-n_0000000000) [n:127.0.0.1:62411_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"addreplica",
   [junit4]   2>   "collection":"halfdeletedcollection",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"halfdeletedcollection_shard1_replica_n41",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:62408/solr",
   [junit4]   2>   "node_name":"127.0.0.1:62408_solr",
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1223419 INFO  (qtp1406728332-13605) [n:127.0.0.1:62408_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node42&dataDir=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CollectionsAPIDistributedZkTest_B36F42A0BB758B11-001\tempDir-002&collection.configName=conf&name=halfdeletedcollection_shard1_replica_n41&action=CREATE&collection=halfdeletedcollection&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1223420 INFO  (qtp1406728332-13605) [n:127.0.0.1:62408_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 1223446 INFO  (qtp1406728332-13605) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard1 r:core_node42 x:halfdeletedcollection_shard1_replica_n41] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 1223464 INFO  (qtp1406728332-13605) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard1 r:core_node42 x:halfdeletedcollection_shard1_replica_n41] o.a.s.s.IndexSchema [halfdeletedcollection_shard1_replica_n41] Schema name=minimal
   [junit4]   2> 1223468 INFO  (qtp1406728332-13605) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard1 r:core_node42 x:halfdeletedcollection_shard1_replica_n41] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1223468 INFO  (qtp1406728332-13605) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard1 r:core_node42 x:halfdeletedcollection_shard1_replica_n41] o.a.s.c.CoreContainer Creating SolrCore 'halfdeletedcollection_shard1_replica_n41' using configuration from collection halfdeletedcollection, trusted=true
   [junit4]   2> 1223469 INFO  (qtp1406728332-13605) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard1 r:core_node42 x:halfdeletedcollection_shard1_replica_n41] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.halfdeletedcollection.shard1.replica_n41' (registry 'solr.core.halfdeletedcollection.shard1.replica_n41') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4380cb1f
   [junit4]   2> 1223469 INFO  (qtp1406728332-13605) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard1 r:core_node42 x:halfdeletedcollection_shard1_replica_n41] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1223469 INFO  (qtp1406728332-13605) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard1 r:core_node42 x:halfdeletedcollection_shard1_replica_n41] o.a.s.c.SolrCore [[halfdeletedcollection_shard1_replica_n41] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CollectionsAPIDistributedZkTest_B36F42A0BB758B11-001\tempDir-001\node1\halfdeletedcollection_shard1_replica_n41], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CollectionsAPIDistributedZkTest_B36F42A0BB758B11-001\tempDir-002\]
   [junit4]   2> 1223530 INFO  (zkCallback-2559-thread-1-processing-n:127.0.0.1:62408_solr) [n:127.0.0.1:62408_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfdeletedcollection/state.json] for collection [halfdeletedcollection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1223530 INFO  (zkCallback-2559-thread-2-processing-n:127.0.0.1:62408_solr) [n:127.0.0.1:62408_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfdeletedcollection/state.json] for collection [halfdeletedcollection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1223571 INFO  (qtp1406728332-13605) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard1 r:core_node42 x:halfdeletedcollection_shard1_replica_n41] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1223571 INFO  (qtp1406728332-13605) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard1 r:core_node42 x:halfdeletedcollection_shard1_replica_n41] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1223572 INFO  (qtp1406728332-13605) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard1 r:core_node42 x:halfdeletedcollection_shard1_replica_n41] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1223572 INFO  (qtp1406728332-13605) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard1 r:core_node42 x:halfdeletedcollection_shard1_replica_n41] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1223574 INFO  (qtp1406728332-13605) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard1 r:core_node42 x:halfdeletedcollection_shard1_replica_n41] o.a.s.s.SolrIndexSearcher Opening [Searcher@77f928e6[halfdeletedcollection_shard1_replica_n41] main]
   [junit4]   2> 1223594 INFO  (qtp1406728332-13605) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard1 r:core_node42 x:halfdeletedcollection_shard1_replica_n41] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1223594 INFO  (qtp1406728332-13605) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard1 r:core_node42 x:halfdeletedcollection_shard1_replica_n41] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1223594 INFO  (qtp1406728332-13605) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard1 r:core_node42 x:halfdeletedcollection_shard1_replica_n41] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1223596 INFO  (searcherExecutor-4681-thread-1-processing-n:127.0.0.1:62408_solr x:halfdeletedcollection_shard1_replica_n41 s:shard1 c:halfdeletedcollection r:core_node42) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard1 r:core_node42 x:halfdeletedcollection_shard1_replica_n41] o.a.s.c.SolrCore [halfdeletedcollection_shard1_replica_n41] Registered new searcher Searcher@77f928e6[halfdeletedcollection_shard1_replica_n41] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1223597 INFO  (qtp1406728332-13605) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard1 r:core_node42 x:halfdeletedcollection_shard1_replica_n41] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1580134846700716032
   [junit4]   2> 1223612 INFO  (qtp1406728332-13605) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard1 r:core_node42 x:halfdeletedcollection_shard1_replica_n41] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1223612 INFO  (qtp1406728332-13605) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard1 r:core_node42 x:halfdeletedcollection_shard1_replica_n41] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1223612 INFO  (qtp1406728332-13605) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard1 r:core_node42 x:halfdeletedcollection_shard1_replica_n41] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:62408/solr/halfdeletedcollection_shard1_replica_n41/
   [junit4]   2> 1223612 INFO  (qtp1406728332-13605) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard1 r:core_node42 x:halfdeletedcollection_shard1_replica_n41] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 1223612 INFO  (qtp1406728332-13605) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard1 r:core_node42 x:halfdeletedcollection_shard1_replica_n41] o.a.s.c.SyncStrategy http://127.0.0.1:62408/solr/halfdeletedcollection_shard1_replica_n41/ has no replicas
   [junit4]   2> 1223612 INFO  (qtp1406728332-13605) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard1 r:core_node42 x:halfdeletedcollection_shard1_replica_n41] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
   [junit4]   2> 1223619 INFO  (qtp1406728332-13605) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard1 r:core_node42 x:halfdeletedcollection_shard1_replica_n41] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:62408/solr/halfdeletedcollection_shard1_replica_n41/ shard1
   [junit4]   2> 1223734 INFO  (zkCallback-2559-thread-2-processing-n:127.0.0.1:62408_solr) [n:127.0.0.1:62408_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfdeletedcollection/state.json] for collection [halfdeletedcollection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1223734 INFO  (zkCallback-2559-thread-1-processing-n:127.0.0.1:62408_solr) [n:127.0.0.1:62408_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfdeletedcollection/state.json] for collection [halfdeletedcollection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1223774 INFO  (qtp1406728332-13605) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard1 r:core_node42 x:halfdeletedcollection_shard1_replica_n41] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1223775 INFO  (qtp1406728332-13605) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard1 r:core_node42 x:halfdeletedcollection_shard1_replica_n41] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node42&dataDir=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CollectionsAPIDistributedZkTest_B36F42A0BB758B11-001\tempDir-002&collection.configName=conf&name=halfdeletedcollection_shard1_replica_n41&action=CREATE&collection=halfdeletedcollection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=356
   [junit4]   2> 1223781 INFO  (qtp1576490968-13573) [n:127.0.0.1:62411_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={dataDir=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CollectionsAPIDistributedZkTest_B36F42A0BB758B11-001\tempDir-002&action=ADDREPLICA&collection=halfdeletedcollection&shard=shard1&type=NRT&wt=javabin&version=2} status=0 QTime=576
   [junit4]   2> 1223782 INFO  (qtp1576490968-13572) [n:127.0.0.1:62411_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=halfdeletedcollection&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1223792 INFO  (OverseerThreadFactory-4672-thread-4-processing-n:127.0.0.1:62411_solr) [n:127.0.0.1:62411_solr    ] o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
   [junit4]   2> 1223792 INFO  (qtp1406728332-13568) [n:127.0.0.1:62408_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.halfdeletedcollection.shard1.replica_n41, tag=null
   [junit4]   2> 1223792 INFO  (qtp1406728332-13568) [n:127.0.0.1:62408_solr    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@43360a1: rootName = null, domain = solr.core.halfdeletedcollection.shard1.replica_n41, service url = null, agent id = null] for registry solr.core.halfdeletedcollection.shard1.replica_n41 / com.codahale.metrics.MetricRegistry@454484d2
   [junit4]   2> 1223798 INFO  (OverseerCollectionConfigSetProcessor-98758427824488460-127.0.0.1:62411_solr-n_0000000000) [n:127.0.0.1:62411_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> 1223805 INFO  (qtp1406728332-13568) [n:127.0.0.1:62408_solr    ] o.a.s.c.SolrCore [halfdeletedcollection_shard1_replica_n41]  CLOSING SolrCore org.apache.solr.core.SolrCore@a5df0d6
   [junit4]   2> 1223805 INFO  (qtp1406728332-13568) [n:127.0.0.1:62408_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.halfdeletedcollection.shard1.replica_n41, tag=173928662
   [junit4]   2> 1223805 INFO  (qtp1406728332-13568) [n:127.0.0.1:62408_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.halfdeletedcollection.shard1.leader, tag=173928662
   [junit4]   2> 1223820 INFO  (qtp1406728332-13568) [n:127.0.0.1:62408_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=halfdeletedcollection_shard1_replica_n41&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=27
   [junit4]   2> 1224532 INFO  (qtp1576490968-13572) [n:127.0.0.1:62411_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=halfdeletedcollection&action=DELETE&wt=javabin&version=2} status=0 QTime=749
   [junit4]   2> 1224532 INFO  (qtp1576490968-13571) [n:127.0.0.1:62411_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1224532 INFO  (qtp1576490968-13571) [n:127.0.0.1:62411_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1224533 INFO  (qtp1576490968-13571) [n:127.0.0.1:62411_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf&name=halfdeletedcollection&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1224536 INFO  (OverseerCollectionConfigSetProcessor-98758427824488460-127.0.0.1:62411_solr-n_0000000000) [n:127.0.0.1:62411_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000006 doesn't exist.  Requestor may have disconnected from ZooKeeper
   [junit4]   2> 1224536 INFO  (OverseerThreadFactory-4672-thread-5-processing-n:127.0.0.1:62411_solr) [n:127.0.0.1:62411_solr    ] o.a.s.c.CreateCollectionCmd Create collection halfdeletedcollection
   [junit4]   2> 1224747 INFO  (OverseerStateUpdate-98758427824488460-127.0.0.1:62411_solr-n_0000000000) [n:127.0.0.1:62411_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"halfdeletedcollection",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"halfdeletedcollection_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:62417/solr",
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1224751 INFO  (OverseerStateUpdate-98758427824488460-127.0.0.1:62411_solr-n_0000000000) [n:127.0.0.1:62411_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"halfdeletedcollection",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"halfdeletedcollection_shard2_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:62408/solr",
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1224953 INFO  (qtp1406728332-13567) [n:127.0.0.1:62408_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=halfdeletedcollection_shard2_replica_n2&action=CREATE&numShards=2&collection=halfdeletedcollection&shard=shard2&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1224953 INFO  (qtp1858356534-13592) [n:127.0.0.1:62417_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=halfdeletedcollection_shard1_replica_n1&action=CREATE&numShards=2&collection=halfdeletedcollection&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1224957 INFO  (qtp1858356534-13592) [n:127.0.0.1:62417_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 1225072 INFO  (zkCallback-2559-thread-2-processing-n:127.0.0.1:62408_solr) [n:127.0.0.1:62408_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfdeletedcollection/state.json] for collection [halfdeletedcollection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1225072 INFO  (zkCallback-2560-thread-1-processing-n:127.0.0.1:62417_solr) [n:127.0.0.1:62417_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfdeletedcollection/state.json] for collection [halfdeletedcollection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1225072 INFO  (zkCallback-2559-thread-1-processing-n:127.0.0.1:62408_solr) [n:127.0.0.1:62408_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfdeletedcollection/state.json] for collection [halfdeletedcollection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1225074 INFO  (zkCallback-2560-thread-2-processing-n:127.0.0.1:62417_solr) [n:127.0.0.1:62417_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfdeletedcollection/state.json] for collection [halfdeletedcollection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1226003 INFO  (qtp1406728332-13567) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard2 r:core_node4 x:halfdeletedcollection_shard2_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 1226003 INFO  (qtp1858356534-13592) [n:127.0.0.1:62417_solr c:halfdeletedcollection s:shard1 r:core_node3 x:halfdeletedcollection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 1226009 INFO  (qtp1858356534-13592) [n:127.0.0.1:62417_solr c:halfdeletedcollection s:shard1 r:core_node3 x:halfdeletedcollection_shard1_replica_n1] o.a.s.s.IndexSchema [halfdeletedcollection_shard1_replica_n1] Schema name=minimal
   [junit4]   2> 1226009 INFO  (qtp1406728332-13567) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard2 r:core_node4 x:halfdeletedcollection_shard2_replica_n2] o.a.s.s.IndexSchema [halfdeletedcollection_shard2_replica_n2] Schema name=minimal
   [junit4]   2> 1226016 INFO  (qtp1406728332-13567) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard2 r:core_node4 x:halfdeletedcollection_shard2_replica_n2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1226016 INFO  (qtp1858356534-13592) [n:127.0.0.1:62417_solr c:halfdeletedcollection s:shard1 r:core_node3 x:halfdeletedcollection_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1226016 INFO  (qtp1858356534-13592) [n:127.0.0.1:62417_solr c:halfdeletedcollection s:shard1 r:core_node3 x:halfdeletedcollection_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'halfdeletedcollection_shard1_replica_n1' using configuration from collection halfdeletedcollection, trusted=true
   [junit4]   2> 1226016 INFO  (qtp1406728332-13567) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard2 r:core_node4 x:halfdeletedcollection_shard2_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'halfdeletedcollection_shard2_replica_n2' using configuration from collection halfdeletedcollection, trusted=true
   [junit4]   2> 1226017 INFO  (qtp1406728332-13567) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard2 r:core_node4 x:halfdeletedcollection_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.halfdeletedcollection.shard2.replica_n2' (registry 'solr.core.halfdeletedcollection.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4380cb1f
   [junit4]   2> 1226017 INFO  (qtp1858356534-13592) [n:127.0.0.1:62417_solr c:halfdeletedcollection s:shard1 r:core_node3 x:halfdeletedcollection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.halfdeletedcollection.shard1.replica_n1' (registry 'solr.core.halfdeletedcollection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4380cb1f
   [junit4]   2> 1226017 INFO  (qtp1406728332-13567) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard2 r:core_node4 x:halfdeletedcollection_shard2_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1226017 INFO  (qtp1858356534-13592) [n:127.0.0.1:62417_solr c:halfdeletedcollection s:shard1 r:core_node3 x:halfdeletedcollection_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1226017 INFO  (qtp1406728332-13567) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard2 r:core_node4 x:halfdeletedcollection_shard2_replica_n2] o.a.s.c.SolrCore [[halfdeletedcollection_shard2_replica_n2] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CollectionsAPIDistributedZkTest_B36F42A0BB758B11-001\tempDir-001\node1\halfdeletedcollection_shard2_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CollectionsAPIDistributedZkTest_B36F42A0BB758B11-001\tempDir-001\node1\halfdeletedcollection_shard2_replica_n2\data\]
   [junit4]   2> 1226017 INFO  (qtp1858356534-13592) [n:127.0.0.1:62417_solr c:halfdeletedcollection s:shard1 r:core_node3 x:halfdeletedcollection_shard1_replica_n1] o.a.s.c.SolrCore [[halfdeletedcollection_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CollectionsAPIDistributedZkTest_B36F42A0BB758B11-001\tempDir-001\node4\halfdeletedcollection_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CollectionsAPIDistributedZkTest_B36F42A0BB758B11-001\tempDir-001\node4\halfdeletedcollection_shard1_replica_n1\data\]
   [junit4]   2> 1226159 INFO  (qtp1858356534-13592) [n:127.0.0.1:62417_solr c:halfdeletedcollection s:shard1 r:core_node3 x:halfdeletedcollection_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1226159 INFO  (qtp1858356534-13592) [n:127.0.0.1:62417_solr c:halfdeletedcollection s:shard1 r:core_node3 x:halfdeletedcollection_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1226161 INFO  (qtp1858356534-13592) [n:127.0.0.1:62417_solr c:halfdeletedcollection s:shard1 r:core_node3 x:halfdeletedcollection_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1226161 INFO  (qtp1858356534-13592) [n:127.0.0.1:62417_solr c:halfdeletedcollection s:shard1 r:core_node3 x:halfdeletedcollection_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1226161 INFO  (qtp1406728332-13567) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard2 r:core_node4 x:halfdeletedcollection_shard2_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1226161 INFO  (qtp1406728332-13567) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard2 r:core_node4 x:halfdeletedcollection_shard2_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1226184 INFO  (qtp1406728332-13567) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard2 r:core_node4 x:halfdeletedcollection_shard2_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1226184 INFO  (qtp1406728332-13567) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard2 r:core_node4 x:halfdeletedcollection_shard2_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1226186 INFO  (qtp1858356534-13592) [n:127.0.0.1:62417_solr c:halfdeletedcollection s:shard1 r:core_node3 x:halfdeletedcollection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@64b28090[halfdeletedcollection_shard1_replica_n1] main]
   [junit4]   2> 1226187 INFO  (qtp1858356534-13592) [n:127.0.0.1:62417_solr c:halfdeletedcollection s:shard1 r:core_node3 x:halfdeletedcollection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1226187 INFO  (qtp1406728332-13567) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard2 r:core_node4 x:halfdeletedcollection_shard2_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@5cae67d7[halfdeletedcollection_shard2_replica_n2] main]
   [junit4]   2> 1226189 INFO  (qtp1858356534-13592) [n:127.0.0.1:62417_solr c:halfdeletedcollection s:shard1 r:core_node3 x:halfdeletedcollection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1226189 INFO  (qtp1858356534-13592) [n:127.0.0.1:62417_solr c:halfdeletedcollection s:shard1 r:core_node3 x:halfdeletedcollection_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1226190 INFO  (qtp1406728332-13567) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard2 r:core_node4 x:halfdeletedcollection_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1226190 INFO  (searcherExecutor-4686-thread-1-processing-n:127.0.0.1:62417_solr x:halfdeletedcollection_shard1_replica_n1 s:shard1 c:halfdeletedcollection r:core_node3) [n:127.0.0.1:62417_solr c:halfdeletedcollection s:shard1 r:core_node3 x:halfdeletedcollection_shard1_replica_n1] o.a.s.c.SolrCore [halfdeletedcollection_shard1_replica_n1] Registered new searcher Searcher@64b28090[halfdeletedcollection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1226190 INFO  (qtp1858356534-13592) [n:127.0.0.1:62417_solr c:halfdeletedcollection s:shard1 r:core_node3 x:halfdeletedcollection_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1580134849419673600
   [junit4]   2> 1226191 INFO  (qtp1406728332-13567) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard2 r:core_node4 x:halfdeletedcollection_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1226191 INFO  (qtp1406728332-13567) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard2 r:core_node4 x:halfdeletedcollection_shard2_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1226192 INFO  (searcherExecutor-4687-thread-1-processing-n:127.0.0.1:62408_solr x:halfdeletedcollection_shard2_replica_n2 s:shard2 c:halfdeletedcollection r:core_node4) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard2 r:core_node4 x:halfdeletedcollection_shard2_replica_n2] o.a.s.c.SolrCore [halfdeletedcollection_shard2_replica_n2] Registered new searcher Searcher@5cae67d7[halfdeletedcollection_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1226192 INFO  (qtp1406728332-13567) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard2 r:core_node4 x:halfdeletedcollection_shard2_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1580134849421770752
   [junit4]   2> 1226196 INFO  (qtp1858356534-13592) [n:127.0.0.1:62417_solr c:halfdeletedcollection s:shard1 r:core_node3 x:halfdeletedcollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1226196 INFO  (qtp1858356534-13592) [n:127.0.0.1:62417_solr c:halfdeletedcollection s:shard1 r:core_node3 x:halfdeletedcollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1226196 INFO  (qtp1858356534-13592) [n:127.0.0.1:62417_solr c:halfdeletedcollection s:shard1 r:core_node3 x:halfdeletedcollection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:62417/solr/halfdeletedcollection_shard1_replica_n1/
   [junit4]   2> 1226196 INFO  (qtp1858356534-13592) [n:127.0.0.1:62417_solr c:halfdeletedcollection s:shard1 r:core_node3 x:halfdeletedcollection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 1226196 INFO  (qtp1858356534-13592) [n:127.0.0.1:62417_solr c:halfdeletedcollection s:shard1 r:core_node3 x:halfdeletedcollection_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:62417/solr/halfdeletedcollection_shard1_replica_n1/ has no replicas
   [junit4]   2> 1226196 INFO  (qtp1858356534-13592) [n:127.0.0.1:62417_solr c:halfdeletedcollection s:shard1 r:core_node3 x:halfdeletedcollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
   [junit4]   2> 1226202 INFO  (qtp1406728332-13567) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard2 r:core_node4 x:halfdeletedcollection_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1226202 INFO  (qtp1406728332-13567) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard2 r:core_node4 x:halfdeletedcollection_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1226202 INFO  (qtp1406728332-13567) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard2 r:core_node4 x:halfdeletedcollection_shard2_replica_n2] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:62408/solr/halfdeletedcollection_shard2_replica_n2/
   [junit4]   2> 1226202 INFO  (qtp1406728332-13567) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard2 r:core_node4 x:halfdeletedcollection_shard2_replica_n2] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 1226202 INFO  (qtp1406728332-13567) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard2 r:core_node4 x:halfdeletedcollection_shard2_replica_n2] o.a.s.c.SyncStrategy http://127.0.0.1:62408/solr/halfdeletedcollection_shard2_replica_n2/ has no replicas
   [junit4]   2> 1226202 INFO  (qtp1406728332-13567) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard2 r:core_node4 x:halfdeletedcollection_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
   [junit4]   2> 1226202 INFO  (qtp1858356534-13592) [n:127.0.0.1:62417_solr c:halfdeletedcollection s:shard1 r:core_node3 x:halfdeletedcollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:62417/solr/halfdeletedcollection_shard1_replica_n1/ shard1
   [junit4]   2> 1226209 INFO  (qtp1406728332-13567) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard2 r:core_node4 x:halfdeletedcollection_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:62408/solr/halfdeletedcollection_shard2_replica_n2/ shard2
   [junit4]   2> 1226313 INFO  (zkCallback-2559-thread-1-processing-n:127.0.0.1:62408_solr) [n:127.0.0.1:62408_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfdeletedcollection/state.json] for collection [halfdeletedcollection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1226313 INFO  (zkCallback-2559-thread-2-processing-n:127.0.0.1:62408_solr) [n:127.0.0.1:62408_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfdeletedcollection/state.json] for collection [halfdeletedcollection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1226313 INFO  (zkCallback-2560-thread-1-processing-n:127.0.0.1:62417_solr) [n:127.0.0.1:62417_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfdeletedcollection/state.json] for collection [halfdeletedcollection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1226313 INFO  (zkCallback-2560-thread-2-processing-n:127.0.0.1:62417_solr) [n:127.0.0.1:62417_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfdeletedcollection/state.json] for collection [halfdeletedcollection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1226357 INFO  (qtp1858356534-13592) [n:127.0.0.1:62417_solr c:halfdeletedcollection s:shard1 r:core_node3 x:halfdeletedcollection_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1226357 INFO  (qtp1858356534-13592) [n:127.0.0.1:62417_solr c:halfdeletedcollection s:shard1 r:core_node3 x:halfdeletedcollection_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=halfdeletedcollection_shard1_replica_n1&action=CREATE&numShards=2&collection=halfdeletedcollection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1403
   [junit4]   2> 1226362 INFO  (qtp1406728332-13567) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard2 r:core_node4 x:halfdeletedcollection_shard2_replica_n2] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1226363 INFO  (qtp1406728332-13567) [n:127.0.0.1:62408_solr c:halfdeletedcollection s:shard2 r:core_node4 x:halfdeletedcollection_shard2_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=halfdeletedcollection_shard2_replica_n2&action=CREATE&numShards=2&collection=halfdeletedcollection&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1409
   [junit4]   2> 1226365 INFO  (qtp1576490968-13571) [n:127.0.0.1:62411_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> 1226466 INFO  (zkCallback-2560-thread-2-processing-n:127.0.0.1:62417_solr) [n:127.0.0.1:62417_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfdeletedcollection/state.json] for collection [halfdeletedcollection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1226466 INFO  (zkCallback-2560-thread-1-processing-n:127.0.0.1:62417_solr) [n:127.0.0.1:62417_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfdeletedcollection/state.json] for collection [halfdeletedcollection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1226468 INFO  (zkCallback-2559-thread-2-processing-n:127.0.0.1:62408_solr) [n:127.0.0.1:62408_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfdeletedcollection/state.json] for collection [halfdeletedcollection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1226468 INFO  (zkCallback-2559-thread-1-processing-n:127.0.0.1:62408_solr) [n:127.0.0.1:62408_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfdeletedcollection/state.json] for collection [halfdeletedcollection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1226539 INFO  (OverseerCollectionConfigSetProcessor-98758427824488460-127.0.0.1:62411_solr-n_0000000000) [n:127.0.0.1:62411_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000008 doesn't exist.  Requestor may have disconnected from ZooKeeper
   [junit4]   2> 1227366 INFO  (qtp1576490968-13571) [n:127.0.0.1:62411_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf&name=halfdeletedcollection&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=2833
   [junit4]   2> 1227367 INFO  (qtp1576490968-13617) [n:127.0.0.1:62411_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1227368 INFO  (qtp1576490968-13617) [n:127.0.0.1:62411_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1227368 INFO  (TEST-CollectionsAPIDistributedZkTest.deletePartiallyCreatedCollection-seed#[B36F42A0BB758B11]) [    ] o.a.s.SolrTestCaseJ4 ###Ending deletePartiallyCreatedCollection
   [junit4]   2> 1227399 INFO  (TEST-CollectionsAPIDistributedZkTest.testSpecificConfigsets-seed#[B36F42A0BB758B11]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testSpecificConfigsets
   [junit4]   2> 1227400 INFO  (TEST-CollectionsAPIDistributedZkTest.testSpecificConfigsets-seed#[B36F42A0BB758B11]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1227401 INFO  (qtp1576490968-13616) [n:127.0.0.1:62411_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=halfdeletedcollection&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1227404 INFO  (OverseerThreadFactory-4672-thread-5-processing-n:127.0.0.1:62411_solr) [n:127.0.0.1:62411_solr    ] o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
   [junit4]   2> 1227407 INFO  (qtp1858356534-13591) [n:127.0.0.1:62417_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.halfdeletedcollection.shard1.replica_n1, tag=null
   [junit4]   2> 1227407 INFO  (qtp1406728332-13566) [n:127.0.0.1:62408_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.halfdeletedcollection.shard2.replica_n2, tag=null
   [junit4]   2> 1227407 INFO  (qtp1858356534-13591) [n:127.0.0.1:62417_solr    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@52cfb560: rootName = null, domain = solr.core.halfdeletedcollection.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.halfdeletedcollection.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@69806bb6
   [junit4]   2> 1227407 INFO  (qtp1406728332-13566) [n:127.0.0.1:62408_solr    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@784b55a4: rootName = null, domain = solr.core.halfdeletedcollection.shard2.replica_n2, service url = null, agent id = null] for registry solr.core.halfdeletedcollection.shard2.replica_n2 / com.codahale.metrics.MetricRegistry@26bd79e6
   [junit4]   2> 1227425 INFO  (qtp1858356534-13591) [n:127.0.0.1:62417_solr    ] o.a.s.c.SolrCore [halfdeletedcollection_shard1_replica_n1]  CLOSING SolrCore org.apache.solr.core.SolrCore@62ce2a24
   [junit4]   2> 1227425 INFO  (qtp1858356534-13591) [n:127.0.0.1:62417_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.halfdeletedcollection.shard1.replica_n1, tag=1657678372
   [junit4]   2> 1227425 INFO  (qtp1858356534-13591) [n:127.0.0.1:62417_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.halfdeletedcollection.shard1.leader, tag=1657678372
   [junit4]   2> 1227427 INFO  (qtp1406728332-13566) [n:127.0.0.1:62408_solr    ] o.a.s.c.SolrCore [halfdeletedcollection_shard2_replica_n2]  CLOSING SolrCore org.apache.solr.core.SolrCore@5910f53b
   [junit4]   2> 1227427 INFO  (qtp1406728332-13566) [n:127.0.0.1:62408_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.halfdeletedcollection.shard2.replica_n2, tag=1494283579
   [junit4]   2> 1227427 INFO  (qtp1406728332-13566) [n:127.0.0.1:62408_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.halfdeletedcollection.shard2.leader, tag=1494283579
   [junit4]   2> 1227440 INFO  (qtp1858356534-13591) [n:127.0.0.1:62417_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=halfdeletedcollection_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=34
   [junit4]   2> 1227446 INFO  (qtp1406728332-13566) [n:127.0.0.1:62408_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=halfdeletedcollection_shard2_replica_n2&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=40
   [junit4]   2> 1228151 INFO  (qtp1576490968-13616) [n:127.0.0.1:62411_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=halfdeletedcollection&action=DELETE&wt=javabin&version=2} status=0 QTime=749
   [junit4]   2> 1228152 INFO  (qtp1576490968-13615) [n:127.0.0.1:62411_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf2&name=withconfigset2&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1228154 INFO  (OverseerThreadFactory-4672-thread-5-processing-n:127.0.0.1:62411_solr) [n:127.0.0.1:62411_solr    ] o.a.s.c.CreateCollectionCmd Create collection withconfigset2
   [junit4]   2> 1228155 INFO  (OverseerCollectionConfigSetProcessor-98758427824488460-127.0.0.1:62411_solr-n_0000000000) [n:127.0.0.1:62411_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000010 doesn't exist.  Requestor may have disconnected from ZooKeeper
   [junit4]   2> 1228363 INFO  (OverseerStateUpdate-98758427824488460-127.0.0.1:62411_solr-n_0000000000) [n:127.0.0.1:62411_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withconfigset2",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"withconfigset2_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:62414/solr",
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1228566 INFO  (qtp455575448-13583) [n:127.0.0.1:62414_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf2&newCollection=true&name=withconfigset2_shard1_replica_n1&action=CREATE&numShards=1&collection=withconfigset2&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1228567 INFO  (qtp455575448-13583) [n:127.0.0.1:62414_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 1228674 INFO  (zkCallback-2558-thread-2-processing-n:127.0.0.1:62414_solr) [n:127.0.0.1:62414_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/withconfigset2/state.json] for collection [withconfigset2] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1228674 INFO  (zkCallback-2558-thread-1-processing-n:127.0.0.1:62414_solr) [n:127.0.0.1:62414_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/withconfigset2/state.json] for collection [withconfigset2] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1229598 INFO  (qtp455575448-13583) [n:127.0.0.1:62414_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 1229608 INFO  (qtp455575448-13583) [n:127.0.0.1:62414_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.s.IndexSchema [withconfigset2_shard1_replica_n1] Schema name=minimal
   [junit4]   2> 1229614 INFO  (qtp455575448-13583) [n:127.0.0.1:62414_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1229614 INFO  (qtp455575448-13583) [n:127.0.0.1:62414_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'withconfigset2_shard1_replica_n1' using configuration from collection withconfigset2, trusted=true
   [junit4]   2> 1229616 INFO  (qtp455575448-13583) [n:127.0.0.1:62414_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.withconfigset2.shard1.replica_n1' (registry 'solr.core.withconfigset2.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4380cb1f
   [junit4]   2> 1229616 INFO  (qtp455575448-13583) [n:127.0.0.1:62414_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1229616 INFO  (qtp455575448-13583) [n:127.0.0.1:62414_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.c.SolrCore [[withconfigset2_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CollectionsAPIDistributedZkTest_B36F42A0BB758B11-001\tempDir-001\node2\withconfigset2_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CollectionsAPIDistributedZkTest_B36F42A0BB758B11-001\tempDir-001\node2\withconfigset2_shard1_replica_n1\data\]
   [junit4]   2> 1229732 INFO  (qtp455575448-13583) [n:127.0.0.1:62414_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1229732 INFO  (qtp455575448-13583) [n:127.0.0.1:62414_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1229734 INFO  (qtp455575448-13583) [n:127.0.0.1:62414_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1229734 INFO  (qtp455575448-13583) [n:127.0.0.1:62414_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1229736 INFO  (qtp455575448-13583) [n:127.0.0.1:62414_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3f581764[withconfigset2_shard1_replica_n1] main]
   [junit4]   2> 1229765 INFO  (qtp455575448-13583) [n:127.0.0.1:62414_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf2
   [junit4]   2> 1229766 INFO  (qtp455575448-13583) [n:127.0.0.1:62414_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf2
   [junit4]   2> 1229766 INFO  (qtp455575448-13583) [n:127.0.0.1:62414_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1229767 INFO  (qtp455575448-13583) [n:127.0.0.1:62414_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1580134853170429952
   [junit4]   2> 1229768 INFO  (searcherExecutor-4696-thread-1-processing-n:127.0.0.1:62414_solr x:withconfigset2_shard1_replica_n1 s:shard1 c:withconfigset2 r:core_node2) [n:127.0.0.1:62414_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.c.SolrCore [withconfigset2_shard1_replica_n1] Registered new searcher Searcher@3f581764[withconfigset2_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1229781 INFO  (qtp455575448-13583) [n:127.0.0.1:62414_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1229781 INFO  (qtp455575448-13583) [n:127.0.0.1:62414_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1229781 INFO  (qtp455575448-13583) [n:127.0.0.1:62414_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:62414/solr/withconfigset2_shard1_replica_n1/
   [junit4]   2> 1229781 INFO  (qtp455575448-13583) [n:127.0.0.1:62414_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 1229781 INFO  (qtp455575448-13583) [n:127.0.0.1:62414_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:62414/solr/withconfigset2_shard1_replica_n1/ has no replicas
   [junit4]   2> 1229781 INFO  (qtp455575448-13583) [n:127.0.0.1:62414_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
   [junit4]   2> 1229786 INFO  (qtp455575448-13583) [n:127.0.0.1:62414_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:62414/solr/withconfigset2_shard1_replica_n1/ shard1
   [junit4]   2> 1229950 INFO  (zkCallback-2558-thread-1-processing-n:127.0.0.1:62414_solr) [n:127.0.0.1:62414_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/withconfigset2/state.json] for collection [withconfigset2] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1229950 INFO  (zkCallback-2558-thread-2-processing-n:127.0.0.1:62414_solr) [n:127.0.0.1:62414_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/withconfigset2/state.json] for collection [withconfigset2] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1229993 INFO  (qtp455575448-13583) [n:127.0.0.1:62414_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1229998 INFO  (qtp455575448-13583) [n:127.0.0.1:62414_solr c:withconfigset2 s:shard1 r:core_node2 x:withconfigset2_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf2&newCollection=true&name=withconfigset2_shard1_replica_n1&action=CREATE&numShards=1&collection=withconfigset2&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1432
   [junit4]   2> 1230002 INFO  (qtp1576490968-13615) [n:127.0.0.1:62411_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> 1230104 INFO  (zkCallback-2558-thread-2-processing-n:127.0.0.1:62414_solr) [n:127.0.0.1:62414_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/withconfigset2/state.json] for collection [withconfigset2] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1230105 INFO  (zkCallback-2558-thread-1-processing-n:127.0.0.1:62414_solr) [n:127.0.0.1:62414_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/withconfigset2/state.json] for collection [withconfigset2] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1230162 INFO  (OverseerCollectionConfigSetProcessor-98758427824488460-127.0.0.1:62411_solr-n_0000000000) [n:127.0.0.1:62411_solr    ] o.a.s.c.OverseerTaskQueue Response ZK

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

core.awhollynewcollection_0.shard3.replica_n4 / com.codahale.metrics.MetricRegistry@72a7b83
   [junit4]   2> 1313177 INFO  (coreCloseExecutor-4865-thread-1) [n:127.0.0.1:62408_solr c:awhollynewcollection_0 s:shard3 r:core_node6 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.awhollynewcollection_0.shard3.leader, tag=1702152444
   [junit4]   2> 1313177 WARN  (zkCallback-2596-thread-2-processing-n:127.0.0.1:62408_solr) [n:127.0.0.1:62408_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 1314169 ERROR (coreZkRegister-4849-thread-1-processing-n:127.0.0.1:62408_solr x:awhollynewcollection_0_shard3_replica_n4 s:shard3 c:awhollynewcollection_0 r:core_node6) [n:127.0.0.1:62408_solr c:awhollynewcollection_0 s:shard3 r:core_node6 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.c.ZkController Error getting leader from zk
   [junit4]   2> org.apache.solr.common.SolrException: CoreContainer is closed
   [junit4]   2> 	at org.apache.solr.cloud.ZkController.getLeaderProps(ZkController.java:1143)
   [junit4]   2> 	at org.apache.solr.cloud.ZkController.getLeaderProps(ZkController.java:1110)
   [junit4]   2> 	at org.apache.solr.cloud.ZkController.getLeader(ZkController.java:1066)
   [junit4]   2> 	at org.apache.solr.cloud.ZkController.register(ZkController.java:971)
   [junit4]   2> 	at org.apache.solr.cloud.ZkController.register(ZkController.java:906)
   [junit4]   2> 	at org.apache.solr.core.ZkContainer.lambda$registerInZk$0(ZkContainer.java:181)
   [junit4]   2> 	at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 1314169 ERROR (coreZkRegister-4849-thread-1-processing-n:127.0.0.1:62408_solr x:awhollynewcollection_0_shard3_replica_n4 s:shard3 c:awhollynewcollection_0 r:core_node6) [n:127.0.0.1:62408_solr c:awhollynewcollection_0 s:shard3 r:core_node6 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.c.ZkContainer :org.apache.solr.common.SolrException: Error getting leader from zk for shard shard3
   [junit4]   2> 	at org.apache.solr.cloud.ZkController.getLeader(ZkController.java:1099)
   [junit4]   2> 	at org.apache.solr.cloud.ZkController.register(ZkController.java:971)
   [junit4]   2> 	at org.apache.solr.cloud.ZkController.register(ZkController.java:906)
   [junit4]   2> 	at org.apache.solr.core.ZkContainer.lambda$registerInZk$0(ZkContainer.java:181)
   [junit4]   2> 	at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> Caused by: org.apache.solr.common.SolrException: CoreContainer is closed
   [junit4]   2> 	at org.apache.solr.cloud.ZkController.getLeaderProps(ZkController.java:1143)
   [junit4]   2> 	at org.apache.solr.cloud.ZkController.getLeaderProps(ZkController.java:1110)
   [junit4]   2> 	at org.apache.solr.cloud.ZkController.getLeader(ZkController.java:1066)
   [junit4]   2> 	... 7 more
   [junit4]   2> 
   [junit4]   2> 1314178 INFO  (jetty-closer-2540-thread-2) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5e15f5c0{/solr,null,UNAVAILABLE}
   [junit4]   2> 1314625 INFO  (jetty-closer-2540-thread-3) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3cabce6{/solr,null,UNAVAILABLE}
   [junit4]   2> 1314628 ERROR (SUITE-CollectionsAPIDistributedZkTest-seed#[B36F42A0BB758B11]-worker) [    ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
   [junit4]   2> 1314628 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[B36F42A0BB758B11]-worker) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:62404 62404
   [junit4]   2> 1314630 INFO  (Thread-2454) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:62404 62404
   [junit4]   2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CollectionsAPIDistributedZkTest_B36F42A0BB758B11-001
   [junit4]   2> Oct 02, 2017 8:48:30 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
   [junit4]   2> NOTE: test params are: codec=Lucene70, sim=RandomSimilarity(queryNorm=true): {}, locale=sr-Latn, timezone=Asia/Srednekolymsk
   [junit4]   2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=3,threads=1,free=244254496,total=460324864
   [junit4]   2> NOTE: All tests run in this JVM: [ChaosMonkeySafeLeaderWithPullReplicasTest, DocValuesMultiTest, TestLuceneMatchVersion, TestReRankQParserPlugin, SolrShardReporterTest, TestDocumentBuilder, TestCollationFieldDocValues, TermVectorComponentDistributedTest, TestRawResponseWriter, TestInitParams, DeleteNodeTest, TestSolr4Spatial2, TestRangeQuery, HdfsDirectoryTest, TestFieldTypeResource, CursorMarkTest, CreateCollectionCleanupTest, SignatureUpdateProcessorFactoryTest, TestPullReplicaErrorHandling, TestIndexSearcher, SampleTest, TestReload, TestCoreAdminApis, TestSolrXml, MergeStrategyTest, TestQueryTypes, TestFileDictionaryLookup, SolrCoreCheckLockOnStartupTest, HttpSolrCallGetCoreTest, TestDocTermOrds, MoreLikeThisHandlerTest, TestSchemaVersionResource, DistributedTermsComponentTest, DistribCursorPagingTest, ZkStateReaderTest, FullHLLTest, TestCloudDeleteByQuery, TestMinMaxOnMultiValuedField, TestSolr4Spatial, DistributedFacetPivotLargeTest, BlockDirectoryTest, TestSolrQueryParser, TestBackupRepositoryFactory, SpellingQueryConverterTest, TestCustomDocTransformer, LoggingHandlerTest, MultiThreadedOCPTest, BadIndexSchemaTest, TestSolrConfigHandlerConcurrent, TestCollapseQParserPlugin, TestSimpleQParserPlugin, TestImplicitCoreProperties, TestCustomStream, HdfsRecoverLeaseTest, TestCloudSearcherWarming, TestSegmentSorting, TestCollectionAPIs, TestCSVLoader, TestPayloadScoreQParserPlugin, OverseerCollectionConfigSetProcessorTest, TestSolrCloudWithKerberosAlt, SimpleMLTQParserTest, CoreAdminCreateDiscoverTest, BasicFunctionalityTest, TestChildDocTransformer, BlockJoinFacetDistribTest, MBeansHandlerTest, EnumFieldTest, TestAuthorizationFramework, HdfsChaosMonkeyNothingIsSafeTest, TestBM25SimilarityFactory, TestTolerantUpdateProcessorCloud, SolrXmlInZkTest, DistributedVersionInfoTest, TestStressLiveNodes, TestCrossCoreJoin, BadComponentTest, AtomicUpdatesTest, DistributedIntervalFacetingTest, SOLR749Test, SmileWriterTest, TestSolrCLIRunExample, UpdateRequestProcessorFactoryTest, SpatialRPTFieldTypeTest, TestMergePolicyConfig, TestManagedResource, FullSolrCloudDistribCmdsTest, FileBasedSpellCheckerTest, TestPostingsSolrHighlighter, LukeRequestHandlerTest, CollectionReloadTest, TermVectorComponentTest, JvmMetricsTest, TestFastWriter, SuggestComponentTest, DistributedFacetPivotWhiteBoxTest, TestEmbeddedSolrServerConstructors, TestSolrFieldCacheBean, SolrGangliaReporterTest, TestMiniSolrCloudClusterSSL, ForceLeaderTest, TestRequestStatusCollectionAPI, SSLMigrationTest, DirectSolrSpellCheckerTest, TestXmlQParser, TestSchemaResource, RecoveryAfterSoftCommitTest, AnalysisErrorHandlingTest, TestJoin, SortSpecParsingTest, LeaderFailureAfterFreshStartTest, TestManagedSchemaThreadSafety, UpdateLogTest, TestHdfsCloudBackupRestore, TestPerFieldSimilarity, JavabinLoaderTest, SolrCLIZkUtilsTest, TestRTGBase, CollectionsAPIDistributedZkTest]
   [junit4] Completed [273/733 (1!)] on J1 in 92.77s, 19 tests, 1 error <<< FAILURES!

[...truncated 38884 lines...]
-documentation-lint:
     [echo] checking for broken html...
    [jtidy] Checking for broken html (such as invalid tags)...
   [delete] Deleting directory C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\jtidy_tmp
     [echo] Checking for broken links...
     [exec] 
     [exec] Crawl/parse...
     [exec] 
     [exec] Verify...
     [exec] 
     [exec] file:///build/docs/sandbox/org/apache/lucene/document/FloatPointNearestNeighbor.html
     [exec]   BROKEN LINK: file:///build/docs/core/org/apache/lucene/document/FloatPointNearestNeighbor.NearestHit.html
     [exec]   BROKEN LINK: file:///build/docs/core/org/apache/lucene/document/FloatPointNearestNeighbor.NearestHit.html
     [exec] 
     [exec] Broken javadocs links were found! Common root causes:
     [exec] * A typo of some sort for manually created links.
     [exec] * Public methods referencing non-public classes in their signature.

BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\build.xml:826: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\build.xml:101: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build.xml:142: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build.xml:155: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\common-build.xml:2570: exec returned: 1

Total time: 86 minutes 43 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