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...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org