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 <?> 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 <?> 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...]
|