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-master-Windows (32bit/jdk1.8.0_131) - Build # 6648 - Still Unstable!
Date Wed, 14 Jun 2017 20:29:18 GMT
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6648/
Java: 32bit/jdk1.8.0_131 -server -XX:+UseConcMarkSweepGC

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

Error Message:
Error from server at http://127.0.0.1:51029/solr: delete the collection time out:180s

Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:51029/solr: delete the collection time out:180s
	at __randomizedtesting.SeedInfo.seed([DF0BF1BC1F11E7E7:5F2B94920E520F41]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:592)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:219)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:459)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:389)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1130)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:871)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806)
	at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:160)
	at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:177)
	at org.apache.solr.cloud.MiniSolrCloudCluster.deleteAllCollections(MiniSolrCloudCluster.java:442)
	at org.apache.solr.cloud.autoscaling.TestPolicyCloud.after(TestPolicyCloud.java:63)
	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:1713)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:965)
	at org.junit.rules.ExpectedException$ExpectedExceptionStatement.evaluate(ExpectedException.java:110)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
	at java.lang.Thread.run(Thread.java:748)


FAILED:  org.apache.solr.handler.V2ApiIntegrationTest.testCollectionsApi

Error Message:
Error from server at http://127.0.0.1:60773/solr: Expected mime type application/octet-stream but got text/html. <html> <head> <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/> <title>Error 400 </title> </head> <body> <h2>HTTP ERROR: 400</h2> <p>Problem accessing /solr/____v2/c. Reason: <pre>    {metadata={error-class=org.apache.solr.common.SolrException,root-error-class=java.nio.file.InvalidPathException},msg=java.nio.file.InvalidPathException: Illegal char &lt;?&gt; at index 53: C:UsersjenkinsworkspaceLucene-Solr-master-Windowssolr?uildsolr-core estJ0 empsolr.handler.V2ApiIntegrationTest_DF0BF1BC1F11E7E7-001 empDir-002,code=400}</pre></p> <hr /><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.3.14.v20161028</a><hr/> </body> </html> 

Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:60773/solr: Expected mime type application/octet-stream but got text/html. <html>
<head>
<meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/>
<title>Error 400 </title>
</head>
<body>
<h2>HTTP ERROR: 400</h2>
<p>Problem accessing /solr/____v2/c. Reason:
<pre>    {metadata={error-class=org.apache.solr.common.SolrException,root-error-class=java.nio.file.InvalidPathException},msg=java.nio.file.InvalidPathException: Illegal char &lt;?&gt; at index 53: C:UsersjenkinsworkspaceLucene-Solr-master-Windowssolr?uildsolr-core	estJ0	empsolr.handler.V2ApiIntegrationTest_DF0BF1BC1F11E7E7-001	empDir-002,code=400}</pre></p>
<hr /><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.3.14.v20161028</a><hr/>
</body>
</html>

	at __randomizedtesting.SeedInfo.seed([DF0BF1BC1F11E7E7:395163DA7088359]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:219)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:459)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:389)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1130)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:871)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806)
	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
	at org.apache.solr.handler.V2ApiIntegrationTest.testCollectionsApi(V2ApiIntegrationTest.java:99)
	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:1713)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
	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:916)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
	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 12036 lines...]
   [junit4] Suite: org.apache.solr.cloud.autoscaling.TestPolicyCloud
   [junit4]   2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TestPolicyCloud_DF0BF1BC1F11E7E7-001\init-core-data-001
   [junit4]   2> 1185225 INFO  (SUITE-TestPolicyCloud-seed#[DF0BF1BC1F11E7E7]-worker) [    ] o.a.s.SolrTestCaseJ4 Using TrieFields
   [junit4]   2> 1185227 INFO  (SUITE-TestPolicyCloud-seed#[DF0BF1BC1F11E7E7]-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> 1185231 INFO  (SUITE-TestPolicyCloud-seed#[DF0BF1BC1F11E7E7]-worker) [    ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 5 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TestPolicyCloud_DF0BF1BC1F11E7E7-001\tempDir-001
   [junit4]   2> 1185231 INFO  (SUITE-TestPolicyCloud-seed#[DF0BF1BC1F11E7E7]-worker) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1185232 INFO  (Thread-2323) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1185232 INFO  (Thread-2323) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1185235 ERROR (Thread-2323) [    ] 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> 1185331 INFO  (SUITE-TestPolicyCloud-seed#[DF0BF1BC1F11E7E7]-worker) [    ] o.a.s.c.ZkTestServer start zk server on port:51021
   [junit4]   2> 1185338 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 0x15ca82766ee0000, 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> 1185340 INFO  (jetty-launcher-2092-thread-4) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1185340 INFO  (jetty-launcher-2092-thread-3) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1185341 INFO  (jetty-launcher-2092-thread-4) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@34213{/solr,null,AVAILABLE}
   [junit4]   2> 1185341 INFO  (jetty-launcher-2092-thread-3) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1897405{/solr,null,AVAILABLE}
   [junit4]   2> 1185342 INFO  (jetty-launcher-2092-thread-4) [    ] o.e.j.s.AbstractConnector Started ServerConnector@908868{HTTP/1.1,[http/1.1]}{127.0.0.1:51026}
   [junit4]   2> 1185342 INFO  (jetty-launcher-2092-thread-4) [    ] o.e.j.s.Server Started @1190220ms
   [junit4]   2> 1185342 INFO  (jetty-launcher-2092-thread-4) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=51026}
   [junit4]   2> 1185342 INFO  (jetty-launcher-2092-thread-1) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1185342 ERROR (jetty-launcher-2092-thread-4) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1185342 INFO  (jetty-launcher-2092-thread-4) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 7.0.0
   [junit4]   2> 1185342 INFO  (jetty-launcher-2092-thread-4) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1185342 INFO  (jetty-launcher-2092-thread-4) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1185342 INFO  (jetty-launcher-2092-thread-4) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-06-14T19:49:33.657Z
   [junit4]   2> 1185342 INFO  (jetty-launcher-2092-thread-1) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@df4012{/solr,null,AVAILABLE}
   [junit4]   2> 1185343 INFO  (jetty-launcher-2092-thread-3) [    ] o.e.j.s.AbstractConnector Started ServerConnector@13dd5b7{HTTP/1.1,[http/1.1]}{127.0.0.1:51029}
   [junit4]   2> 1185343 INFO  (jetty-launcher-2092-thread-3) [    ] o.e.j.s.Server Started @1190221ms
   [junit4]   2> 1185343 INFO  (jetty-launcher-2092-thread-3) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=51029}
   [junit4]   2> 1185343 INFO  (jetty-launcher-2092-thread-1) [    ] o.e.j.s.AbstractConnector Started ServerConnector@196a687{HTTP/1.1,[http/1.1]}{127.0.0.1:51032}
   [junit4]   2> 1185343 INFO  (jetty-launcher-2092-thread-1) [    ] o.e.j.s.Server Started @1190221ms
   [junit4]   2> 1185343 ERROR (jetty-launcher-2092-thread-3) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1185343 INFO  (jetty-launcher-2092-thread-3) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 7.0.0
   [junit4]   2> 1185343 INFO  (jetty-launcher-2092-thread-3) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1185343 INFO  (jetty-launcher-2092-thread-3) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1185343 INFO  (jetty-launcher-2092-thread-3) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-06-14T19:49:33.658Z
   [junit4]   2> 1185343 INFO  (jetty-launcher-2092-thread-1) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=51032}
   [junit4]   2> 1185344 ERROR (jetty-launcher-2092-thread-1) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1185344 INFO  (jetty-launcher-2092-thread-1) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 7.0.0
   [junit4]   2> 1185344 INFO  (jetty-launcher-2092-thread-1) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1185344 INFO  (jetty-launcher-2092-thread-1) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1185344 INFO  (jetty-launcher-2092-thread-1) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-06-14T19:49:33.659Z
   [junit4]   2> 1185344 INFO  (jetty-launcher-2092-thread-2) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1185344 INFO  (jetty-launcher-2092-thread-2) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@195894d{/solr,null,AVAILABLE}
   [junit4]   2> 1185346 INFO  (jetty-launcher-2092-thread-2) [    ] o.e.j.s.AbstractConnector Started ServerConnector@1030200{HTTP/1.1,[http/1.1]}{127.0.0.1:51036}
   [junit4]   2> 1185346 INFO  (jetty-launcher-2092-thread-2) [    ] o.e.j.s.Server Started @1190223ms
   [junit4]   2> 1185346 INFO  (jetty-launcher-2092-thread-2) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=51036}
   [junit4]   2> 1185346 ERROR (jetty-launcher-2092-thread-2) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1185346 INFO  (jetty-launcher-2092-thread-2) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 7.0.0
   [junit4]   2> 1185346 INFO  (jetty-launcher-2092-thread-2) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1185346 INFO  (jetty-launcher-2092-thread-2) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1185346 INFO  (jetty-launcher-2092-thread-2) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-06-14T19:49:33.661Z
   [junit4]   2> 1185346 INFO  (jetty-launcher-2092-thread-5) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1185347 INFO  (jetty-launcher-2092-thread-5) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7d21d5{/solr,null,AVAILABLE}
   [junit4]   2> 1185347 INFO  (jetty-launcher-2092-thread-5) [    ] o.e.j.s.AbstractConnector Started ServerConnector@2d54da{HTTP/1.1,[http/1.1]}{127.0.0.1:51046}
   [junit4]   2> 1185348 INFO  (jetty-launcher-2092-thread-5) [    ] o.e.j.s.Server Started @1190225ms
   [junit4]   2> 1185348 INFO  (jetty-launcher-2092-thread-5) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=51046}
   [junit4]   2> 1185348 ERROR (jetty-launcher-2092-thread-5) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1185348 INFO  (jetty-launcher-2092-thread-5) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 7.0.0
   [junit4]   2> 1185348 INFO  (jetty-launcher-2092-thread-5) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1185348 INFO  (jetty-launcher-2092-thread-5) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1185348 INFO  (jetty-launcher-2092-thread-5) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-06-14T19:49:33.663Z
   [junit4]   2> 1185355 INFO  (jetty-launcher-2092-thread-1) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1185357 INFO  (jetty-launcher-2092-thread-2) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1185375 INFO  (jetty-launcher-2092-thread-2) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51021/solr
   [junit4]   2> 1185376 INFO  (jetty-launcher-2092-thread-4) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1185377 INFO  (jetty-launcher-2092-thread-1) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51021/solr
   [junit4]   2> 1185378 INFO  (jetty-launcher-2092-thread-3) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1185383 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 0x15ca82766ee0004, 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> 1185383 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 0x15ca82766ee0003, 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> 1185385 INFO  (jetty-launcher-2092-thread-3) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51021/solr
   [junit4]   2> 1185386 INFO  (jetty-launcher-2092-thread-4) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51021/solr
   [junit4]   2> 1185387 INFO  (jetty-launcher-2092-thread-5) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1185395 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 0x15ca82766ee0006, 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> 1185400 INFO  (jetty-launcher-2092-thread-5) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51021/solr
   [junit4]   2> 1185402 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 0x15ca82766ee0008, 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> 1185430 INFO  (jetty-launcher-2092-thread-4) [n:127.0.0.1:51026_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1185430 INFO  (jetty-launcher-2092-thread-2) [n:127.0.0.1:51036_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1185430 INFO  (jetty-launcher-2092-thread-3) [n:127.0.0.1:51029_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1185431 INFO  (jetty-launcher-2092-thread-1) [n:127.0.0.1:51032_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1185431 INFO  (jetty-launcher-2092-thread-2) [n:127.0.0.1:51036_solr    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:51036_solr
   [junit4]   2> 1185432 INFO  (jetty-launcher-2092-thread-4) [n:127.0.0.1:51026_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51026_solr
   [junit4]   2> 1185432 INFO  (jetty-launcher-2092-thread-3) [n:127.0.0.1:51029_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51029_solr
   [junit4]   2> 1185433 INFO  (jetty-launcher-2092-thread-5) [n:127.0.0.1:51046_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1185433 INFO  (jetty-launcher-2092-thread-2) [n:127.0.0.1:51036_solr    ] o.a.s.c.Overseer Overseer (id=98138179079372810-127.0.0.1:51036_solr-n_0000000000) starting
   [junit4]   2> 1185433 INFO  (jetty-launcher-2092-thread-1) [n:127.0.0.1:51032_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51032_solr
   [junit4]   2> 1185445 INFO  (zkCallback-2112-thread-1-processing-n:127.0.0.1:51036_solr) [n:127.0.0.1:51036_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1185448 INFO  (jetty-launcher-2092-thread-5) [n:127.0.0.1:51046_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51046_solr
   [junit4]   2> 1185450 INFO  (zkCallback-2118-thread-1-processing-n:127.0.0.1:51026_solr) [n:127.0.0.1:51026_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1185450 INFO  (zkCallback-2112-thread-1-processing-n:127.0.0.1:51036_solr) [n:127.0.0.1:51036_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1185450 INFO  (zkCallback-2119-thread-1-processing-n:127.0.0.1:51046_solr) [n:127.0.0.1:51046_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1185450 INFO  (zkCallback-2113-thread-1-processing-n:127.0.0.1:51032_solr) [n:127.0.0.1:51032_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1185569 INFO  (zkCallback-2117-thread-1-processing-n:127.0.0.1:51029_solr) [n:127.0.0.1:51029_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1185572 INFO  (zkCallback-2119-thread-2-processing-n:127.0.0.1:51046_solr) [n:127.0.0.1:51046_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1185573 INFO  (zkCallback-2118-thread-2-processing-n:127.0.0.1:51026_solr) [n:127.0.0.1:51026_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1185573 INFO  (zkCallback-2113-thread-2-processing-n:127.0.0.1:51032_solr) [n:127.0.0.1:51032_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1185573 INFO  (zkCallback-2117-thread-2-processing-n:127.0.0.1:51029_solr) [n:127.0.0.1:51029_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1185578 INFO  (zkCallback-2119-thread-2-processing-n:127.0.0.1:51046_solr) [n:127.0.0.1:51046_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1185578 INFO  (zkCallback-2118-thread-2-processing-n:127.0.0.1:51026_solr) [n:127.0.0.1:51026_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1185583 INFO  (zkCallback-2113-thread-2-processing-n:127.0.0.1:51032_solr) [n:127.0.0.1:51032_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1185583 INFO  (zkCallback-2117-thread-2-processing-n:127.0.0.1:51029_solr) [n:127.0.0.1:51029_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1185583 INFO  (zkCallback-2112-thread-1-processing-n:127.0.0.1:51036_solr) [n:127.0.0.1:51036_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1185591 INFO  (jetty-launcher-2092-thread-2) [n:127.0.0.1:51036_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51036_solr
   [junit4]   2> 1185618 INFO  (jetty-launcher-2092-thread-4) [n:127.0.0.1:51026_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51026.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b1ca6c
   [junit4]   2> 1185620 INFO  (jetty-launcher-2092-thread-4) [n:127.0.0.1:51026_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51026.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b1ca6c
   [junit4]   2> 1185620 INFO  (jetty-launcher-2092-thread-4) [n:127.0.0.1:51026_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51026.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b1ca6c
   [junit4]   2> 1185621 INFO  (jetty-launcher-2092-thread-4) [n:127.0.0.1:51026_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TestPolicyCloud_DF0BF1BC1F11E7E7-001\tempDir-001\node2\.
   [junit4]   2> 1185621 INFO  (zkCallback-2112-thread-1-processing-n:127.0.0.1:51036_solr) [n:127.0.0.1:51036_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 1185621 INFO  (zkCallback-2117-thread-2-processing-n:127.0.0.1:51029_solr) [n:127.0.0.1:51029_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 1185621 INFO  (zkCallback-2113-thread-2-processing-n:127.0.0.1:51032_solr) [n:127.0.0.1:51032_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 1185622 INFO  (zkCallback-2118-thread-2-processing-n:127.0.0.1:51026_solr) [n:127.0.0.1:51026_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 1185622 INFO  (zkCallback-2119-thread-2-processing-n:127.0.0.1:51046_solr) [n:127.0.0.1:51046_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 1185652 INFO  (jetty-launcher-2092-thread-1) [n:127.0.0.1:51032_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51032.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b1ca6c
   [junit4]   2> 1185652 INFO  (jetty-launcher-2092-thread-3) [n:127.0.0.1:51029_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51029.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b1ca6c
   [junit4]   2> 1185654 INFO  (jetty-launcher-2092-thread-1) [n:127.0.0.1:51032_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51032.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b1ca6c
   [junit4]   2> 1185654 INFO  (jetty-launcher-2092-thread-1) [n:127.0.0.1:51032_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51032.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b1ca6c
   [junit4]   2> 1185655 INFO  (jetty-launcher-2092-thread-3) [n:127.0.0.1:51029_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51029.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b1ca6c
   [junit4]   2> 1185655 INFO  (jetty-launcher-2092-thread-3) [n:127.0.0.1:51029_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51029.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b1ca6c
   [junit4]   2> 1185655 INFO  (jetty-launcher-2092-thread-1) [n:127.0.0.1:51032_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TestPolicyCloud_DF0BF1BC1F11E7E7-001\tempDir-001\node5\.
   [junit4]   2> 1185656 INFO  (jetty-launcher-2092-thread-3) [n:127.0.0.1:51029_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TestPolicyCloud_DF0BF1BC1F11E7E7-001\tempDir-001\node3\.
   [junit4]   2> 1185701 INFO  (jetty-launcher-2092-thread-2) [n:127.0.0.1:51036_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51036.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b1ca6c
   [junit4]   2> 1185701 INFO  (jetty-launcher-2092-thread-5) [n:127.0.0.1:51046_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51046.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b1ca6c
   [junit4]   2> 1185703 INFO  (jetty-launcher-2092-thread-2) [n:127.0.0.1:51036_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51036.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b1ca6c
   [junit4]   2> 1185704 INFO  (jetty-launcher-2092-thread-2) [n:127.0.0.1:51036_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51036.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b1ca6c
   [junit4]   2> 1185704 INFO  (jetty-launcher-2092-thread-5) [n:127.0.0.1:51046_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51046.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b1ca6c
   [junit4]   2> 1185704 INFO  (jetty-launcher-2092-thread-5) [n:127.0.0.1:51046_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51046.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b1ca6c
   [junit4]   2> 1185704 INFO  (jetty-launcher-2092-thread-2) [n:127.0.0.1:51036_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TestPolicyCloud_DF0BF1BC1F11E7E7-001\tempDir-001\node4\.
   [junit4]   2> 1185705 INFO  (jetty-launcher-2092-thread-5) [n:127.0.0.1:51046_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TestPolicyCloud_DF0BF1BC1F11E7E7-001\tempDir-001\node1\.
   [junit4]   2> 1185767 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 0x15ca82766ee0010, 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> 1185773 INFO  (SUITE-TestPolicyCloud-seed#[DF0BF1BC1F11E7E7]-worker) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
   [junit4]   2> 1185774 INFO  (SUITE-TestPolicyCloud-seed#[DF0BF1BC1F11E7E7]-worker) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:51021/solr ready
   [junit4]   2> 1185806 INFO  (TEST-TestPolicyCloud.testDataProvider-seed#[DF0BF1BC1F11E7E7]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testDataProvider
   [junit4]   2> 1185808 INFO  (qtp13927741-12143) [n:127.0.0.1:51029_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params shards=shard1&replicationFactor=2&collection.configName=conf&name=policiesTest&router.name=implicit&nrtReplicas=2&action=CREATE&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1185810 INFO  (OverseerThreadFactory-4742-thread-1-processing-n:127.0.0.1:51036_solr) [n:127.0.0.1:51036_solr    ] o.a.s.c.CreateCollectionCmd Create collection policiesTest
   [junit4]   2> 1185916 INFO  (qtp31329214-12179) [n:127.0.0.1:51046_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=policiesTest_shard1_replica_n1&action=CREATE&numShards=1&collection=policiesTest&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1185920 INFO  (qtp31329214-12179) [n:127.0.0.1:51046_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 1185920 INFO  (qtp13927741-12142) [n:127.0.0.1:51029_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=policiesTest_shard1_replica_n2&action=CREATE&numShards=1&collection=policiesTest&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1185921 INFO  (qtp13927741-12142) [n:127.0.0.1:51029_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 1186026 INFO  (zkCallback-2119-thread-2-processing-n:127.0.0.1:51046_solr) [n:127.0.0.1:51046_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/policiesTest/state.json] for collection [policiesTest] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 1186027 INFO  (zkCallback-2117-thread-2-processing-n:127.0.0.1:51029_solr) [n:127.0.0.1:51029_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/policiesTest/state.json] for collection [policiesTest] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 1186940 INFO  (qtp13927741-12142) [n:127.0.0.1:51029_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1186940 INFO  (qtp31329214-12179) [n:127.0.0.1:51046_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1186945 INFO  (qtp13927741-12142) [n:127.0.0.1:51029_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n2] o.a.s.s.IndexSchema [policiesTest_shard1_replica_n2] Schema name=minimal
   [junit4]   2> 1186945 INFO  (qtp31329214-12179) [n:127.0.0.1:51046_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n1] o.a.s.s.IndexSchema [policiesTest_shard1_replica_n1] Schema name=minimal
   [junit4]   2> 1186951 INFO  (qtp13927741-12142) [n:127.0.0.1:51029_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1186951 INFO  (qtp31329214-12179) [n:127.0.0.1:51046_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1186951 INFO  (qtp31329214-12179) [n:127.0.0.1:51046_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'policiesTest_shard1_replica_n1' using configuration from collection policiesTest, trusted=true
   [junit4]   2> 1186951 INFO  (qtp31329214-12179) [n:127.0.0.1:51046_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51046.solr.core.policiesTest.shard1.replica_n1' (registry 'solr.core.policiesTest.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b1ca6c
   [junit4]   2> 1186951 INFO  (qtp31329214-12179) [n:127.0.0.1:51046_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1186952 INFO  (qtp31329214-12179) [n:127.0.0.1:51046_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n1] o.a.s.c.SolrCore [[policiesTest_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TestPolicyCloud_DF0BF1BC1F11E7E7-001\tempDir-001\node1\policiesTest_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TestPolicyCloud_DF0BF1BC1F11E7E7-001\tempDir-001\node1\.\policiesTest_shard1_replica_n1\data\]
   [junit4]   2> 1186951 INFO  (qtp13927741-12142) [n:127.0.0.1:51029_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'policiesTest_shard1_replica_n2' using configuration from collection policiesTest, trusted=true
   [junit4]   2> 1186954 INFO  (qtp13927741-12142) [n:127.0.0.1:51029_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51029.solr.core.policiesTest.shard1.replica_n2' (registry 'solr.core.policiesTest.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b1ca6c
   [junit4]   2> 1186954 INFO  (qtp13927741-12142) [n:127.0.0.1:51029_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1186954 INFO  (qtp13927741-12142) [n:127.0.0.1:51029_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n2] o.a.s.c.SolrCore [[policiesTest_shard1_replica_n2] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TestPolicyCloud_DF0BF1BC1F11E7E7-001\tempDir-001\node3\policiesTest_shard1_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TestPolicyCloud_DF0BF1BC1F11E7E7-001\tempDir-001\node3\.\policiesTest_shard1_replica_n2\data\]
   [junit4]   2> 1187041 INFO  (qtp13927741-12142) [n:127.0.0.1:51029_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1187041 INFO  (qtp13927741-12142) [n:127.0.0.1:51029_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1187041 INFO  (qtp31329214-12179) [n:127.0.0.1:51046_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1187041 INFO  (qtp31329214-12179) [n:127.0.0.1:51046_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1187043 INFO  (qtp13927741-12142) [n:127.0.0.1:51029_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1187043 INFO  (qtp31329214-12179) [n:127.0.0.1:51046_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1187043 INFO  (qtp13927741-12142) [n:127.0.0.1:51029_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1187043 INFO  (qtp31329214-12179) [n:127.0.0.1:51046_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1187044 INFO  (qtp13927741-12142) [n:127.0.0.1:51029_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@ba7d62[policiesTest_shard1_replica_n2] main]
   [junit4]   2> 1187044 INFO  (qtp31329214-12179) [n:127.0.0.1:51046_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1763f9e[policiesTest_shard1_replica_n1] main]
   [junit4]   2> 1187046 INFO  (qtp31329214-12179) [n:127.0.0.1:51046_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1187046 INFO  (qtp13927741-12142) [n:127.0.0.1:51029_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1187046 INFO  (qtp31329214-12179) [n:127.0.0.1:51046_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1187046 INFO  (qtp13927741-12142) [n:127.0.0.1:51029_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1187046 INFO  (qtp31329214-12179) [n:127.0.0.1:51046_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1187046 INFO  (qtp13927741-12142) [n:127.0.0.1:51029_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1187047 INFO  (qtp31329214-12179) [n:127.0.0.1:51046_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1570210867169984512
   [junit4]   2> 1187047 INFO  (qtp13927741-12142) [n:127.0.0.1:51029_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1570210867169984512
   [junit4]   2> 1187048 INFO  (searcherExecutor-4747-thread-1-processing-n:127.0.0.1:51046_solr x:policiesTest_shard1_replica_n1 s:shard1 c:policiesTest) [n:127.0.0.1:51046_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n1] o.a.s.c.SolrCore [policiesTest_shard1_replica_n1] Registered new searcher Searcher@1763f9e[policiesTest_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1187049 INFO  (searcherExecutor-4748-thread-1-processing-n:127.0.0.1:51029_solr x:policiesTest_shard1_replica_n2 s:shard1 c:policiesTest) [n:127.0.0.1:51029_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n2] o.a.s.c.SolrCore [policiesTest_shard1_replica_n2] Registered new searcher Searcher@ba7d62[policiesTest_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1187054 INFO  (qtp13927741-12142) [n:127.0.0.1:51029_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1187054 INFO  (qtp13927741-12142) [n:127.0.0.1:51029_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1187054 INFO  (qtp13927741-12142) [n:127.0.0.1:51029_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n2] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:51029/solr/policiesTest_shard1_replica_n2/
   [junit4]   2> 1187056 INFO  (qtp13927741-12142) [n:127.0.0.1:51029_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n2] o.a.s.u.PeerSync PeerSync: core=policiesTest_shard1_replica_n2 url=http://127.0.0.1:51029/solr START replicas=[http://127.0.0.1:51046/solr/policiesTest_shard1_replica_n1/] nUpdates=100
   [junit4]   2> 1187056 INFO  (qtp13927741-12142) [n:127.0.0.1:51029_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n2] o.a.s.u.PeerSync PeerSync: core=policiesTest_shard1_replica_n2 url=http://127.0.0.1:51029/solr DONE.  We have no versions.  sync failed.
   [junit4]   2> 1187059 INFO  (qtp31329214-12175) [n:127.0.0.1:51046_solr c:policiesTest s:shard1 r:core_node1 x:policiesTest_shard1_replica_n1] o.a.s.c.S.Request [policiesTest_shard1_replica_n1]  webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1187059 INFO  (qtp13927741-12142) [n:127.0.0.1:51029_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n2] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
   [junit4]   2> 1187059 INFO  (qtp13927741-12142) [n:127.0.0.1:51029_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
   [junit4]   2> 1187059 INFO  (qtp13927741-12142) [n:127.0.0.1:51029_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
   [junit4]   2> 1187061 INFO  (qtp13927741-12142) [n:127.0.0.1:51029_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:51029/solr/policiesTest_shard1_replica_n2/ shard1
   [junit4]   2> 1187166 INFO  (zkCallback-2117-thread-2-processing-n:127.0.0.1:51029_solr) [n:127.0.0.1:51029_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/policiesTest/state.json] for collection [policiesTest] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 1187166 INFO  (zkCallback-2119-thread-2-processing-n:127.0.0.1:51046_solr) [n:127.0.0.1:51046_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/policiesTest/state.json] for collection [policiesTest] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 1187213 INFO  (qtp13927741-12142) [n:127.0.0.1:51029_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n2] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1187217 INFO  (qtp13927741-12142) [n:127.0.0.1:51029_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=policiesTest_shard1_replica_n2&action=CREATE&numShards=1&collection=policiesTest&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1296
   [junit4]   2> 1187316 INFO  (zkCallback-2117-thread-2-processing-n:127.0.0.1:51029_solr) [n:127.0.0.1:51029_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/policiesTest/state.json] for collection [policiesTest] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 1187316 INFO  (zkCallback-2119-thread-2-processing-n:127.0.0.1:51046_solr) [n:127.0.0.1:51046_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/policiesTest/state.json] for collection [policiesTest] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 1188059 INFO  (qtp31329214-12179) [n:127.0.0.1:51046_solr c:policiesTest s:shard1  x:policiesTest_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=policiesTest_shard1_replica_n1&action=CREATE&numShards=1&collection=policiesTest&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2142
   [junit4]   2> 1188061 INFO  (qtp13927741-12143) [n:127.0.0.1:51029_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> 1188163 INFO  (zkCallback-2119-thread-2-processing-n:127.0.0.1:51046_solr) [n:127.0.0.1:51046_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/policiesTest/state.json] for collection [policiesTest] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 1188163 INFO  (zkCallback-2117-thread-2-processing-n:127.0.0.1:51029_solr) [n:127.0.0.1:51029_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/policiesTest/state.json] for collection [policiesTest] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 1189062 INFO  (qtp13927741-12143) [n:127.0.0.1:51029_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={shards=shard1&replicationFactor=2&collection.configName=conf&name=policiesTest&router.name=implicit&nrtReplicas=2&action=CREATE&wt=javabin&version=2} status=0 QTime=3253
   [junit4]   2> 1189078 INFO  (qtp31329214-12172) [n:127.0.0.1:51046_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=2
   [junit4]   2> 1189079 INFO  (qtp13927741-12141) [n:127.0.0.1:51029_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addrole with params node=127.0.0.1:51036_solr&role=overseer&action=ADDROLE&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1189081 INFO  (OverseerCollectionConfigSetProcessor-98138179079372810-127.0.0.1:51036_solr-n_0000000000) [n:127.0.0.1:51036_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> 1189082 INFO  (OverseerThreadFactory-4742-thread-2-processing-n:127.0.0.1:51036_solr) [n:127.0.0.1:51036_solr    ] o.a.s.c.OverseerRoleCmd Overseer role added to 127.0.0.1:51036_solr
   [junit4]   2> 1189085 INFO  (qtp13927741-12141) [n:127.0.0.1:51029_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:51036_solr&role=overseer&action=ADDROLE&wt=javabin&version=2} status=0 QTime=5
   [junit4]   2> 1190095 INFO  (qtp33450830-12161) [n:127.0.0.1:51036_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=system.properties&property=java.version&property=java.vendor&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1190095 INFO  (TEST-TestPolicyCloud.testDataProvider-seed#[DF0BF1BC1F11E7E7]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
   [junit4]   2> 1190099 INFO  (qtp13927741-12140) [n:127.0.0.1:51029_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=policiesTest&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1190100 INFO  (OverseerCollectionConfigSetProcessor-98138179079372810-127.0.0.1:51036_solr-n_0000000000) [n:127.0.0.1:51036_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> 1190101 INFO  (OverseerThreadFactory-4742-thread-3-processing-n:127.0.0.1:51036_solr) [n:127.0.0.1:51036_solr    ] o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
   [junit4]   2> 1190102 INFO  (qtp31329214-12186) [n:127.0.0.1:51046_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.policiesTest.shard1.replica_n1, tag=null
   [junit4]   2> 1190102 INFO  (qtp13927741-12139) [n:127.0.0.1:51029_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.policiesTest.shard1.replica_n2, tag=null
   [junit4]   2> 1190107 INFO  (qtp13927741-12139) [n:127.0.0.1:51029_solr    ] o.a.s.c.SolrCore [policiesTest_shard1_replica_n2]  CLOSING SolrCore org.apache.solr.core.SolrCore@1842ef
   [junit4]   2> 1190108 INFO  (qtp31329214-12186) [n:127.0.0.1:51046_solr    ] o.a.s.c.SolrCore [policiesTest_shard1_replica_n1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1127fca
   [junit4]   2> 1190109 INFO  (qtp13927741-12139) [n:127.0.0.1:51029_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.policiesTest.shard1.replica_n2, tag=1589999
   [junit4]   2> 1190109 INFO  (qtp13927741-12139) [n:127.0.0.1:51029_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.policiesTest.shard1.leader, tag=1589999
   [junit4]   2> 1190109 INFO  (qtp31329214-12186) [n:127.0.0.1:51046_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.policiesTest.shard1.replica_n1, tag=17989578
   [junit4]   2> 1190109 INFO  (qtp31329214-12186) [n:127.0.0.1:51046_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.policiesTest.shard1.leader, tag=17989578
   [junit4]   2> 1190114 INFO  (qtp31329214-12186) [n:127.0.0.1:51046_solr    ] o.a.s.c.ShardLeaderElectionContextBase No version found for ephemeral leader parent node, won't remove previous leader registration.
   [junit4]   2> 1190114 INFO  (qtp31329214-12186) [n:127.0.0.1:51046_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=policiesTest_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=12
   [junit4]   2> 1190114 WARN  (zkCallback-2119-thread-2-processing-n:127.0.0.1:51046_solr) [n:127.0.0.1:51046_solr    ] o.a.s.c.LeaderElector Our node is no longer in line to be leader
   [junit4]   2> 1190116 INFO  (qtp13927741-12139) [n:127.0.0.1:51029_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=policiesTest_shard1_replica_n2&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=14
   [junit4]   2> 1190821 INFO  (qtp13927741-12140) [n:127.0.0.1:51029_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=policiesTest&action=DELETE&wt=javabin&version=2} status=0 QTime=721
   [junit4]   2> 1190822 INFO  (TEST-TestPolicyCloud.testDataProvider-seed#[DF0BF1BC1F11E7E7]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testDataProvider
   [junit4]   2> 1190847 INFO  (TEST-TestPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[DF0BF1BC1F11E7E7]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testCreateCollectionAddShardUsingPolicy
   [junit4]   2> 1190879 INFO  (qtp13927741-12197) [n:127.0.0.1:51029_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
   [junit4]   2> 1190880 INFO  (qtp13927741-12197) [n:127.0.0.1:51029_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:51021/solr ready
   [junit4]   2> 1190887 INFO  (qtp13927741-12196) [n:127.0.0.1:51029_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
   [junit4]   2> 1190890 INFO  (qtp11690963-12149) [n:127.0.0.1:51032_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
   [junit4]   2> 1190891 INFO  (qtp14047025-12183) [n:127.0.0.1:51026_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
   [junit4]   2> 1190892 INFO  (qtp33450830-12159) [n:127.0.0.1:51036_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
   [junit4]   2> 1190893 INFO  (qtp31329214-12187) [n:127.0.0.1:51046_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
   [junit4]   2> 1190895 INFO  (qtp13927741-12197) [n:127.0.0.1:51029_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=45
   [junit4]   2> 1190896 INFO  (qtp13927741-12146) [n:127.0.0.1:51029_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params shards=s1,s2&replicationFactor=1&name=policiesTest&router.name=implicit&nrtReplicas=1&action=CREATE&wt=javabin&version=2&policy=c1 and sendToOCPQueue=true
   [junit4]   2> 1190897 INFO  (OverseerThreadFactory-4742-thread-4-processing-n:127.0.0.1:51036_solr) [n:127.0.0.1:51036_solr    ] o.a.s.c.CreateCollectionCmd Create collection policiesTest
   [junit4]   2> 1190898 INFO  (OverseerCollectionConfigSetProcessor-98138179079372810-127.0.0.1:51036_solr-n_0000000000) [n:127.0.0.1:51036_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> 1190898 INFO  (OverseerThreadFactory-4742-thread-4-processing-n:127.0.0.1:51036_solr) [n:127.0.0.1:51036_solr    ] o.a.s.c.CreateCollectionCmd Only one config set found in zk - using it:conf
   [junit4]   2> 1190905 INFO  (qtp13927741-12143) [n:127.0.0.1:51029_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
   [junit4]   2> 1190906 INFO  (qtp11690963-12151) [n:127.0.0.1:51032_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
   [junit4]   2> 1190907 INFO  (qtp14047025-12137) [n:127.0.0.1:51026_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
   [junit4]   2> 1190908 INFO  (qtp33450830-12157) [n:127.0.0.1:51036_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
   [junit4]   2> 1190909 INFO  (qtp31329214-12176) [n:127.0.0.1:51046_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
   [junit4]   2> 1191128 INFO  (qtp14047025-12132) [n:127.0.0.1:51026_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=policiesTest_s2_replica_n1&action=CREATE&numShards=2&collection=policiesTest&shard=s2&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1191128 INFO  (qtp14047025-12136) [n:127.0.0.1:51026_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=policiesTest_s1_replica_n1&action=CREATE&numShards=2&collection=policiesTest&shard=s1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1191129 INFO  (qtp14047025-12136) [n:127.0.0.1:51026_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 1191235 INFO  (zkCallback-2118-thread-2-processing-n:127.0.0.1:51026_solr) [n:127.0.0.1:51026_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/policiesTest/state.json] for collection [policiesTest] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 1192163 INFO  (qtp14047025-12132) [n:127.0.0.1:51026_solr c:policiesTest s:s2  x:policiesTest_s2_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1192165 INFO  (qtp14047025-12136) [n:127.0.0.1:51026_solr c:policiesTest s:s1  x:policiesTest_s1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1192169 INFO  (qtp14047025-12132) [n:127.0.0.1:51026_solr c:policiesTest s:s2  x:policiesTest_s2_replica_n1] o.a.s.s.IndexSchema [policiesTest_s2_replica_n1] Schema name=minimal
   [junit4]   2> 1192169 INFO  (qtp14047025-12136) [n:127.0.0.1:51026_solr c:policiesTest s:s1  x:policiesTest_s1_replica_n1] o.a.s.s.IndexSchema [policiesTest_s1_replica_n1] Schema name=minimal
   [junit4]   2> 1192173 INFO  (qtp14047025-12132) [n:127.0.0.1:51026_solr c:policiesTest s:s2  x:policiesTest_s2_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1192173 INFO  (qtp14047025-12136) [n:127.0.0.1:51026_solr c:policiesTest s:s1  x:policiesTest_s1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1192174 INFO  (qtp14047025-12132) [n:127.0.0.1:51026_solr c:policiesTest s:s2  x:policiesTest_s2_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'policiesTest_s2_replica_n1' using configuration from collection policiesTest, trusted=true
   [junit4]   2> 1192174 INFO  (qtp14047025-12136) [n:127.0.0.1:51026_solr c:policiesTest s:s1  x:policiesTest_s1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'policiesTest_s1_replica_n1' using configuration from collection policiesTest, trusted=true
   [junit4]   2> 1192174 INFO  (qtp14047025-12136) [n:127.0.0.1:51026_solr c:policiesTest s:s1  x:policiesTest_s1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51026.solr.core.policiesTest.s1.replica_n1' (registry 'solr.core.policiesTest.s1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b1ca6c
   [junit4]   2> 1192174 INFO  (qtp14047025-12132) [n:127.0.0.1:51026_solr c:policiesTest s:s2  x:policiesTest_s2_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51026.solr.core.policiesTest.s2.replica_n1' (registry 'solr.core.policiesTest.s2.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b1ca6c
   [junit4]   2> 1192174 INFO  (qtp14047025-12136) [n:127.0.0.1:51026_solr c:policiesTest s:s1  x:policiesTest_s1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1192174 INFO  (qtp14047025-12132) [n:127.0.0.1:51026_solr c:policiesTest s:s2  x:policiesTest_s2_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1192174 INFO  (qtp14047025-12136) [n:127.0.0.1:51026_solr c:policiesTest s:s1  x:policiesTest_s1_replica_n1] o.a.s.c.SolrCore [[policiesTest_s1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TestPolicyCloud_DF0BF1BC1F11E7E7-001\tempDir-001\node2\policiesTest_s1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TestPolicyCloud_DF0BF1BC1F11E7E7-001\tempDir-001\node2\.\policiesTest_s1_replica_n1\data\]
   [junit4]   2> 1192174 INFO  (qtp14047025-12132) [n:127.0.0.1:51026_solr c:policiesTest s:s2  x:policiesTest_s2_replica_n1] o.a.s.c.SolrCore [[policiesTest_s2_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TestPolicyCloud_DF0BF1BC1F11E7E7-001\tempDir-001\node2\policiesTest_s2_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TestPolicyCloud_DF0BF1BC1F11E7E7-001\tempDir-001\node2\.\policiesTest_s2_replica_n1\data\]
   [junit4]   2> 1192263 INFO  (qtp14047025-12136) [n:127.0.0.1:51026_solr c:policiesTest s:s1  x:policiesTest_s1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1192263 INFO  (qtp14047025-12132) [n:127.0.0.1:51026_solr c:policiesTest s:s2  x:policiesTest_s2_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1192263 INFO  (qtp14047025-12136) [n:127.0.0.1:51026_solr c:policiesTest s:s1  x:policiesTest_s1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1192263 INFO  (qtp14047025-12132) [n:127.0.0.1:51026_solr c:policiesTest s:s2  x:policiesTest_s2_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1192265 INFO  (qtp14047025-12132) [n:127.0.0.1:51026_solr c:policiesTest s:s2  x:policiesTest_s2_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1192266 INFO  (qtp14047025-12132) [n:127.0.0.1:51026_solr c:policiesTest s:s2  x:policiesTest_s2_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1192266 INFO  (qtp14047025-12136) [n:127.0.0.1:51026_solr c:policiesTest s:s1  x:policiesTest_s1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1192266 INFO  (qtp14047025-12136) [n:127.0.0.1:51026_solr c:policiesTest s:s1  x:policiesTest_s1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1192267 INFO  (qtp14047025-12132) [n:127.0.0.1:51026_solr c:policiesTest s:s2  x:policiesTest_s2_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1be6095[policiesTest_s2_replica_n1] main]
   [junit4]   2> 1192267 INFO  (qtp14047025-12136) [n:127.0.0.1:51026_solr c:policiesTest s:s1  x:policiesTest_s1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1926a9a[policiesTest_s1_replica_n1] main]
   [junit4]   2> 1192268 INFO  (qtp14047025-12136) [n:127.0.0.1:51026_solr c:policiesTest s:s1  x:policiesTest_s1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1192268 INFO  (qtp14047025-12132) [n:127.0.0.1:51026_solr c:policiesTest s:s2  x:policiesTest_s2_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1192269 INFO  (qtp14047025-12136) [n:127.0.0.1:51026_solr c:policiesTest s:s1  x:policiesTest_s1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1192269 INFO  (qtp14047025-12136) [n:127.0.0.1:51026_solr c:policiesTest s:s1  x:policiesTest_s1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1192269 INFO  (qtp14047025-12136) [n:127.0.0.1:51026_solr c:policiesTest s:s1  x:policiesTest_s1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1570210872645648384
   [junit4]   2> 1192271 INFO  (searcherExecutor-4758-thread-1-processing-n:127.0.0.1:51026_solr x:policiesTest_s1_replica_n1 s:s1 c:policiesTest) [n:127.0.0.1:51026_solr c:policiesTest s:s1  x:policiesTest_s1_replica_n1] o.a.s.c.SolrCore [policiesTest_s1_replica_n1] Registered new searcher Searcher@1926a9a[policiesTest_s1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1192271 INFO  (qtp14047025-12132) [n:127.0.0.1:51026_solr c:policiesTest s:s2  x:policiesTest_s2_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1192272 INFO  (qtp14047025-12132) [n:127.0.0.1:51026_solr c:policiesTest s:s2  x:policiesTest_s2_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1192272 INFO  (qtp14047025-12132) [n:127.0.0.1:51026_solr c:policiesTest s:s2  x:policiesTest_s2_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1570210872648794112
   [junit4]   2> 1192274 INFO  (searcherExecutor-4757-thread-1-processing-n:127.0.0.1:51026_solr x:policiesTest_s2_replica_n1 s:s2 c:policiesTest) [n:127.0.0.1:51026_solr c:policiesTest s:s2  x:policiesTest_s2_replica_n1] o.a.s.c.SolrCore [policiesTest_s2_replica_n1] Registered new searcher Searcher@1be6095[policiesTest_s2_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1192278 INFO  (qtp14047025-12136) [n:127.0.0.1:51026_solr c:policiesTest s:s1  x:policiesTest_s1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1192278 INFO  (qtp14047025-12136) [n:127.0.0.1:51026_solr c:policiesTest s:s1  x:policiesTest_s1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1192278 INFO  (qtp14047025-12136) [n:127.0.0.1:51026_solr c:policiesTest s:s1  x:policiesTest_s1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:51026/solr/policiesTest_s1_replica_n1/
   [junit4]   2> 1192278 INFO  (qtp14047025-12136) [n:127.0.0.1:51026_solr c:policiesTest s:s1  x:policiesTest_s1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 1192278 INFO  (qtp14047025-12132) [n:127.0.0.1:51026_solr c:policiesTest s:s2  x:policiesTest_s2_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1192278 INFO  (qtp14047025-12132) [n:127.0.0.1:51026_solr c:policiesTest s:s2  x:policiesTest_s2_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1192278 INFO  (qtp14047025-12132) [n:127.0.0.1:51026_solr c:policiesTest s:s2  x:policiesTest_s2_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:51026/solr/policiesTest_s2_replica_n1/
   [junit4]   2> 1192278 INFO  (qtp14047025-12136) [n:127.0.0.1:51026_solr c:policiesTest s:s1  x:policiesTest_s1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:51026/solr/policiesTest_s1_replica_n1/ has no replicas
   [junit4]   2> 1192278 INFO  (qtp14047025-12136) [n:127.0.0.1:51026_solr c:policiesTest s:s1  x:policiesTest_s1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
   [junit4]   2> 1192278 INFO  (qtp14047025-12132) [n:127.0.0.1:51026_solr c:policiesTest s:s2  x:policiesTest_s2_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 1192278 INFO  (qtp14047025-12132) [n:127.0.0.1:51026_solr c:policiesTest s:s2  x:policiesTest_s2_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:51026/solr/policiesTest_s2_replica_n1/ has no replicas
   [junit4]   2> 1192278 INFO  (qtp14047025-12132) [n:127.0.0.1:51026_solr c:policiesTest s:s2  x:policiesTest_s2_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
   [junit4]   2> 1192284 INFO  (qtp14047025-12136) [n:127.0.0.1:51026_solr c:policiesTest s:s1  x:policiesTest_s1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:51026/solr/policiesTest_s1_replica_n1/ s1
   [junit4]   2> 1192284 INFO  (qtp14047025-12132) [n:127.0.0.1:51026_solr c:policiesTest s:s2  x:policiesTest_s2_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:51026/solr/policiesTest_s2_replica_n1/ s2
   [junit4]   2> 1192387 INFO  (zkCallback-2118-thread-2-processing-n:127.0.0.1:51026_solr) [n:127.0.0.1:51026_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/policiesTest/state.json] for collection [policiesTest] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 1192437 INFO  (qtp14047025-12136) [n:127.0.0.1:51026_solr c:policiesTest s:s1  x:policiesTest_s1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1192437 INFO  (qtp14047025-12132) [n:127.0.0.1:51026_solr c:policiesTest s:s2  x:policiesTest_s2_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1192440 INFO  (qtp14047025-12136) [n:127.0.0.1:51026_solr c:policiesTest s:s1  x:policiesTest_s1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=policiesTest_s1_replica_n1&action=CREATE&numShards=2&collection=policiesTest&shard=s1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1312
   [junit4]   2> 1192441 INFO  (qtp14047025-12132) [n:127.0.0.1:51026_solr c:policiesTest s:s2  x:policiesTest_s2_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=policiesTest_s2_replica_n1&action=CREATE&numShards=2&collection=policiesTest&shard=s2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1312
   [junit4]   2> 1192443 INFO  (qtp13927741-12146) [n:127.0.0.1:51029_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> 1192545 INFO  (zkCallback-2118-thread-2-processing-n:127.0.0.1:51026_solr) [n:127.0.0.1:51026_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/policiesTest/state.json] for collection [policiesTest] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 1192900 INFO  (OverseerCollectionConfigSetProcessor-98138179079372810-127.0.0.1:51036_solr-n_0000000000) [n:127.0.0.1:51036_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> 1193444 INFO  (qtp13927741-12146) [n:127.0.0.1:51029_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={shards=s1,s2&replicationFactor=1&name=policiesTest&router.name=implicit&nrtReplicas=1&action=CREATE&wt=javabin&version=2&policy=c1} status=0 QTime=2548
   [junit4]   2> 1193446 INFO  (qtp13927741-12141) [n:127.0.0.1:51029_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :createshard with params action=CREATESHARD&collection=policiesTest&shard=s3&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1193448 INFO  (OverseerThreadFactory-4742-thread-5-processing-n:127.0.0.1:51036_solr) [n:127.0.0.1:51036_solr    ] o.a.s.c.CreateShardCmd Create shard invoked: {
   [junit4]   2>   "collection":"policiesTest",
   [junit4]   2>   "shard":"s3",
   [junit4]   2>   "operation":"createshard"}
   [junit4]   2> 1193455 INFO  (qtp13927741-12198) [n:127.0.0.1:51029_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
   [junit4]   2> 1193456 INFO  (qtp11690963-12152) [n:127.0.0.1:51032_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
   [junit4]   2> 1193458 INFO  (qtp14047025-12130) [n:127.0.0.1:51026_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=1
   [junit4]   2> 1193459 INFO  (qtp33450830-12191) [n:127.0.0.1:51036_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
   [junit4]   2> 1193461 INFO  (qtp31329214-12174) [n:127.0.0.1:51046_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
   [junit4]   2> 1193567 INFO  (zkCallback-2118-thread-2-processing-n:127.0.0.1:51026_solr) [n:127.0.0.1:51026_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/policiesTest/state.json] for collection [policiesTest] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 1193666 INFO  (OverseerThreadFactory-4742-thread-5-processing-n:127.0.0.1:51036_solr) [n:127.0.0.1:51036_solr    ] o.a.s.c.CreateShardCmd Creating replica policiesTest_s3_replica_n1 as part of slice s3 of collection policiesTest on 127.0.0.1:51026_solr
   [junit4]   2> 1193667 INFO  (qtp14047025-12185) [n:127.0.0.1:51026_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&name=policiesTest_s3_replica_n1&action=CREATE&numShards=1&collection=policiesTest&shard=s3&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1193772 INFO  (zkCallback-2118-thread-2-processing-n:127.0.0.1:51026_solr) [n:127.0.0.1:51026_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/policiesTest/state.json] for collection [policiesTest] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 1194683 INFO  (qtp14047025-12185) [n:127.0.0.1:51026_solr c:policiesTest s:s3  x:policiesTest_s3_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1194688 INFO  (qtp14047025-12185) [n:127.0.0.1:51026_solr c:policiesTest s:s3  x:policiesTest_s3_replica_n1] o.a.s.s.IndexSchema [policiesTest_s3_replica_n1] Schema name=minimal
   [junit4]   2> 1194692 INFO  (qtp14047025-12185) [n:127.0.0.1:51026_solr c:policiesTest s:s3  x:policiesTest_s3_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1194692 INFO  (qtp14047025-12185) [n:127.0.0.1:51026_solr c:policiesTest s:s3  x:policiesTest_s3_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'policiesTest_s3_replica_n1' using configuration from collection policiesTest, trusted=true
   [junit4]   2> 1194692 INFO  (qtp14047025-12185) [n:127.0.0.1:51026_solr c:policiesTest s:s3  x:policiesTest_s3_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51026.solr.core.policiesTest.s3.replica_n1' (registry 'solr.core.policiesTest.s3.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b1ca6c
   [junit4]   2> 1194692 INFO  (qtp14047025-12185) [n:127.0.0.1:51026_solr c:policiesTest s:s3  x:policiesTest_s3_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1194692 INFO  (qtp14047025-12185) [n:127.0.0.1:51026_solr c:policiesTest s:s3  x:policiesTest_s3_replica_n1] o.a.s.c.SolrCore [[policiesTest_s3_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TestPolicyCloud_DF0BF1BC1F11E7E7-001\tempDir-001\node2\policiesTest_s3_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TestPolicyCloud_DF0BF1BC1F11E7E7-001\tempDir-001\node2\.\policiesTest_s3_replica_n1\data\]
   [junit4]   2> 1194773 INFO  (qtp14047025-12185) [n:127.0.0.1:51026_solr c:policiesTest s:s3  x:policiesTest_s3_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1194773 INFO  (qtp14047025-12185) [n:127.0.0.1:51026_solr c:policiesTest s:s3  x:policiesTest_s3_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1194776 INFO  (qtp14047025-12185) [n:127.0.0.1:51026_solr c:policiesTest s:s3  x:policiesTest_s3_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1194776 INFO  (qtp14047025-12185) [n:127.0.0.1:51026_solr c:policiesTest s:s3  x:policiesTest_s3_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1194776 INFO  (qtp14047025-12185) [n:127.0.0.1:51026_solr c:policiesTest s:s3  x:policiesTest_s3_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@109f906[policiesTest_s3_replica_n1] main]
   [junit4]   2> 1194778 INFO  (qtp14047025-12185) [n:127.0.0.1:51026_solr c:policiesTest s:s3  x:policiesTest_s3_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1194778 INFO  (qtp14047025-12185) [n:127.0.0.1:51026_solr c:policiesTest s:s3  x:policiesTest_s3_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1194779 INFO  (qtp14047025-12185) [n:127.0.0.1:51026_solr c:policiesTest s:s3  x:policiesTest_s3_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1194779 INFO  (searcherExecutor-4767-thread-1-processing-n:127.0.0.1:51026_solr x:policiesTest_s3_replica_n1 s:s3 c:policiesTest) [n:127.0.0.1:51026_solr c:policiesTest s:s3  x:policiesTest_s3_replica_n1] o.a.s.c.SolrCore [policiesTest_s3_replica_n1] Registered new searcher Searcher@109f906[policiesTest_s3_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1194780 INFO  (qtp14047025-12185) [n:127.0.0.1:51026_solr c:policiesTest s:s3  x:policiesTest_s3_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1570210875278622720
   [junit4]   2> 1194784 INFO  (qtp14047025-12185) [n:127.0.0.1:51026_solr c:policiesTest s:s3  x:policiesTest_s3_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1194784 INFO  (qtp14047025-12185) [n:127.0.0.1:51026_solr c:policiesTest s:s3  x:policiesTest_s3_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1194784 INFO  (qtp14047025-12185) [n:127.0.0.1:51026_solr c:policiesTest s:s3  x:policiesTest_s3_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:51026/solr/policiesTest_s3_replica_n1/
   [junit4]   2> 1194784 INFO  (qtp14047025-12185) [n:127.0.0.1:51026_solr c:policiesTest s:s3  x:policiesTest_s3_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 1194784 INFO  (qtp14047025-12185) [n:127.0.0.1:51026_solr c:policiesTest s:s3  x:policiesTest_s3_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:51026/solr/policiesTest_s3_replica_n1/ has no replicas
   [junit4]   2> 1194784 INFO  (qtp14047025-12185) [n:127.0.0.1:51026_solr c:policiesTest s:s3  x:policiesTest_s3_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
   [junit4]   2> 1194787 INFO  (qtp14047025-12185) [n:127.0.0.1:51026_solr c:policiesTest s:s3  x:policiesTest_s3_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:51026/solr/policiesTest_s3_replica_n1/ s3
   [junit4]   2> 1194891 INFO  (zkCallback-2118-thread-2-processing-n:127.0.0.1:51026_solr) [n:127.0.0.1:51026_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/policiesTest/state.json] for collection [policiesTest] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 1194939 INFO  (qtp14047025-12185) [n:127.0.0.1:51026_solr c:policiesTest s:s3  x:policiesTest_s3_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1194942 INFO  (qtp14047025-12185) [n:127.0.0.1:51026_solr c:policiesTest s:s3  x:policiesTest_s3_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&name=policiesTest_s3_replica_n1&action=CREATE&numShards=1&collection=policiesTest&shard=s3&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1275
   [junit4]   2> 1194942 INFO  (OverseerThreadFactory-4742-thread-5-processing-n:127.0.0.1:51036_solr) [n:127.0.0.1:51036_solr    ] o.a.s.c.CreateShardCmd Finished create command on all shards for collection: policiesTest
   [junit4]   2> 1194944 INFO  (qtp13927741-12141) [n:127.0.0.1:51029_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CREATESHARD&collection=policiesTest&shard=s3&wt=javabin&version=2} status=0 QTime=1498
   [junit4]   2> 1194945 INFO  (TEST-TestPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[DF0BF1BC1F11E7E7]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
   [junit4]   2> 1194947 INFO  (qtp13927741-12196) [n:127.0.0.1:51029_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=policiesTest&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1194949 INFO  (OverseerCollectionConfigSetProcessor-98138179079372810-127.0.0.1:51036_solr-n_0000000000) [n:127.0.0.1:51036_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> 119

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

ta from client sessionid 0x15ca83ae2570005, 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> 2466119 WARN  (zkCallback-5180-thread-2-processing-n:127.0.0.1:60773_solr) [n:127.0.0.1:60773_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 2466120 INFO  (jetty-closer-5170-thread-2) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5e550d{/solr,null,UNAVAILABLE}
   [junit4]   2> 2466121 ERROR (SUITE-V2ApiIntegrationTest-seed#[DF0BF1BC1F11E7E7]-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> 2466122 INFO  (SUITE-V2ApiIntegrationTest-seed#[DF0BF1BC1F11E7E7]-worker) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:60769 60769
   [junit4]   2> 2466123 INFO  (Thread-6210) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:60769 60769
   [junit4]   2> 2466124 WARN  (Thread-6210) [    ] o.a.s.c.ZkTestServer Watch limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2> 	3	/solr/aliases.json
   [junit4]   2> 	3	/solr/clusterprops.json
   [junit4]   2> 	2	/solr/configs/conf1/managed-schema
   [junit4]   2> 	2	/solr/security.json
   [junit4]   2> 	2	/solr/configs/conf1
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2> 	3	/solr/clusterstate.json
   [junit4]   2> 	3	/solr/clusterprops.json
   [junit4]   2> 	2	/solr/collections/collection1/state.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2> 	3	/solr/live_nodes
   [junit4]   2> 	3	/solr/collections
   [junit4]   2> 
   [junit4]   2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.V2ApiIntegrationTest_DF0BF1BC1F11E7E7-001
   [junit4]   2> Jun 14, 2017 8:10:54 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 4 leaked thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=283, maxMBSortInHeap=6.894696624484796, sim=RandomSimilarity(queryNorm=false): {}, locale=en-CA, timezone=AGT
   [junit4]   2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_131 (32-bit)/cpus=3,threads=1,free=250661024,total=518979584
   [junit4]   2> NOTE: All tests run in this JVM: [TestPostingsSolrHighlighter, TestFastOutputStream, TestSolrJ, TestCoreContainer, TestCloudDeleteByQuery, TestDistributedSearch, DistributedFacetPivotSmallTest, TestHttpShardHandlerFactory, DeleteInactiveReplicaTest, TestConfigsApi, RequiredFieldsTest, TermsComponentTest, TestRTimerTree, DistributedSpellCheckComponentTest, DistributedFacetPivotLongTailTest, TestBulkSchemaConcurrent, CleanupOldIndexTest, TestOverriddenPrefixQueryForCustomFieldType, TestStressReorder, SolrMetricManagerTest, TestCharFilters, ImplicitSnitchTest, OverseerTest, AnalysisAfterCoreReloadTest, OpenExchangeRatesOrgProviderTest, DateMathParserTest, TestManagedSynonymGraphFilterFactory, TestJsonRequest, SimpleMLTQParserTest, HdfsRecoverLeaseTest, SoftAutoCommitTest, TestSolrCloudWithHadoopAuthPlugin, MBeansHandlerTest, TestFieldSortValues, PluginInfoTest, ZkCLITest, TestManagedStopFilterFactory, SolrCLIZkUtilsTest, StandardRequestHandlerTest, IndexSchemaRuntimeFieldTest, CdcrBootstrapTest, TestCSVLoader, TestSchemaNameResource, AutoScalingHandlerTest, TestIBSimilarityFactory, SolrPluginUtilsTest, JSONWriterTest, AddBlockUpdateTest, TestCSVResponseWriter, TestCloudNestedDocsSort, TestTrackingShardHandlerFactory, TestLFUCache, RecoveryZkTest, BooleanFieldTest, InfoHandlerTest, ShardSplitTest, LeaderInitiatedRecoveryOnShardRestartTest, DistributedFacetExistsSmallTest, LeaderElectionContextKeyTest, TestReloadAndDeleteDocs, DistributedMLTComponentTest, TestDownShardTolerantSearch, TestConfigSetsAPI, ChaosMonkeySafeLeaderWithPullReplicasTest, ConfigureRecoveryStrategyTest, CollectionReloadTest, TestShortCircuitedRequests, TestInitQParser, StressHdfsTest, RestartWhileUpdatingTest, EnumFieldTest, BlockCacheTest, TestGroupingSearch, DeleteNodeTest, TestDelegationWithHadoopAuth, TestSimpleQParserPlugin, TestSystemCollAutoCreate, BasicAuthStandaloneTest, DeleteShardTest, TestSort, SolrShardReporterTest, TestPseudoReturnFields, TestSystemIdResolver, TestPayloadScoreQParserPlugin, TestTestInjection, ResourceLoaderTest, TestSolrCloudWithDelegationTokens, TestDynamicFieldCollectionResource, PingRequestHandlerTest, CursorPagingTest, TestMergePolicyConfig, SearchHandlerTest, HdfsUnloadDistributedZkTest, TestFieldCollectionResource, CopyFieldTest, DateFieldTest, TestChildDocTransformer, DistributedQueryElevationComponentTest, BinaryUpdateRequestHandlerTest, SchemaVersionSpecificBehaviorTest, NumericFieldsTest, ParsingFieldUpdateProcessorsTest, TestPartialUpdateDeduplication, SpellingQueryConverterTest, DocExpirationUpdateProcessorFactoryTest, TestStressVersions, TestSolrDeletionPolicy1, TestDFISimilarityFactory, TestHdfsCloudBackupRestore, SolrTestCaseJ4Test, TestPhraseSuggestions, TestConfigSetsAPIZkFailure, PeerSyncWithIndexFingerprintCachingTest, SolrCoreTest, TestFaceting, SimpleFacetsTest, SolrMetricsIntegrationTest, TestSolrCoreSnapshots, DistributedSuggestComponentTest, TestBackupRepositoryFactory, DirectSolrSpellCheckerTest, TestImplicitCoreProperties, TestCursorMarkWithoutUniqueKey, TestElisionMultitermQuery, SolrXmlInZkTest, ClassificationUpdateProcessorFactoryTest, BlockJoinFacetSimpleTest, MultiThreadedOCPTest, TestManagedSchemaThreadSafety, CollectionTooManyReplicasTest, TestBM25SimilarityFactory, DirectUpdateHandlerTest, TestNumericTerms64, TestLazyCores, PathHierarchyTokenizerFactoryTest, TestNumericRangeQuery64, TestPushWriter, TestRawTransformer, OverseerCollectionConfigSetProcessorTest, DocumentBuilderTest, TestBinaryResponseWriter, RulesTest, TestMiniSolrCloudClusterSSL, TestFieldCache, MergeStrategyTest, UniqFieldsUpdateProcessorFactoryTest, CurrencyFieldXmlFileTest, TestDocBasedVersionConstraints, TestCollationFieldDocValues, TestBinaryField, TestGraphMLResponseWriter, PolyFieldTest, TestRandomDVFaceting, HdfsRestartWhileUpdatingTest, ExplicitHLLTest, SolrSlf4jReporterTest, MultiTermTest, TestSerializedLuceneMatchVersion, HdfsLockFactoryTest, TestReplicationHandler, ClassificationUpdateProcessorTest, SSLMigrationTest, TestMultiWordSynonyms, PeerSyncTest, TestBlendedInfixSuggestions, AliasIntegrationTest, TestTolerantSearch, TestInPlaceUpdatesStandalone, TestCustomStream, TestFreeTextSuggestions, TestXmlQParser, PropertiesRequestHandlerTest, OverseerRolesTest, DocValuesMultiTest, BasicDistributedZkTest, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, SyncSliceTest, ShardRoutingTest, BasicZkTest, TestRandomFaceting, LeaderElectionTest, ShardRoutingCustomTest, AutoCommitTest, TestRecovery, ZkControllerTest, TestRealTimeGet, HardAutoCommitTest, SpellCheckComponentTest, QueryElevationComponentTest, BasicFunctionalityTest, HighlighterTest, CurrencyFieldOpenExchangeTest, SimplePostToolTest, TestCoreDiscovery, DocValuesTest, DirectUpdateHandlerOptimizeTest, TestRemoteStreaming, DistanceFunctionTest, DebugComponentTest, IndexBasedSpellCheckerTest, DisMaxRequestHandlerTest, DocumentAnalysisRequestHandlerTest, TestQueryTypes, XmlUpdateRequestHandlerTest, TestIndexingPerformance, MoreLikeThisHandlerTest, IndexSchemaTest, TestCollationField, QueryParsingTest, TestComponentsName, TestQuerySenderListener, TestQuerySenderNoQuery, SolrIndexConfigTest, TestSolrDeletionPolicy2, TestSearchPerf, OutputWriterTest, ExternalFileFieldSortTest, NotRequiredUniqueKeyTest, SynonymTokenizerTest, TestXIncludeConfig, TestSweetSpotSimilarityFactory, TestDFRSimilarityFactory, TestLMJelinekMercerSimilarityFactory, RAMDirectoryFactoryTest, TestUtils, FileUtilsTest, TestRTGBase, TestDistributedMissingSort, ActionThrottleTest, CdcrReplicationHandlerTest, CdcrRequestHandlerTest, CdcrVersionReplicationTest, ConfigSetsAPITest, ConnectionManagerTest, CreateCollectionCleanupTest, CustomCollectionTest, DistribCursorPagingTest, DocValuesNotIndexedTest, ForceLeaderTest, MissingSegmentRecoveryTest, MoveReplicaTest, OverseerTaskQueueTest, RemoteQueryErrorTest, ReplaceNodeTest, SaslZkACLProviderTest, SolrCloudExampleTest, TestCloudPseudoReturnFields, TestClusterProperties, TestCollectionAPI, TestDeleteCollectionOnDownNodes, TestExclusionRuleCollectionAccess, TestLeaderElectionZkExpiry, TestLockTree, TestMiniSolrCloudCluster, TestRandomFlRTGCloud, TestRebalanceLeaders, TestReplicaProperties, TestSolrCloudWithKerberosAlt, TestStressInPlaceUpdates, TestTolerantUpdateProcessorRandomCloud, TriLevelCompositeIdRoutingTest, HdfsBasicDistributedZk2Test, HdfsBasicDistributedZkTest, HdfsChaosMonkeyNothingIsSafeTest, HdfsCollectionsAPIDistributedZkTest, HdfsRecoveryZkTest, HdfsThreadLeakTest, TestClusterStateMutator, ZkStateReaderTest, ZkStateWriterTest, BlobRepositoryCloudTest, DirectoryFactoryTest, ExitableDirectoryReaderTest, HdfsDirectoryFactoryTest, TestConfigOverlay, TestConfigSetImmutable, TestConfigSetProperties, TestConfigSets, TestInfoStreamLogging, TestInitParams, TestNRTOpen, TestSimpleTextCodec, TestSolrConfigHandler, TestSolrXml, TestSolrCloudSnapshots, TestHdfsBackupRestoreCore, TestReplicationHandlerBackup, TestReqParamsAPI, TestRestoreCore, TestSolrConfigHandlerCloud, V2ApiIntegrationTest]
   [junit4] Completed [583/725 (2!)] on J0 in 4.76s, 5 tests, 1 error <<< FAILURES!

[...truncated 39869 lines...]

Mime
View raw message