Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6939/ Java: 32bit/jdk1.8.0_144 -client -XX:+UseSerialGC
1 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI Error Message: Error from server at http://127.0.0.1:64414/solr/awhollynewcollection_0: {"awhollynewcollection_0":6} Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:64414/solr/awhollynewcollection_0: {"awhollynewcollection_0":6} at __randomizedtesting.SeedInfo.seed([C54CEF44785C1850:8D399BF07E6F37C5]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:627) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:253) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:242) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1121) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:862) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:967) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:967) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:967) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:967) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:967) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:793) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:178) at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:942) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI(CollectionsAPIDistributedZkTest.java:460) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) Build Log: [...truncated 12419 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CollectionsAPIDistributedZkTest_C54CEF44785C1850-001\init-core-data-001 [junit4] 2> 1734790 WARN (SUITE-CollectionsAPIDistributedZkTest-seed#[C54CEF44785C1850]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=53 numCloses=53 [junit4] 2> 1734791 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[C54CEF44785C1850]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 1734798 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[C54CEF44785C1850]-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> 1734798 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[C54CEF44785C1850]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 1734803 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[C54CEF44785C1850]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CollectionsAPIDistributedZkTest_C54CEF44785C1850-001\tempDir-001 [junit4] 2> 1734803 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[C54CEF44785C1850]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1734803 INFO (Thread-3660) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1734803 INFO (Thread-3660) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1734809 ERROR (Thread-3660) [ ] 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> 1734904 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[C54CEF44785C1850]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:64407 [junit4] 2> 1734918 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 0x15ee51c3f8c0000, 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> 1734922 INFO (jetty-launcher-2474-thread-1) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 1734923 INFO (jetty-launcher-2474-thread-2) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 1734923 INFO (jetty-launcher-2474-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1beb6d8{/solr,null,AVAILABLE} [junit4] 2> 1734923 INFO (jetty-launcher-2474-thread-3) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 1734923 INFO (jetty-launcher-2474-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@d71135{/solr,null,AVAILABLE} [junit4] 2> 1734924 INFO (jetty-launcher-2474-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@97f9d0{HTTP/1.1,[http/1.1]}{127.0.0.1:64411} [junit4] 2> 1734924 INFO (jetty-launcher-2474-thread-1) [ ] o.e.j.s.Server Started @1742345ms [junit4] 2> 1734924 INFO (jetty-launcher-2474-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=64411} [junit4] 2> 1734924 INFO (jetty-launcher-2474-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@17c8b78{HTTP/1.1,[http/1.1]}{127.0.0.1:64414} [junit4] 2> 1734925 INFO (jetty-launcher-2474-thread-2) [ ] o.e.j.s.Server Started @1742345ms [junit4] 2> 1734925 INFO (jetty-launcher-2474-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=64414} [junit4] 2> 1734925 ERROR (jetty-launcher-2474-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1734925 INFO (jetty-launcher-2474-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@53755b{/solr,null,AVAILABLE} [junit4] 2> 1734925 INFO (jetty-launcher-2474-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 1734925 INFO (jetty-launcher-2474-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1734925 INFO (jetty-launcher-2474-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 1734925 INFO (jetty-launcher-2474-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-04T01:59:47.457Z [junit4] 2> 1734926 INFO (jetty-launcher-2474-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7ad58f{HTTP/1.1,[http/1.1]}{127.0.0.1:64417} [junit4] 2> 1734926 INFO (jetty-launcher-2474-thread-3) [ ] o.e.j.s.Server Started @1742347ms [junit4] 2> 1734926 INFO (jetty-launcher-2474-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=64417} [junit4] 2> 1734927 ERROR (jetty-launcher-2474-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1734927 INFO (jetty-launcher-2474-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 1734927 INFO (jetty-launcher-2474-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1734927 INFO (jetty-launcher-2474-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 1734927 INFO (jetty-launcher-2474-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-04T01:59:47.459Z [junit4] 2> 1734927 ERROR (jetty-launcher-2474-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1734928 INFO (jetty-launcher-2474-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 1734928 INFO (jetty-launcher-2474-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1734928 INFO (jetty-launcher-2474-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 1734928 INFO (jetty-launcher-2474-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-04T01:59:47.460Z [junit4] 2> 1734943 INFO (jetty-launcher-2474-thread-4) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 1734943 INFO (jetty-launcher-2474-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@74370b{/solr,null,AVAILABLE} [junit4] 2> 1734948 INFO (jetty-launcher-2474-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1734948 INFO (jetty-launcher-2474-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1734949 INFO (jetty-launcher-2474-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@b29f24{HTTP/1.1,[http/1.1]}{127.0.0.1:64429} [junit4] 2> 1734949 INFO (jetty-launcher-2474-thread-4) [ ] o.e.j.s.Server Started @1742370ms [junit4] 2> 1734949 INFO (jetty-launcher-2474-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=64429} [junit4] 2> 1734950 ERROR (jetty-launcher-2474-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1734951 INFO (jetty-launcher-2474-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 1734951 INFO (jetty-launcher-2474-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1734951 INFO (jetty-launcher-2474-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 1734951 INFO (jetty-launcher-2474-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-04T01:59:47.483Z [junit4] 2> 1734954 INFO (jetty-launcher-2474-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1734989 INFO (jetty-launcher-2474-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64407/solr [junit4] 2> 1734993 INFO (jetty-launcher-2474-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64407/solr [junit4] 2> 1734994 INFO (jetty-launcher-2474-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64407/solr [junit4] 2> 1735007 INFO (jetty-launcher-2474-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1735013 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 0x15ee51c3f8c0007, 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> 1735026 INFO (jetty-launcher-2474-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64407/solr [junit4] 2> 1735141 INFO (jetty-launcher-2474-thread-4) [n:127.0.0.1:64429_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1735142 INFO (jetty-launcher-2474-thread-4) [n:127.0.0.1:64429_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:64429_solr [junit4] 2> 1735145 INFO (jetty-launcher-2474-thread-4) [n:127.0.0.1:64429_solr ] o.a.s.c.Overseer Overseer (id=98768151336714252-127.0.0.1:64429_solr-n_0000000000) starting [junit4] 2> 1735165 INFO (jetty-launcher-2474-thread-4) [n:127.0.0.1:64429_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64429_solr [junit4] 2> 1735174 INFO (zkCallback-2499-thread-1-processing-n:127.0.0.1:64429_solr) [n:127.0.0.1:64429_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1735277 INFO (jetty-launcher-2474-thread-1) [n:127.0.0.1:64411_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1735281 INFO (jetty-launcher-2474-thread-1) [n:127.0.0.1:64411_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1735287 INFO (jetty-launcher-2474-thread-1) [n:127.0.0.1:64411_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64411_solr [junit4] 2> 1735300 INFO (zkCallback-2499-thread-1-processing-n:127.0.0.1:64429_solr) [n:127.0.0.1:64429_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1735302 INFO (zkCallback-2491-thread-1-processing-n:127.0.0.1:64411_solr) [n:127.0.0.1:64411_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1735410 INFO (jetty-launcher-2474-thread-3) [n:127.0.0.1:64417_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1735412 INFO (jetty-launcher-2474-thread-2) [n:127.0.0.1:64414_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1735418 INFO (jetty-launcher-2474-thread-3) [n:127.0.0.1:64417_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1735419 INFO (jetty-launcher-2474-thread-2) [n:127.0.0.1:64414_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1735422 INFO (jetty-launcher-2474-thread-3) [n:127.0.0.1:64417_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64417_solr [junit4] 2> 1735422 INFO (jetty-launcher-2474-thread-2) [n:127.0.0.1:64414_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64414_solr [junit4] 2> 1735514 INFO (zkCallback-2491-thread-1-processing-n:127.0.0.1:64411_solr) [n:127.0.0.1:64411_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1735514 INFO (zkCallback-2490-thread-1-processing-n:127.0.0.1:64417_solr) [n:127.0.0.1:64417_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1735515 INFO (zkCallback-2492-thread-1-processing-n:127.0.0.1:64414_solr) [n:127.0.0.1:64414_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1735576 INFO (zkCallback-2499-thread-1-processing-n:127.0.0.1:64429_solr) [n:127.0.0.1:64429_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1735580 INFO (zkCallback-2491-thread-1-processing-n:127.0.0.1:64411_solr) [n:127.0.0.1:64411_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1735581 INFO (zkCallback-2490-thread-1-processing-n:127.0.0.1:64417_solr) [n:127.0.0.1:64417_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1735581 INFO (zkCallback-2492-thread-1-processing-n:127.0.0.1:64414_solr) [n:127.0.0.1:64414_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1735581 INFO (zkCallback-2499-thread-1-processing-n:127.0.0.1:64429_solr) [n:127.0.0.1:64429_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1735715 INFO (jetty-launcher-2474-thread-4) [n:127.0.0.1:64429_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50b310 [junit4] 2> 1735730 INFO (jetty-launcher-2474-thread-4) [n:127.0.0.1:64429_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50b310 [junit4] 2> 1735730 INFO (jetty-launcher-2474-thread-4) [n:127.0.0.1:64429_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50b310 [junit4] 2> 1735732 INFO (jetty-launcher-2474-thread-4) [n:127.0.0.1:64429_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.CollectionsAPIDistributedZkTest_C54CEF44785C1850-001\tempDir-001\node4 [junit4] 2> 1735774 INFO (jetty-launcher-2474-thread-1) [n:127.0.0.1:64411_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50b310 [junit4] 2> 1735806 INFO (jetty-launcher-2474-thread-1) [n:127.0.0.1:64411_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50b310 [junit4] 2> 1735808 INFO (jetty-launcher-2474-thread-1) [n:127.0.0.1:64411_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50b310 [junit4] 2> 1735813 INFO (jetty-launcher-2474-thread-1) [n:127.0.0.1:64411_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.CollectionsAPIDistributedZkTest_C54CEF44785C1850-001\tempDir-001\node1 [junit4] 2> 1735904 WARN (jetty-launcher-2474-thread-3) [n:127.0.0.1:64417_solr ] o.a.s.m.r.j.JmxMetricsReporter Unable to register meter [junit4] 2> javax.management.InstanceNotFoundException: solr:dom1=node,category=ADMIN,scope=/admin/health,name=clientErrors [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1095) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:427) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:415) [junit4] 2> at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:546) [junit4] 2> at org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter$JmxListener.registerMBean(JmxMetricsReporter.java:530) [junit4] 2> at org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter$JmxListener.onMeterAdded(JmxMetricsReporter.java:644) [junit4] 2> at com.codahale.metrics.MetricRegistry.notifyListenerOfAddedMetric(MetricRegistry.java:460) [junit4] 2> at com.codahale.metrics.MetricRegistry.addListener(MetricRegistry.java:297) [junit4] 2> at org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter.start(JmxMetricsReporter.java:725) [junit4] 2> at org.apache.solr.metrics.reporters.SolrJmxReporter.doInit(SolrJmxReporter.java:109) [junit4] 2> at org.apache.solr.metrics.SolrMetricReporter.init(SolrMetricReporter.java:70) [junit4] 2> at org.apache.solr.metrics.SolrMetricManager.loadReporter(SolrMetricManager.java:849) [junit4] 2> at org.apache.solr.metrics.SolrMetricManager.loadReporters(SolrMetricManager.java:817) [junit4] 2> at org.apache.solr.core.CoreContainer.load(CoreContainer.java:547) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.createCoreContainer(SolrDispatchFilter.java:263) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.init(SolrDispatchFilter.java:183) [junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.initialize(FilterHolder.java:139) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.initialize(ServletHandler.java:873) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.updateMappings(ServletHandler.java:1596) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.setFilterMappings(ServletHandler.java:1659) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.addFilterMapping(ServletHandler.java:1316) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.addFilterWithMapping(ServletHandler.java:1145) [junit4] 2> at org.eclipse.jetty.servlet.ServletContextHandler.addFilter(ServletContextHandler.java:448) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$1.lifeCycleStarted(JettySolrRunner.java:306) [junit4] 2> at org.eclipse.jetty.util.component.AbstractLifeCycle.setStarted(AbstractLifeCycle.java:179) [junit4] 2> at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:69) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:394) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:367) [junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster.startJettySolrRunner(MiniSolrCloudCluster.java:384) [junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster.lambda$new$0(MiniSolrCloudCluster.java:247) [junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 1735911 INFO (jetty-launcher-2474-thread-2) [n:127.0.0.1:64414_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50b310 [junit4] 2> 1735914 INFO (jetty-launcher-2474-thread-3) [n:127.0.0.1:64417_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50b310 [junit4] 2> 1735926 INFO (jetty-launcher-2474-thread-2) [n:127.0.0.1:64414_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50b310 [junit4] 2> 1735927 INFO (jetty-launcher-2474-thread-2) [n:127.0.0.1:64414_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50b310 [junit4] 2> 1735929 INFO (jetty-launcher-2474-thread-2) [n:127.0.0.1:64414_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.CollectionsAPIDistributedZkTest_C54CEF44785C1850-001\tempDir-001\node2 [junit4] 2> 1735938 INFO (jetty-launcher-2474-thread-3) [n:127.0.0.1:64417_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50b310 [junit4] 2> 1735938 INFO (jetty-launcher-2474-thread-3) [n:127.0.0.1:64417_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50b310 [junit4] 2> 1735941 INFO (jetty-launcher-2474-thread-3) [n:127.0.0.1:64417_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.CollectionsAPIDistributedZkTest_C54CEF44785C1850-001\tempDir-001\node3 [junit4] 2> 1736122 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[C54CEF44785C1850]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 1736125 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[C54CEF44785C1850]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64407/solr ready [junit4] 2> 1736198 INFO (TEST-CollectionsAPIDistributedZkTest.testDeleteNonExistentCollection-seed#[C54CEF44785C1850]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDeleteNonExistentCollection [junit4] 2> 1736200 INFO (TEST-CollectionsAPIDistributedZkTest.testDeleteNonExistentCollection-seed#[C54CEF44785C1850]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 1736207 INFO (qtp8283034-15672) [n:127.0.0.1:64411_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=unknown_collection&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1736212 INFO (OverseerThreadFactory-7127-thread-1-processing-n:127.0.0.1:64429_solr) [n:127.0.0.1:64429_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true [junit4] 2> 1736213 ERROR (OverseerThreadFactory-7127-thread-1-processing-n:127.0.0.1:64429_solr) [n:127.0.0.1:64429_solr ] o.a.s.c.OverseerCollectionMessageHandler Collection: unknown_collection operation: delete failed:org.apache.solr.common.SolrException: Could not find collection : unknown_collection [junit4] 2> at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:109) [junit4] 2> at org.apache.solr.cloud.OverseerCollectionMessageHandler.collectionCmd(OverseerCollectionMessageHandler.java:796) [junit4] 2> at org.apache.solr.cloud.DeleteCollectionCmd.call(DeleteCollectionCmd.java:88) [junit4] 2> at org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:228) [junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:464) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> [junit4] 2> 1736229 INFO (qtp8283034-15672) [n:127.0.0.1:64411_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=unknown_collection&action=DELETE&wt=javabin&version=2} status=400 QTime=22 [junit4] 2> 1736231 INFO (qtp8283034-15646) [n:127.0.0.1:64411_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=2&collection.configName=conf&name=acollectionafterbaddelete&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1736236 INFO (OverseerCollectionConfigSetProcessor-98768151336714252-127.0.0.1:64429_solr-n_0000000000) [n:127.0.0.1:64429_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> 1736238 INFO (OverseerThreadFactory-7127-thread-2-processing-n:127.0.0.1:64429_solr) [n:127.0.0.1:64429_solr ] o.a.s.c.CreateCollectionCmd Create collection acollectionafterbaddelete [junit4] 2> 1736377 INFO (OverseerStateUpdate-98768151336714252-127.0.0.1:64429_solr-n_0000000000) [n:127.0.0.1:64429_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"acollectionafterbaddelete", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"acollectionafterbaddelete_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:64411/solr";, [junit4] 2> "type":"NRT"} [junit4] 2> 1736386 INFO (OverseerStateUpdate-98768151336714252-127.0.0.1:64429_solr-n_0000000000) [n:127.0.0.1:64429_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"acollectionafterbaddelete", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"acollectionafterbaddelete_shard1_replica_n2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:64417/solr";, [junit4] 2> "type":"NRT"} [junit4] 2> 1736590 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=acollectionafterbaddelete_shard1_replica_n1&action=CREATE&numShards=1&collection=acollectionafterbaddelete&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 1736590 INFO (qtp32492895-15662) [n:127.0.0.1:64417_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=acollectionafterbaddelete_shard1_replica_n2&action=CREATE&numShards=1&collection=acollectionafterbaddelete&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 1736591 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1736592 INFO (qtp32492895-15662) [n:127.0.0.1:64417_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1736705 INFO (zkCallback-2491-thread-1-processing-n:127.0.0.1:64411_solr) [n:127.0.0.1:64411_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/acollectionafterbaddelete/state.json] for collection [acollectionafterbaddelete] has occurred - updating... (live nodes size: [4]) [junit4] 2> 1736705 INFO (zkCallback-2490-thread-1-processing-n:127.0.0.1:64417_solr) [n:127.0.0.1:64417_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/acollectionafterbaddelete/state.json] for collection [acollectionafterbaddelete] has occurred - updating... (live nodes size: [4]) [junit4] 2> 1736706 INFO (zkCallback-2491-thread-2-processing-n:127.0.0.1:64411_solr) [n:127.0.0.1:64411_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/acollectionafterbaddelete/state.json] for collection [acollectionafterbaddelete] has occurred - updating... (live nodes size: [4]) [junit4] 2> 1736706 INFO (zkCallback-2490-thread-2-processing-n:127.0.0.1:64417_solr) [n:127.0.0.1:64417_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/acollectionafterbaddelete/state.json] for collection [acollectionafterbaddelete] has occurred - updating... (live nodes size: [4]) [junit4] 2> 1737624 INFO (qtp32492895-15662) [n:127.0.0.1:64417_solr c:acollectionafterbaddelete s:shard1 r:core_node4 x:acollectionafterbaddelete_shard1_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 1737625 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr c:acollectionafterbaddelete s:shard1 r:core_node3 x:acollectionafterbaddelete_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 1737651 INFO (qtp32492895-15662) [n:127.0.0.1:64417_solr c:acollectionafterbaddelete s:shard1 r:core_node4 x:acollectionafterbaddelete_shard1_replica_n2] o.a.s.s.IndexSchema [acollectionafterbaddelete_shard1_replica_n2] Schema name=minimal [junit4] 2> 1737653 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr c:acollectionafterbaddelete s:shard1 r:core_node3 x:acollectionafterbaddelete_shard1_replica_n1] o.a.s.s.IndexSchema [acollectionafterbaddelete_shard1_replica_n1] Schema name=minimal [junit4] 2> 1737659 INFO (qtp32492895-15662) [n:127.0.0.1:64417_solr c:acollectionafterbaddelete s:shard1 r:core_node4 x:acollectionafterbaddelete_shard1_replica_n2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1737659 INFO (qtp32492895-15662) [n:127.0.0.1:64417_solr c:acollectionafterbaddelete s:shard1 r:core_node4 x:acollectionafterbaddelete_shard1_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'acollectionafterbaddelete_shard1_replica_n2' using configuration from collection acollectionafterbaddelete, trusted=true [junit4] 2> 1737659 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr c:acollectionafterbaddelete s:shard1 r:core_node3 x:acollectionafterbaddelete_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1737659 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr c:acollectionafterbaddelete s:shard1 r:core_node3 x:acollectionafterbaddelete_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'acollectionafterbaddelete_shard1_replica_n1' using configuration from collection acollectionafterbaddelete, trusted=true [junit4] 2> 1737660 INFO (qtp32492895-15662) [n:127.0.0.1:64417_solr c:acollectionafterbaddelete s:shard1 r:core_node4 x:acollectionafterbaddelete_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.acollectionafterbaddelete.shard1.replica_n2' (registry 'solr.core.acollectionafterbaddelete.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50b310 [junit4] 2> 1737660 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr c:acollectionafterbaddelete s:shard1 r:core_node3 x:acollectionafterbaddelete_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.acollectionafterbaddelete.shard1.replica_n1' (registry 'solr.core.acollectionafterbaddelete.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50b310 [junit4] 2> 1737660 INFO (qtp32492895-15662) [n:127.0.0.1:64417_solr c:acollectionafterbaddelete s:shard1 r:core_node4 x:acollectionafterbaddelete_shard1_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1737660 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr c:acollectionafterbaddelete s:shard1 r:core_node3 x:acollectionafterbaddelete_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1737660 INFO (qtp32492895-15662) [n:127.0.0.1:64417_solr c:acollectionafterbaddelete s:shard1 r:core_node4 x:acollectionafterbaddelete_shard1_replica_n2] o.a.s.c.SolrCore [[acollectionafterbaddelete_shard1_replica_n2] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CollectionsAPIDistributedZkTest_C54CEF44785C1850-001\tempDir-001\node3\acollectionafterbaddelete_shard1_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CollectionsAPIDistributedZkTest_C54CEF44785C1850-001\tempDir-001\node3\acollectionafterbaddelete_shard1_replica_n2\data\] [junit4] 2> 1737660 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr c:acollectionafterbaddelete s:shard1 r:core_node3 x:acollectionafterbaddelete_shard1_replica_n1] o.a.s.c.SolrCore [[acollectionafterbaddelete_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CollectionsAPIDistributedZkTest_C54CEF44785C1850-001\tempDir-001\node1\acollectionafterbaddelete_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CollectionsAPIDistributedZkTest_C54CEF44785C1850-001\tempDir-001\node1\acollectionafterbaddelete_shard1_replica_n1\data\] [junit4] 2> 1737766 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr c:acollectionafterbaddelete s:shard1 r:core_node3 x:acollectionafterbaddelete_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1737766 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr c:acollectionafterbaddelete s:shard1 r:core_node3 x:acollectionafterbaddelete_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1737768 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr c:acollectionafterbaddelete s:shard1 r:core_node3 x:acollectionafterbaddelete_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1737768 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr c:acollectionafterbaddelete s:shard1 r:core_node3 x:acollectionafterbaddelete_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1737769 INFO (qtp32492895-15662) [n:127.0.0.1:64417_solr c:acollectionafterbaddelete s:shard1 r:core_node4 x:acollectionafterbaddelete_shard1_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1737769 INFO (qtp32492895-15662) [n:127.0.0.1:64417_solr c:acollectionafterbaddelete s:shard1 r:core_node4 x:acollectionafterbaddelete_shard1_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1737770 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr c:acollectionafterbaddelete s:shard1 r:core_node3 x:acollectionafterbaddelete_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1803013[acollectionafterbaddelete_shard1_replica_n1] main] [junit4] 2> 1737772 INFO (qtp32492895-15662) [n:127.0.0.1:64417_solr c:acollectionafterbaddelete s:shard1 r:core_node4 x:acollectionafterbaddelete_shard1_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1737772 INFO (qtp32492895-15662) [n:127.0.0.1:64417_solr c:acollectionafterbaddelete s:shard1 r:core_node4 x:acollectionafterbaddelete_shard1_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1737773 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr c:acollectionafterbaddelete s:shard1 r:core_node3 x:acollectionafterbaddelete_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1737773 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr c:acollectionafterbaddelete s:shard1 r:core_node3 x:acollectionafterbaddelete_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 1737774 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr c:acollectionafterbaddelete s:shard1 r:core_node3 x:acollectionafterbaddelete_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1737774 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr c:acollectionafterbaddelete s:shard1 r:core_node3 x:acollectionafterbaddelete_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1580290424497504256 [junit4] 2> 1737774 INFO (searcherExecutor-7137-thread-1-processing-n:127.0.0.1:64411_solr x:acollectionafterbaddelete_shard1_replica_n1 s:shard1 c:acollectionafterbaddelete r:core_node3) [n:127.0.0.1:64411_solr c:acollectionafterbaddelete s:shard1 r:core_node3 x:acollectionafterbaddelete_shard1_replica_n1] o.a.s.c.SolrCore [acollectionafterbaddelete_shard1_replica_n1] Registered new searcher Searcher@1803013[acollectionafterbaddelete_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1737774 INFO (qtp32492895-15662) [n:127.0.0.1:64417_solr c:acollectionafterbaddelete s:shard1 r:core_node4 x:acollectionafterbaddelete_shard1_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@7389e6[acollectionafterbaddelete_shard1_replica_n2] main] [junit4] 2> 1737787 INFO (qtp32492895-15662) [n:127.0.0.1:64417_solr c:acollectionafterbaddelete s:shard1 r:core_node4 x:acollectionafterbaddelete_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1737788 INFO (qtp32492895-15662) [n:127.0.0.1:64417_solr c:acollectionafterbaddelete s:shard1 r:core_node4 x:acollectionafterbaddelete_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 1737789 INFO (qtp32492895-15662) [n:127.0.0.1:64417_solr c:acollectionafterbaddelete s:shard1 r:core_node4 x:acollectionafterbaddelete_shard1_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1737791 INFO (searcherExecutor-7136-thread-1-processing-n:127.0.0.1:64417_solr x:acollectionafterbaddelete_shard1_replica_n2 s:shard1 c:acollectionafterbaddelete r:core_node4) [n:127.0.0.1:64417_solr c:acollectionafterbaddelete s:shard1 r:core_node4 x:acollectionafterbaddelete_shard1_replica_n2] o.a.s.c.SolrCore [acollectionafterbaddelete_shard1_replica_n2] Registered new searcher Searcher@7389e6[acollectionafterbaddelete_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1737791 INFO (qtp32492895-15662) [n:127.0.0.1:64417_solr c:acollectionafterbaddelete s:shard1 r:core_node4 x:acollectionafterbaddelete_shard1_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1580290424515330048 [junit4] 2> 1737792 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr c:acollectionafterbaddelete s:shard1 r:core_node3 x:acollectionafterbaddelete_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=2 found=1 timeoutin=9999ms [junit4] 2> 1737894 INFO (zkCallback-2491-thread-2-processing-n:127.0.0.1:64411_solr) [n:127.0.0.1:64411_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/acollectionafterbaddelete/state.json] for collection [acollectionafterbaddelete] has occurred - updating... (live nodes size: [4]) [junit4] 2> 1737894 INFO (zkCallback-2491-thread-1-processing-n:127.0.0.1:64411_solr) [n:127.0.0.1:64411_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/acollectionafterbaddelete/state.json] for collection [acollectionafterbaddelete] has occurred - updating... (live nodes size: [4]) [junit4] 2> 1737894 INFO (zkCallback-2490-thread-2-processing-n:127.0.0.1:64417_solr) [n:127.0.0.1:64417_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/acollectionafterbaddelete/state.json] for collection [acollectionafterbaddelete] has occurred - updating... (live nodes size: [4]) [junit4] 2> 1737894 INFO (zkCallback-2490-thread-1-processing-n:127.0.0.1:64417_solr) [n:127.0.0.1:64417_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/acollectionafterbaddelete/state.json] for collection [acollectionafterbaddelete] has occurred - updating... (live nodes size: [4]) [junit4] 2> 1738293 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr c:acollectionafterbaddelete s:shard1 r:core_node3 x:acollectionafterbaddelete_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1738293 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr c:acollectionafterbaddelete s:shard1 r:core_node3 x:acollectionafterbaddelete_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1738294 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr c:acollectionafterbaddelete s:shard1 r:core_node3 x:acollectionafterbaddelete_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:64411/solr/acollectionafterbaddelete_shard1_replica_n1/ [junit4] 2> 1738294 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr c:acollectionafterbaddelete s:shard1 r:core_node3 x:acollectionafterbaddelete_shard1_replica_n1] o.a.s.u.PeerSync PeerSync: core=acollectionafterbaddelete_shard1_replica_n1 url=http://127.0.0.1:64411/solr START replicas=[http://127.0.0.1:64417/solr/acollectionafterbaddelete_shard1_replica_n2/] nUpdates=100 [junit4] 2> 1738297 INFO (qtp32492895-15658) [n:127.0.0.1:64417_solr c:acollectionafterbaddelete s:shard1 r:core_node4 x:acollectionafterbaddelete_shard1_replica_n2] o.a.s.c.S.Request [acollectionafterbaddelete_shard1_replica_n2] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1738596 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr c:acollectionafterbaddelete s:shard1 r:core_node3 x:acollectionafterbaddelete_shard1_replica_n1] o.a.s.u.PeerSync PeerSync: core=acollectionafterbaddelete_shard1_replica_n1 url=http://127.0.0.1:64411/solr DONE. We have no versions. sync failed. [junit4] 2> 1738596 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr c:acollectionafterbaddelete s:shard1 r:core_node3 x:acollectionafterbaddelete_shard1_replica_n1] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate [junit4] 2> 1738596 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr c:acollectionafterbaddelete s:shard1 r:core_node3 x:acollectionafterbaddelete_shard1_replica_n1] 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> 1738596 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr c:acollectionafterbaddelete s:shard1 r:core_node3 x:acollectionafterbaddelete_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 1738623 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr c:acollectionafterbaddelete s:shard1 r:core_node3 x:acollectionafterbaddelete_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:64411/solr/acollectionafterbaddelete_shard1_replica_n1/ shard1 [junit4] 2> 1738728 INFO (zkCallback-2491-thread-1-processing-n:127.0.0.1:64411_solr) [n:127.0.0.1:64411_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/acollectionafterbaddelete/state.json] for collection [acollectionafterbaddelete] has occurred - updating... (live nodes size: [4]) [junit4] 2> 1738728 INFO (zkCallback-2491-thread-2-processing-n:127.0.0.1:64411_solr) [n:127.0.0.1:64411_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/acollectionafterbaddelete/state.json] for collection [acollectionafterbaddelete] has occurred - updating... (live nodes size: [4]) [junit4] 2> 1738730 INFO (zkCallback-2490-thread-2-processing-n:127.0.0.1:64417_solr) [n:127.0.0.1:64417_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/acollectionafterbaddelete/state.json] for collection [acollectionafterbaddelete] has occurred - updating... (live nodes size: [4]) [junit4] 2> 1738730 INFO (zkCallback-2490-thread-1-processing-n:127.0.0.1:64417_solr) [n:127.0.0.1:64417_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/acollectionafterbaddelete/state.json] for collection [acollectionafterbaddelete] has occurred - updating... (live nodes size: [4]) [junit4] 2> 1738777 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr c:acollectionafterbaddelete s:shard1 r:core_node3 x:acollectionafterbaddelete_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1738778 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr c:acollectionafterbaddelete s:shard1 r:core_node3 x:acollectionafterbaddelete_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=acollectionafterbaddelete_shard1_replica_n1&action=CREATE&numShards=1&collection=acollectionafterbaddelete&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2190 [junit4] 2> 1738802 INFO (qtp32492895-15662) [n:127.0.0.1:64417_solr c:acollectionafterbaddelete s:shard1 r:core_node4 x:acollectionafterbaddelete_shard1_replica_n2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=acollectionafterbaddelete_shard1_replica_n2&action=CREATE&numShards=1&collection=acollectionafterbaddelete&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2213 [junit4] 2> 1738810 INFO (qtp8283034-15646) [n:127.0.0.1:64411_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> 1738911 INFO (zkCallback-2491-thread-2-processing-n:127.0.0.1:64411_solr) [n:127.0.0.1:64411_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/acollectionafterbaddelete/state.json] for collection [acollectionafterbaddelete] has occurred - updating... (live nodes size: [4]) [junit4] 2> 1738911 INFO (zkCallback-2490-thread-2-processing-n:127.0.0.1:64417_solr) [n:127.0.0.1:64417_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/acollectionafterbaddelete/state.json] for collection [acollectionafterbaddelete] has occurred - updating... (live nodes size: [4]) [junit4] 2> 1738911 INFO (zkCallback-2490-thread-1-processing-n:127.0.0.1:64417_solr) [n:127.0.0.1:64417_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/acollectionafterbaddelete/state.json] for collection [acollectionafterbaddelete] has occurred - updating... (live nodes size: [4]) [junit4] 2> 1738911 INFO (zkCallback-2491-thread-1-processing-n:127.0.0.1:64411_solr) [n:127.0.0.1:64411_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/acollectionafterbaddelete/state.json] for collection [acollectionafterbaddelete] has occurred - updating... (live nodes size: [4]) [junit4] 2> 1739810 INFO (qtp8283034-15646) [n:127.0.0.1:64411_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=2&collection.configName=conf&name=acollectionafterbaddelete&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=3578 [junit4] 2> 1739812 INFO (TEST-CollectionsAPIDistributedZkTest.testDeleteNonExistentCollection-seed#[C54CEF44785C1850]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDeleteNonExistentCollection [junit4] 2> 1739845 INFO (TEST-CollectionsAPIDistributedZkTest.testTooManyReplicas-seed#[C54CEF44785C1850]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testTooManyReplicas [junit4] 2> 1739847 INFO (TEST-CollectionsAPIDistributedZkTest.testTooManyReplicas-seed#[C54CEF44785C1850]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 1739849 INFO (qtp8283034-15643) [n:127.0.0.1:64411_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=acollectionafterbaddelete&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1739853 INFO (OverseerCollectionConfigSetProcessor-98768151336714252-127.0.0.1:64429_solr-n_0000000000) [n:127.0.0.1:64429_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> 1739853 INFO (OverseerThreadFactory-7127-thread-3-processing-n:127.0.0.1:64429_solr) [n:127.0.0.1:64429_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true [junit4] 2> 1739857 INFO (qtp32492895-15657) [n:127.0.0.1:64417_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.acollectionafterbaddelete.shard1.replica_n2, tag=null [junit4] 2> 1739857 INFO (qtp32492895-15657) [n:127.0.0.1:64417_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@10ebe86: rootName = null, domain = solr.core.acollectionafterbaddelete.shard1.replica_n2, service url = null, agent id = null] for registry solr.core.acollectionafterbaddelete.shard1.replica_n2 / com.codahale.metrics.MetricRegistry@338714 [junit4] 2> 1739857 INFO (qtp8283034-15642) [n:127.0.0.1:64411_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.acollectionafterbaddelete.shard1.replica_n1, tag=null [junit4] 2> 1739857 INFO (qtp8283034-15642) [n:127.0.0.1:64411_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@206988: rootName = null, domain = solr.core.acollectionafterbaddelete.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.acollectionafterbaddelete.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@38b8cf [junit4] 2> 1739871 INFO (qtp8283034-15642) [n:127.0.0.1:64411_solr ] o.a.s.c.SolrCore [acollectionafterbaddelete_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@f031bf [junit4] 2> 1739871 INFO (qtp8283034-15642) [n:127.0.0.1:64411_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.acollectionafterbaddelete.shard1.replica_n1, tag=15741375 [junit4] 2> 1739871 INFO (qtp8283034-15642) [n:127.0.0.1:64411_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.acollectionafterbaddelete.shard1.leader, tag=15741375 [junit4] 2> 1739874 INFO (qtp32492895-15657) [n:127.0.0.1:64417_solr ] o.a.s.c.SolrCore [acollectionafterbaddelete_shard1_replica_n2] CLOSING SolrCore org.apache.solr.core.SolrCore@348500 [junit4] 2> 1739874 INFO (qtp32492895-15657) [n:127.0.0.1:64417_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.acollectionafterbaddelete.shard1.replica_n2, tag=3441920 [junit4] 2> 1739874 INFO (qtp32492895-15657) [n:127.0.0.1:64417_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.acollectionafterbaddelete.shard1.leader, tag=3441920 [junit4] 2> 1739878 WARN (zkCallback-2490-thread-1-processing-n:127.0.0.1:64417_solr) [n:127.0.0.1:64417_solr ] o.a.s.c.LeaderElector [junit4] 2> org.apache.solr.common.SolrException: SolrCore not found:acollectionafterbaddelete_shard1_replica_n2 in [] [junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:309) [junit4] 2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:170) [junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:135) [junit4] 2> at org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:56) [junit4] 2> at org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:348) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.lambda$process$0(SolrZkClient.java:269) [junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 1739878 INFO (qtp8283034-15642) [n:127.0.0.1:64411_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=acollectionafterbaddelete_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=23 [junit4] 2> 1739883 INFO (qtp32492895-15657) [n:127.0.0.1:64417_solr ] o.a.s.c.ShardLeaderElectionContextBase No version found for ephemeral leader parent node, won't remove previous leader registration. [junit4] 2> 1739884 INFO (qtp32492895-15657) [n:127.0.0.1:64417_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=acollectionafterbaddelete_shard1_replica_n2&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=29 [junit4] 2> 1740590 INFO (qtp8283034-15643) [n:127.0.0.1:64411_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=acollectionafterbaddelete&action=DELETE&wt=javabin&version=2} status=0 QTime=740 [junit4] 2> 1740592 INFO (qtp8283034-15641) [n:127.0.0.1:64411_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=10&collection.configName=conf&name=collection&nrtReplicas=10&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1740599 INFO (OverseerThreadFactory-7127-thread-4-processing-n:127.0.0.1:64429_solr) [n:127.0.0.1:64429_solr ] o.a.s.c.CreateCollectionCmd Create collection collection [junit4] 2> 1740600 WARN (OverseerThreadFactory-7127-thread-4-processing-n:127.0.0.1:64429_solr) [n:127.0.0.1:64429_solr ] o.a.s.c.CreateCollectionCmd Specified number of replicas of 10 on collection collection is higher than the number of Solr instances currently live or live and part of your createNodeSet(4). It's unusual to run two replica of the same slice on the same Solr-instance. [junit4] 2> 1740600 INFO (OverseerCollectionConfigSetProcessor-98768151336714252-127.0.0.1:64429_solr-n_0000000000) [n:127.0.0.1:64429_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> 1740600 ERROR (OverseerThreadFactory-7127-thread-4-processing-n:127.0.0.1:64429_solr) [n:127.0.0.1:64429_solr ] o.a.s.c.OverseerCollectionMessageHandler Collection: collection operation: create failed:org.apache.solr.common.SolrException: Cannot create collection collection. Value of maxShardsPerNode is 1, and the number of nodes currently live or live and part of your createNodeSet is 4. This allows a maximum of 4 to be created. Value of numShards is 2, value of nrtReplicas is 10, value of tlogReplicas is 0 and value of pullReplicas is 0. This requires 20 shards to be created (higher than the allowed number) [junit4] 2> at org.apache.solr.cloud.CreateCollectionCmd.call(CreateCollectionCmd.java:164) [junit4] 2> at org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:228) [junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:464) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> [junit4] 2> 1740605 INFO (qtp8283034-15641) [n:127.0.0.1:64411_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=10&collection.configName=conf&name=collection&nrtReplicas=10&action=CREATE&numShards=2&wt=javabin&version=2} status=400 QTime=13 [junit4] 2> 1740607 INFO (TEST-CollectionsAPIDistributedZkTest.testTooManyReplicas-seed#[C54CEF44785C1850]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testTooManyReplicas [junit4] 2> 1740632 INFO (TEST-CollectionsAPIDistributedZkTest.deleteCollectionOnlyInZk-seed#[C54CEF44785C1850]) [ ] o.a.s.SolrTestCaseJ4 ###Starting deleteCollectionOnlyInZk [junit4] 2> 1740635 INFO (TEST-CollectionsAPIDistributedZkTest.deleteCollectionOnlyInZk-seed#[C54CEF44785C1850]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 1740642 INFO (qtp8283034-15674) [n:127.0.0.1:64411_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=onlyinzk&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1740649 INFO (OverseerCollectionConfigSetProcessor-98768151336714252-127.0.0.1:64429_solr-n_0000000000) [n:127.0.0.1:64429_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> 1740655 INFO (qtp8283034-15674) [n:127.0.0.1:64411_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=onlyinzk&action=DELETE&wt=javabin&version=2} status=0 QTime=13 [junit4] 2> 1740656 INFO (qtp8283034-15639) [n:127.0.0.1:64411_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1740656 INFO (qtp8283034-15639) [n:127.0.0.1:64411_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1740657 INFO (qtp8283034-15672) [n:127.0.0.1:64411_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf&name=onlyinzk&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1740659 INFO (OverseerThreadFactory-7127-thread-5-processing-n:127.0.0.1:64429_solr) [n:127.0.0.1:64429_solr ] o.a.s.c.CreateCollectionCmd Create collection onlyinzk [junit4] 2> 1740661 INFO (OverseerCollectionConfigSetProcessor-98768151336714252-127.0.0.1:64429_solr-n_0000000000) [n:127.0.0.1:64429_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> 1740874 INFO (OverseerStateUpdate-98768151336714252-127.0.0.1:64429_solr-n_0000000000) [n:127.0.0.1:64429_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"onlyinzk", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"onlyinzk_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:64429/solr";, [junit4] 2> "type":"NRT"} [junit4] 2> 1740878 INFO (OverseerStateUpdate-98768151336714252-127.0.0.1:64429_solr-n_0000000000) [n:127.0.0.1:64429_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"onlyinzk", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"onlyinzk_shard2_replica_n2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:64411/solr";, [junit4] 2> "type":"NRT"} [junit4] 2> 1741081 INFO (qtp8283034-15644) [n:127.0.0.1:64411_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=onlyinzk_shard2_replica_n2&action=CREATE&numShards=2&collection=onlyinzk&shard=shard2&wt=javabin&version=2&replicaType=NRT [junit4] 2> 1741083 INFO (qtp3587941-15687) [n:127.0.0.1:64429_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=onlyinzk_shard1_replica_n1&action=CREATE&numShards=2&collection=onlyinzk&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 1741083 INFO (qtp3587941-15687) [n:127.0.0.1:64429_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1741201 INFO (zkCallback-2499-thread-1-processing-n:127.0.0.1:64429_solr) [n:127.0.0.1:64429_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred - updating... (live nodes size: [4]) [junit4] 2> 1741201 INFO (zkCallback-2491-thread-2-processing-n:127.0.0.1:64411_solr) [n:127.0.0.1:64411_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred - updating... (live nodes size: [4]) [junit4] 2> 1741201 INFO (zkCallback-2491-thread-1-processing-n:127.0.0.1:64411_solr) [n:127.0.0.1:64411_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred - updating... (live nodes size: [4]) [junit4] 2> 1741202 INFO (zkCallback-2499-thread-2-processing-n:127.0.0.1:64429_solr) [n:127.0.0.1:64429_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred - updating... (live nodes size: [4]) [junit4] 2> 1742113 INFO (qtp8283034-15644) [n:127.0.0.1:64411_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 1742113 INFO (qtp3587941-15687) [n:127.0.0.1:64429_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 1742124 INFO (qtp8283034-15644) [n:127.0.0.1:64411_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.s.IndexSchema [onlyinzk_shard2_replica_n2] Schema name=minimal [junit4] 2> 1742124 INFO (qtp3587941-15687) [n:127.0.0.1:64429_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.s.IndexSchema [onlyinzk_shard1_replica_n1] Schema name=minimal [junit4] 2> 1742131 INFO (qtp8283034-15644) [n:127.0.0.1:64411_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1742131 INFO (qtp8283034-15644) [n:127.0.0.1:64411_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'onlyinzk_shard2_replica_n2' using configuration from collection onlyinzk, trusted=true [junit4] 2> 1742131 INFO (qtp3587941-15687) [n:127.0.0.1:64429_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1742131 INFO (qtp3587941-15687) [n:127.0.0.1:64429_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'onlyinzk_shard1_replica_n1' using configuration from collection onlyinzk, trusted=true [junit4] 2> 1742131 INFO (qtp8283034-15644) [n:127.0.0.1:64411_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.onlyinzk.shard2.replica_n2' (registry 'solr.core.onlyinzk.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50b310 [junit4] 2> 1742131 INFO (qtp3587941-15687) [n:127.0.0.1:64429_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.onlyinzk.shard1.replica_n1' (registry 'solr.core.onlyinzk.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50b310 [junit4] 2> 1742132 INFO (qtp8283034-15644) [n:127.0.0.1:64411_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1742132 INFO (qtp3587941-15687) [n:127.0.0.1:64429_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1742132 INFO (qtp8283034-15644) [n:127.0.0.1:64411_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.c.SolrCore [[onlyinzk_shard2_replica_n2] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CollectionsAPIDistributedZkTest_C54CEF44785C1850-001\tempDir-001\node1\onlyinzk_shard2_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CollectionsAPIDistributedZkTest_C54CEF44785C1850-001\tempDir-001\node1\onlyinzk_shard2_replica_n2\data\] [junit4] 2> 1742132 INFO (qtp3587941-15687) [n:127.0.0.1:64429_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.c.SolrCore [[onlyinzk_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CollectionsAPIDistributedZkTest_C54CEF44785C1850-001\tempDir-001\node4\onlyinzk_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CollectionsAPIDistributedZkTest_C54CEF44785C1850-001\tempDir-001\node4\onlyinzk_shard1_replica_n1\data\] [junit4] 2> 1742267 INFO (qtp3587941-15687) [n:127.0.0.1:64429_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1742267 INFO (qtp3587941-15687) [n:127.0.0.1:64429_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1742268 INFO (qtp8283034-15644) [n:127.0.0.1:64411_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1742268 INFO (qtp8283034-15644) [n:127.0.0.1:64411_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1742269 INFO (qtp3587941-15687) [n:127.0.0.1:64429_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1742269 INFO (qtp3587941-15687) [n:127.0.0.1:64429_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1742270 INFO (qtp8283034-15644) [n:127.0.0.1:64411_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1742270 INFO (qtp8283034-15644) [n:127.0.0.1:64411_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1742271 INFO (qtp3587941-15687) [n:127.0.0.1:64429_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@eab8f[onlyinzk_shard1_replica_n1] main] [junit4] 2> 1742272 INFO (qtp8283034-15644) [n:127.0.0.1:64411_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@111380c[onlyinzk_shard2_replica_n2] main] [junit4] 2> 1742272 INFO (qtp3587941-15687) [n:127.0.0.1:64429_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1742272 INFO (qtp3587941-15687) [n:127.0.0.1:64429_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 1742273 INFO (qtp8283034-15644) [n:127.0.0.1:64411_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1742273 INFO (qtp3587941-15687) [n:127.0.0.1:64429_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1742273 INFO (qtp3587941-15687) [n:127.0.0.1:64429_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1580290429215047680 [junit4] 2> 1742274 INFO (qtp8283034-15644) [n:127.0.0.1:64411_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 1742275 INFO (searcherExecutor-7147-thread-1-processing-n:127.0.0.1:64429_solr x:onlyinzk_shard1_replica_n1 s:shard1 c:onlyinzk r:core_node3) [n:127.0.0.1:64429_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.c.SolrCore [onlyinzk_shard1_replica_n1] Registered new searcher Searcher@eab8f[onlyinzk_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1742275 INFO (qtp8283034-15644) [n:127.0.0.1:64411_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1742276 INFO (searcherExecutor-7146-thread-1-processing-n:127.0.0.1:64411_solr x:onlyinzk_shard2_replica_n2 s:shard2 c:onlyinzk r:core_node4) [n:127.0.0.1:64411_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.c.SolrCore [onlyinzk_shard2_replica_n2] Registered new searcher Searcher@111380c[onlyinzk_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1742276 INFO (qtp8283034-15644) [n:127.0.0.1:64411_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1580290429218193408 [junit4] 2> 1742279 INFO (qtp3587941-15687) [n:127.0.0.1:64429_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1742279 INFO (qtp3587941-15687) [n:127.0.0.1:64429_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1742279 INFO (qtp3587941-15687) [n:127.0.0.1:64429_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:64429/solr/onlyinzk_shard1_replica_n1/ [junit4] 2> 1742279 INFO (qtp3587941-15687) [n:127.0.0.1:64429_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1742279 INFO (qtp3587941-15687) [n:127.0.0.1:64429_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:64429/solr/onlyinzk_shard1_replica_n1/ has no replicas [junit4] 2> 1742279 INFO (qtp3587941-15687) [n:127.0.0.1:64429_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 1742281 INFO (qtp8283034-15644) [n:127.0.0.1:64411_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1742281 INFO (qtp8283034-15644) [n:127.0.0.1:64411_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1742281 INFO (qtp8283034-15644) [n:127.0.0.1:64411_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:64411/solr/onlyinzk_shard2_replica_n2/ [junit4] 2> 1742281 INFO (qtp8283034-15644) [n:127.0.0.1:64411_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1742281 INFO (qtp8283034-15644) [n:127.0.0.1:64411_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.c.SyncStrategy http://127.0.0.1:64411/solr/onlyinzk_shard2_replica_n2/ has no replicas [junit4] 2> 1742281 INFO (qtp8283034-15644) [n:127.0.0.1:64411_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 1742285 INFO (qtp3587941-15687) [n:127.0.0.1:64429_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:64429/solr/onlyinzk_shard1_replica_n1/ shard1 [junit4] 2> 1742286 INFO (qtp8283034-15644) [n:127.0.0.1:64411_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:64411/solr/onlyinzk_shard2_replica_n2/ shard2 [junit4] 2> 1742391 INFO (zkCallback-2491-thread-1-processing-n:127.0.0.1:64411_solr) [n:127.0.0.1:64411_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred - updating... (live nodes size: [4]) [junit4] 2> 1742391 INFO (zkCallback-2491-thread-2-processing-n:127.0.0.1:64411_solr) [n:127.0.0.1:64411_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred - updating... (live nodes size: [4]) [junit4] 2> 1742391 INFO (zkCallback-2499-thread-1-processing-n:127.0.0.1:64429_solr) [n:127.0.0.1:64429_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred - updating... (live nodes size: [4]) [junit4] 2> 1742391 INFO (zkCallback-2499-thread-2-processing-n:127.0.0.1:64429_solr) [n:127.0.0.1:64429_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred - updating... (live nodes size: [4]) [junit4] 2> 1742438 INFO (qtp3587941-15687) [n:127.0.0.1:64429_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1742438 INFO (qtp8283034-15644) [n:127.0.0.1:64411_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1742440 INFO (qtp3587941-15687) [n:127.0.0.1:64429_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=onlyinzk_shard1_replica_n1&action=CREATE&numShards=2&collection=onlyinzk&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1357 [junit4] 2> 1742440 INFO (qtp8283034-15644) [n:127.0.0.1:64411_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=onlyinzk_shard2_replica_n2&action=CREATE&numShards=2&collection=onlyinzk&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1360 [junit4] 2> 1742444 INFO (qtp8283034-15672) [n:127.0.0.1:64411_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> 1742545 INFO (zkCallback-2499-thread-2-processing-n:127.0.0.1:64429_solr) [n:127.0.0.1:64429_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred - updating... (live nodes size: [4]) [junit4] 2> 1742545 INFO (zkCallback-2499-thread-1-processing-n:127.0.0.1:64429_solr) [n:127.0.0.1:64429_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred - updating... (live nodes size: [4]) [junit4] 2> 1742545 INFO (zkCallback-2491-thread-1-processing-n:127.0.0.1:64411_solr) [n:127.0.0.1:64411_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred - updating... (live nodes size: [4]) [junit4] 2> 1742545 INFO (zkCallback-2491-thread-2-processing-n:127.0.0.1:64411_solr) [n:127.0.0.1:64411_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred - updating... (live nodes size: [4]) [junit4] 2> 1742664 INFO (OverseerCollectionConfigSetProcessor-98768151336714252-127.0.0.1:64429_solr-n_0000000000) [n:127.0.0.1:64429_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000010 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 1743445 INFO (qtp8283034-15672) [n:127.0.0.1:64411_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf&name=onlyinzk&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=2787 [junit4] 2> 1743446 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1743446 INFO (qtp8283034-15645) [n:127.0.0.1:64411_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1743446 INFO (TEST-CollectionsAPIDistributedZkTest.deleteCollectionOnlyInZk-seed#[C54CEF44785C1850]) [ ] o.a.s.SolrTestCaseJ4 ###Ending deleteCollectionOnlyInZk [junit4] 2> 1743476 INFO (TEST-CollectionsAPIDistributedZkTest.testNoConfigSetExist-seed#[C54CEF44785C1850]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testNoConfigSetExist [junit4] 2> 1743479 INFO (TEST-CollectionsAPIDistributedZkTest.testNoConfigSetExist-seed#[C54CEF44785C1850]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 1743482 INFO (qtp8283034-15646) [n:127.0.0.1:64411_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=onlyinzk&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1743484 INFO (OverseerThreadFactory-7127-thread-5-processing-n:127.0.0.1:64429_solr) [n:127.0.0.1:64429_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true [junit4] 2> 1743487 INFO (qtp3587941-15686) [n:127.0.0.1:64429_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.onlyinzk.shard1.replica_n1, tag=null [junit4] 2> 1743487 INFO (qtp8283034-15642) [n:127.0.0.1:64411_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.onlyinzk.shard2.replica_n2, tag=null [junit4] 2> 1743487 INFO (qtp3587941-15686) [n:127.0.0.1:64429_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@14ac073: rootNam [...truncated too long message...] ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@53755b{/solr,null,UNAVAILABLE} [junit4] 2> 1815067 INFO (coreCloseExecutor-7318-thread-1) [n:127.0.0.1:64414_solr c:awhollynewcollection_0 s:shard3 r:core_node6 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.awhollynewcollection_0.shard3.leader, tag=487484 [junit4] 2> 1815067 INFO (zkCallback-2499-thread-3-processing-n:127.0.0.1:64429_solr) [n:127.0.0.1:64429_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 1815067 INFO (zkCallback-2531-thread-1-processing-n:127.0.0.1:64414_solr) [n:127.0.0.1:64414_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 1815068 INFO (jetty-closer-2475-thread-1) [ ] o.a.s.c.Overseer Overseer (id=98768151336714252-127.0.0.1:64429_solr-n_0000000000) closing [junit4] 2> 1815069 INFO (OverseerStateUpdate-98768151336714252-127.0.0.1:64429_solr-n_0000000000) [n:127.0.0.1:64429_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:64429_solr [junit4] 2> 1815070 WARN (zkCallback-2531-thread-2-processing-n:127.0.0.1:64414_solr) [n:127.0.0.1:64414_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 1815070 INFO (jetty-closer-2475-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@611f9e{/solr,null,UNAVAILABLE} [junit4] 2> 1815070 INFO (zkCallback-2499-thread-3-processing-n:127.0.0.1:64429_solr) [n:127.0.0.1:64429_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 1815072 WARN (zkCallback-2499-thread-2-processing-n:127.0.0.1:64429_solr) [n:127.0.0.1:64429_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 1815072 INFO (jetty-closer-2475-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@74370b{/solr,null,UNAVAILABLE} [junit4] 2> 1815077 ERROR (SUITE-CollectionsAPIDistributedZkTest-seed#[C54CEF44785C1850]-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> 1815077 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[C54CEF44785C1850]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:64407 64407 [junit4] 2> 1815078 INFO (Thread-3660) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:64407 64407 [junit4] 2> 1815079 WARN (Thread-3660) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 25 /solr/aliases.json [junit4] 2> 25 /solr/clusterprops.json [junit4] 2> 22 /solr/configs/conf [junit4] 2> 5 /solr/security.json [junit4] 2> 5 /solr/configs/conf2 [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 52 /solr/collections/addReplicaColl/state.json [junit4] 2> 32 /solr/collections/nodes_used_collection/state.json [junit4] 2> 25 /solr/clusterstate.json [junit4] 2> 24 /solr/collections/reloaded_collection/state.json [junit4] 2> 22 /solr/collections/awhollynewcollection_0/state.json [junit4] 2> 14 /solr/collections/halfdeletedcollection/state.json [junit4] 2> 13 /solr/collections/acollectionafterbaddelete/state.json [junit4] 2> 10 /solr/collections/onlyinzk/state.json [junit4] 2> 5 /solr/collections/halfcollectionblocker/state.json [junit4] 2> 5 /solr/collections/created_and_deleted/state.json [junit4] 2> 5 /solr/collections/halfcollectionblocker2/state.json [junit4] 2> 5 /solr/collections/withconfigset2/state.json [junit4] 2> 4 /solr/collections/halfcollection/state.json [junit4] 2> 3 /solr/overseer_elect/election/98768151336714252-127.0.0.1:64429_solr-n_0000000000 [junit4] 2> 2 /solr/collections/addReplicaColl/leader_elect/shard1/election/98768151336714250-core_node10-n_0000000002 [junit4] 2> 2 /solr/overseer_elect/election/98768151336714248-127.0.0.1:64417_solr-n_0000000002 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 380 /solr/collections [junit4] 2> 62 /solr/live_nodes [junit4] 2> [junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CollectionsAPIDistributedZkTest_C54CEF44785C1850-001 [junit4] 2> Oct 04, 2017 2:01:07 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 6 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Lucene70, sim=RandomSimilarity(queryNorm=false): {}, locale=de-AT, timezone=Asia/Yakutsk [junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 (32-bit)/cpus=3,threads=1,free=160739784,total=351719424 [junit4] 2> NOTE: All tests run in this JVM: [DataDrivenBlockJoinTest, TestCopyFieldCollectionResource, TestCollectionAPIs, SpellCheckComponentTest, FullSolrCloudDistribCmdsTest, CoreMergeIndexesAdminHandlerTest, TestDelegationWithHadoopAuth, TestFieldResource, DateRangeFieldTest, TestDocSet, TestTrie, SecurityConfHandlerTest, TestSolrCloudWithKerberosAlt, TestIndexSearcher, TolerantUpdateProcessorTest, TestUninvertingReader, DistributedFacetPivotWhiteBoxTest, TestConfigSetsAPIExclusivity, TestJettySolrRunner, PeerSyncReplicationTest, TestNumericTerms32, TestCloudPivotFacet, TestCoreAdminApis, SolrCoreCheckLockOnStartupTest, TestCloudRecovery, TestCrossCoreJoin, DocumentAnalysisRequestHandlerTest, TestSchemaVersionResource, TestSolrCLIRunExample, TestFieldTypeResource, TestSimpleTextCodec, TestReplicationHandlerBackup, TestDocumentBuilder, TestReload, TestShardsWithSingleReplica, TestCoreDiscovery, AnalyticsMergeStrategyTest, TestHighFrequencyDictionaryFactory, TestPartialUpdateDeduplication, TestGeoJSONResponseWriter, TestStreamBody, SimpleCollectionCreateDeleteTest, TestScoreJoinQPNoScore, TestRecovery, TestNumericRangeQuery32, SuggesterFSTTest, TestValueSourceCache, ImplicitSnitchTest, HdfsDirectoryFactoryTest, DistributedFacetPivotLargeTest, TestMinMaxOnMultiValuedField, DistributedQueryComponentOptimizationTest, TestCollapseQParserPlugin, SolrRequestParserTest, CheckHdfsIndexTest, BlockJoinFacetSimpleTest, SolrIndexMetricsTest, DistributedTermsComponentTest, SolrCoreMetricManagerTest, TestSizeLimitedDistributedMap, LeaderElectionIntegrationTest, SpellingQueryConverterTest, TestLRUCache, PolyFieldTest, TestPerFieldSimilarityWithDefaultOverride, TestStressUserVersions, ZkStateReaderTest, UninvertDocValuesMergePolicyTest, SignatureUpdateProcessorFactoryTest, TestMergePolicyConfig, DistribCursorPagingTest, TestLockTree, TestSimpleTrackingShardHandler, TestCustomStream, TestAuthorizationFramework, SpellCheckCollatorTest, ConfigSetsAPITest, DistributedIntervalFacetingTest, TestInitParams, TestElisionMultitermQuery, BasicFunctionalityTest, ShardRoutingCustomTest, TestDistribDocBasedVersion, MetricsHandlerTest, JvmMetricsTest, TestClusterProperties, TestSolrDeletionPolicy1, CopyFieldTest, FacetPivotSmallTest, DistributedMLTComponentTest, EchoParamsTest, HdfsDirectoryTest, TestDistributedMissingSort, TestQueryTypes, BadIndexSchemaTest, SaslZkACLProviderTest, TestManagedSynonymFilterFactory, TestRandomFaceting, SpellPossibilityIteratorTest, AssignBackwardCompatibilityTest, BasicAuthStandaloneTest, SchemaVersionSpecificBehaviorTest, SortSpecParsingTest, DeleteNodeTest, PreAnalyzedFieldTest, TestPivotHelperCode, TestConfigSets, TestStressLucene, JavabinLoaderTest, HdfsUnloadDistributedZkTest, BigEndianAscendingWordDeserializerTest, SharedFSAutoReplicaFailoverTest, TestRestoreCore, TestSolr4Spatial, TestJoin, TestRuleBasedAuthorizationPlugin, TestChildDocTransformer, TermsComponentTest, TestPayloadScoreQParserPlugin, AsyncCallRequestStatusResponseTest, SolrJmxReporterCloudTest, TestRestManager, TestAnalyzedSuggestions, ConjunctionSolrSpellCheckerTest, TestSolrXml, UpdateLogTest, TermVectorComponentTest, SolrSlf4jReporterTest, DistributedFacetPivotSmallAdvancedTest, SOLR749Test, TestNumericTokenStream, DistributedExpandComponentTest, TestAuthenticationFramework, SynonymTokenizerTest, TestCustomDocTransformer, TestRebalanceLeaders, SolrCloudExampleTest, PingRequestHandlerTest, SuggestComponentTest, HdfsChaosMonkeyNothingIsSafeTest, TestPerFieldSimilarity, TestFieldCacheWithThreads, TestInitQParser, AddSchemaFieldsUpdateProcessorFactoryTest, TestSchemaResource, TestPostingsSolrHighlighter, TestJsonFacetRefinement, TestTolerantUpdateProcessorCloud, TestLuceneIndexBackCompat, CdcrReplicationHandlerTest, UtilsToolTest, TestPullReplica, TestFastLRUCache, TestFiltering, SolrMetricReporterTest, TestCollectionsAPIViaSolrCloudCluster, SolrShardReporterTest, TestUniqueKeyFieldResource, CdcrBootstrapTest, PreAnalyzedUpdateProcessorTest, TestSolrConfigHandlerConcurrent, TestExpandComponent, TestExactSharedStatsCache, SimpleMLTQParserTest, QueryEqualityTest, TestOnReconnectListenerSupport, TestSolrCoreSnapshots, TestRemoteStreaming, TestCharFilters, LeaderFailoverAfterPartitionTest, TestRangeQuery, CacheHeaderTest, PluginInfoTest, OpenCloseCoreStressTest, ForceLeaderTest, TestSortByMinMaxFunction, TestConfigReload, SolrCmdDistributorTest, ShowFileRequestHandlerTest, TestCloudManagedSchema, FileUtilsTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest] [junit4] Completed [288/735 (1!)] on J1 in 80.84s, 19 tests, 1 error <<< FAILURES! [...truncated 44169 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
