You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2017/10/31 20:57:01 UTC
[JENKINS] Lucene-Solr-master-Solaris (64bit/jdk1.8.0) - Build #
1501 - Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Solaris/1501/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseSerialGC
2 tests failed.
FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI
Error Message:
Error from server at http://127.0.0.1:43905/solr/awhollynewcollection_0_shard3_replica_n4: ClusterState says we are the leader (http://127.0.0.1:43905/solr/awhollynewcollection_0_shard3_replica_n4), but locally we don't think so. Request came from null
Stack Trace:
org.apache.solr.client.solrj.impl.CloudSolrClient$RouteException: Error from server at http://127.0.0.1:43905/solr/awhollynewcollection_0_shard3_replica_n4: ClusterState says we are the leader (http://127.0.0.1:43905/solr/awhollynewcollection_0_shard3_replica_n4), but locally we don't think so. Request came from null
at __randomizedtesting.SeedInfo.seed([39C95B6195AFBB9F:71BC2FD5939C940A]:0)
at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:541)
at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1008)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:875)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:937)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:937)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:937)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:937)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:937)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:808)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:178)
at org.apache.solr.client.solrj.request.UpdateRequest.commit(UpdateRequest.java:233)
at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI(CollectionsAPIDistributedZkTest.java:459)
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)
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:43905/solr/awhollynewcollection_0_shard3_replica_n4: ClusterState says we are the leader (http://127.0.0.1:43905/solr/awhollynewcollection_0_shard3_replica_n4), but locally we don't think so. Request came from null
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:658)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
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.lambda$directUpdate$0(CloudSolrClient.java:518)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
... 1 more
FAILED: org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testParallelCommitStream
Error Message:
expected:<5> but was:<2>
Stack Trace:
java.lang.AssertionError: expected:<5> but was:<2>
at __randomizedtesting.SeedInfo.seed([5B7B0EE40B6887BC:7B916CE497296AF0]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testParallelCommitStream(StreamExpressionTest.java:4594)
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 12791 lines...]
[junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
[junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_39C95B6195AFBB9F-001/init-core-data-001
[junit4] 2> 2294164 WARN (SUITE-CollectionsAPIDistributedZkTest-seed#[39C95B6195AFBB9F]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=53 numCloses=53
[junit4] 2> 2294164 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[39C95B6195AFBB9F]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 2294165 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[39C95B6195AFBB9F]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 2294165 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[39C95B6195AFBB9F]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2294166 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[39C95B6195AFBB9F]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_39C95B6195AFBB9F-001/tempDir-001
[junit4] 2> 2294166 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[39C95B6195AFBB9F]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2294166 INFO (Thread-6745) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2294166 INFO (Thread-6745) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2294167 ERROR (Thread-6745) [ ] 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> 2294266 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[39C95B6195AFBB9F]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:56086
[junit4] 2> 2294272 INFO (jetty-launcher-3106-thread-1) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 2294273 INFO (jetty-launcher-3106-thread-3) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 2294273 INFO (jetty-launcher-3106-thread-4) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 2294273 INFO (jetty-launcher-3106-thread-2) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 2294275 INFO (jetty-launcher-3106-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@72608f71{/solr,null,AVAILABLE}
[junit4] 2> 2294275 INFO (jetty-launcher-3106-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@41982076{/solr,null,AVAILABLE}
[junit4] 2> 2294277 INFO (jetty-launcher-3106-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5b74ea50{HTTP/1.1,[http/1.1]}{127.0.0.1:54423}
[junit4] 2> 2294277 INFO (jetty-launcher-3106-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@d1502f1{HTTP/1.1,[http/1.1]}{127.0.0.1:62610}
[junit4] 2> 2294277 INFO (jetty-launcher-3106-thread-3) [ ] o.e.j.s.Server Started @2296371ms
[junit4] 2> 2294277 INFO (jetty-launcher-3106-thread-1) [ ] o.e.j.s.Server Started @2296371ms
[junit4] 2> 2294277 INFO (jetty-launcher-3106-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54423}
[junit4] 2> 2294277 INFO (jetty-launcher-3106-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=62610}
[junit4] 2> 2294277 INFO (jetty-launcher-3106-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@a8974a7{/solr,null,AVAILABLE}
[junit4] 2> 2294277 ERROR (jetty-launcher-3106-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2294277 INFO (jetty-launcher-3106-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 2294277 INFO (jetty-launcher-3106-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@65b22f9a{HTTP/1.1,[http/1.1]}{127.0.0.1:43905}
[junit4] 2> 2294277 INFO (jetty-launcher-3106-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2294277 INFO (jetty-launcher-3106-thread-4) [ ] o.e.j.s.Server Started @2296371ms
[junit4] 2> 2294277 INFO (jetty-launcher-3106-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2294277 INFO (jetty-launcher-3106-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=43905}
[junit4] 2> 2294277 INFO (jetty-launcher-3106-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-31T19:59:25.671Z
[junit4] 2> 2294277 ERROR (jetty-launcher-3106-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2294278 INFO (jetty-launcher-3106-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 2294278 INFO (jetty-launcher-3106-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2294277 ERROR (jetty-launcher-3106-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2294278 INFO (jetty-launcher-3106-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 2294278 INFO (jetty-launcher-3106-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2294278 INFO (jetty-launcher-3106-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2294278 INFO (jetty-launcher-3106-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-31T19:59:25.672Z
[junit4] 2> 2294278 INFO (jetty-launcher-3106-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2294278 INFO (jetty-launcher-3106-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-31T19:59:25.672Z
[junit4] 2> 2294279 INFO (jetty-launcher-3106-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6fad596a{/solr,null,AVAILABLE}
[junit4] 2> 2294279 INFO (jetty-launcher-3106-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4506312b{HTTP/1.1,[http/1.1]}{127.0.0.1:60697}
[junit4] 2> 2294279 INFO (jetty-launcher-3106-thread-2) [ ] o.e.j.s.Server Started @2296374ms
[junit4] 2> 2294279 INFO (jetty-launcher-3106-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=60697}
[junit4] 2> 2294280 ERROR (jetty-launcher-3106-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2294280 INFO (jetty-launcher-3106-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 2294280 INFO (jetty-launcher-3106-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2294280 INFO (jetty-launcher-3106-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2294280 INFO (jetty-launcher-3106-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-31T19:59:25.674Z
[junit4] 2> 2294281 INFO (jetty-launcher-3106-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2294281 INFO (jetty-launcher-3106-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2294281 INFO (jetty-launcher-3106-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2294283 INFO (jetty-launcher-3106-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2294284 INFO (jetty-launcher-3106-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2294284 INFO (jetty-launcher-3106-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2294285 INFO (jetty-launcher-3106-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2294285 INFO (jetty-launcher-3106-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2294285 INFO (jetty-launcher-3106-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2294285 INFO (jetty-launcher-3106-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2294287 INFO (jetty-launcher-3106-thread-4) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2294287 INFO (jetty-launcher-3106-thread-4) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2294289 INFO (jetty-launcher-3106-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56086/solr
[junit4] 2> 2294289 INFO (jetty-launcher-3106-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56086/solr
[junit4] 2> 2294289 INFO (jetty-launcher-3106-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56086/solr
[junit4] 2> 2294292 INFO (jetty-launcher-3106-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56086/solr
[junit4] 2> 2294532 INFO (jetty-launcher-3106-thread-2) [n:127.0.0.1:60697_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2294534 INFO (jetty-launcher-3106-thread-2) [n:127.0.0.1:60697_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:60697_solr
[junit4] 2> 2294534 INFO (jetty-launcher-3106-thread-3) [n:127.0.0.1:54423_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2294535 INFO (jetty-launcher-3106-thread-1) [n:127.0.0.1:62610_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2294537 INFO (jetty-launcher-3106-thread-2) [n:127.0.0.1:60697_solr ] o.a.s.c.Overseer Overseer (id=98925279023005705-127.0.0.1:60697_solr-n_0000000000) starting
[junit4] 2> 2294537 INFO (jetty-launcher-3106-thread-3) [n:127.0.0.1:54423_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54423_solr
[junit4] 2> 2294538 INFO (jetty-launcher-3106-thread-1) [n:127.0.0.1:62610_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:62610_solr
[junit4] 2> 2294539 INFO (zkCallback-3126-thread-1-processing-n:127.0.0.1:62610_solr) [n:127.0.0.1:62610_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2294540 INFO (zkCallback-3127-thread-1-processing-n:127.0.0.1:54423_solr) [n:127.0.0.1:54423_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2294541 INFO (zkCallback-3128-thread-1-processing-n:127.0.0.1:43905_solr) [n:127.0.0.1:43905_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2294542 INFO (zkCallback-3125-thread-1-processing-n:127.0.0.1:60697_solr) [n:127.0.0.1:60697_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2294543 INFO (zkCallback-3126-thread-1-processing-n:127.0.0.1:62610_solr) [n:127.0.0.1:62610_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2294543 INFO (jetty-launcher-3106-thread-4) [n:127.0.0.1:43905_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2294543 INFO (zkCallback-3127-thread-2-processing-n:127.0.0.1:54423_solr) [n:127.0.0.1:54423_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2294547 INFO (jetty-launcher-3106-thread-4) [n:127.0.0.1:43905_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43905_solr
[junit4] 2> 2294550 INFO (zkCallback-3128-thread-1-processing-n:127.0.0.1:43905_solr) [n:127.0.0.1:43905_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2294560 INFO (zkCallback-3127-thread-2-processing-n:127.0.0.1:54423_solr) [n:127.0.0.1:54423_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2294560 INFO (zkCallback-3125-thread-1-processing-n:127.0.0.1:60697_solr) [n:127.0.0.1:60697_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2294561 INFO (zkCallback-3126-thread-1-processing-n:127.0.0.1:62610_solr) [n:127.0.0.1:62610_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2294630 INFO (jetty-launcher-3106-thread-4) [n:127.0.0.1:43905_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60102d0c
[junit4] 2> 2294632 INFO (jetty-launcher-3106-thread-2) [n:127.0.0.1:60697_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60697_solr
[junit4] 2> 2294634 INFO (zkCallback-3127-thread-2-processing-n:127.0.0.1:54423_solr) [n:127.0.0.1:54423_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2294634 INFO (zkCallback-3128-thread-1-processing-n:127.0.0.1:43905_solr) [n:127.0.0.1:43905_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2294637 INFO (jetty-launcher-3106-thread-4) [n:127.0.0.1:43905_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60102d0c
[junit4] 2> 2294637 INFO (jetty-launcher-3106-thread-4) [n:127.0.0.1:43905_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60102d0c
[junit4] 2> 2294638 INFO (jetty-launcher-3106-thread-4) [n:127.0.0.1:43905_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_39C95B6195AFBB9F-001/tempDir-001/node4
[junit4] 2> 2294643 INFO (jetty-launcher-3106-thread-3) [n:127.0.0.1:54423_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60102d0c
[junit4] 2> 2294650 INFO (jetty-launcher-3106-thread-3) [n:127.0.0.1:54423_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60102d0c
[junit4] 2> 2294651 INFO (jetty-launcher-3106-thread-3) [n:127.0.0.1:54423_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60102d0c
[junit4] 2> 2294653 INFO (jetty-launcher-3106-thread-3) [n:127.0.0.1:54423_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_39C95B6195AFBB9F-001/tempDir-001/node3
[junit4] 2> 2294655 INFO (zkCallback-3125-thread-1-processing-n:127.0.0.1:60697_solr) [n:127.0.0.1:60697_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2294677 INFO (jetty-launcher-3106-thread-1) [n:127.0.0.1:62610_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60102d0c
[junit4] 2> 2294683 INFO (zkCallback-3126-thread-1-processing-n:127.0.0.1:62610_solr) [n:127.0.0.1:62610_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2294685 INFO (jetty-launcher-3106-thread-1) [n:127.0.0.1:62610_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60102d0c
[junit4] 2> 2294685 INFO (jetty-launcher-3106-thread-1) [n:127.0.0.1:62610_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60102d0c
[junit4] 2> 2294686 INFO (jetty-launcher-3106-thread-1) [n:127.0.0.1:62610_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_39C95B6195AFBB9F-001/tempDir-001/node1
[junit4] 2> 2294778 INFO (jetty-launcher-3106-thread-2) [n:127.0.0.1:60697_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60102d0c
[junit4] 2> 2294785 INFO (jetty-launcher-3106-thread-2) [n:127.0.0.1:60697_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60102d0c
[junit4] 2> 2294785 INFO (jetty-launcher-3106-thread-2) [n:127.0.0.1:60697_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60102d0c
[junit4] 2> 2294786 INFO (jetty-launcher-3106-thread-2) [n:127.0.0.1:60697_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_39C95B6195AFBB9F-001/tempDir-001/node2
[junit4] 2> 2294821 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[39C95B6195AFBB9F]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2294823 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[39C95B6195AFBB9F]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:56086/solr ready
[junit4] 2> 2294864 INFO (TEST-CollectionsAPIDistributedZkTest.deleteCollectionOnlyInZk-seed#[39C95B6195AFBB9F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting deleteCollectionOnlyInZk
[junit4] 2> 2294866 INFO (TEST-CollectionsAPIDistributedZkTest.deleteCollectionOnlyInZk-seed#[39C95B6195AFBB9F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2294873 INFO (qtp425568925-20948) [n:127.0.0.1:62610_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> 2294882 INFO (qtp425568925-20948) [n:127.0.0.1:62610_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=onlyinzk&action=DELETE&wt=javabin&version=2} status=0 QTime=8
[junit4] 2> 2294882 INFO (qtp425568925-20949) [n:127.0.0.1:62610_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2294882 INFO (qtp425568925-20949) [n:127.0.0.1:62610_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> 2294883 INFO (qtp425568925-20947) [n:127.0.0.1:62610_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> 2294886 INFO (OverseerThreadFactory-8392-thread-2-processing-n:127.0.0.1:60697_solr) [n:127.0.0.1:60697_solr ] o.a.s.c.CreateCollectionCmd Create collection onlyinzk
[junit4] 2> 2294887 INFO (OverseerCollectionConfigSetProcessor-98925279023005705-127.0.0.1:60697_solr-n_0000000000) [n:127.0.0.1:60697_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> 2295000 INFO (OverseerStateUpdate-98925279023005705-127.0.0.1:60697_solr-n_0000000000) [n:127.0.0.1:60697_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:60697/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2295004 INFO (OverseerStateUpdate-98925279023005705-127.0.0.1:60697_solr-n_0000000000) [n:127.0.0.1:60697_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:54423/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2295214 INFO (qtp270024657-20955) [n:127.0.0.1:60697_solr ] o.a.s.u.TestInjection Inject random core creation delay of 2s
[junit4] 2> 2295215 INFO (qtp1892567940-20957) [n:127.0.0.1:54423_solr ] o.a.s.u.TestInjection Inject random core creation delay of 2s
[junit4] 2> 2297215 INFO (qtp1892567940-20957) [n:127.0.0.1:54423_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> 2297215 INFO (qtp1892567940-20957) [n:127.0.0.1:54423_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2297220 INFO (qtp270024657-20955) [n:127.0.0.1:60697_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> 2297220 INFO (qtp270024657-20955) [n:127.0.0.1:60697_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2297223 INFO (zkCallback-3127-thread-2-processing-n:127.0.0.1:54423_solr) [n:127.0.0.1:54423_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> 2297223 INFO (zkCallback-3127-thread-1-processing-n:127.0.0.1:54423_solr) [n:127.0.0.1:54423_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> 2297329 INFO (zkCallback-3125-thread-1-processing-n:127.0.0.1:60697_solr) [n:127.0.0.1:60697_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> 2297329 INFO (zkCallback-3127-thread-1-processing-n:127.0.0.1:54423_solr) [n:127.0.0.1:54423_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> 2297329 INFO (zkCallback-3127-thread-2-processing-n:127.0.0.1:54423_solr) [n:127.0.0.1:54423_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> 2298235 INFO (qtp1892567940-20957) [n:127.0.0.1:54423_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> 2298237 INFO (qtp270024657-20955) [n:127.0.0.1:60697_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> 2298241 INFO (qtp1892567940-20957) [n:127.0.0.1:54423_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> 2298243 INFO (qtp1892567940-20957) [n:127.0.0.1:54423_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> 2298243 INFO (qtp1892567940-20957) [n:127.0.0.1:54423_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> 2298243 INFO (qtp270024657-20955) [n:127.0.0.1:60697_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> 2298243 INFO (qtp1892567940-20957) [n:127.0.0.1:54423_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@60102d0c
[junit4] 2> 2298244 INFO (qtp1892567940-20957) [n:127.0.0.1:54423_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2298244 INFO (qtp1892567940-20957) [n:127.0.0.1:54423_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 [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_39C95B6195AFBB9F-001/tempDir-001/node3/onlyinzk_shard2_replica_n2], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_39C95B6195AFBB9F-001/tempDir-001/node3/onlyinzk_shard2_replica_n2/data/]
[junit4] 2> 2298245 INFO (qtp270024657-20955) [n:127.0.0.1:60697_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> 2298245 INFO (qtp270024657-20955) [n:127.0.0.1:60697_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> 2298245 INFO (qtp270024657-20955) [n:127.0.0.1:60697_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@60102d0c
[junit4] 2> 2298245 INFO (qtp270024657-20955) [n:127.0.0.1:60697_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2298246 INFO (qtp270024657-20955) [n:127.0.0.1:60697_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 [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_39C95B6195AFBB9F-001/tempDir-001/node2/onlyinzk_shard1_replica_n1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_39C95B6195AFBB9F-001/tempDir-001/node2/onlyinzk_shard1_replica_n1/data/]
[junit4] 2> 2298318 INFO (qtp270024657-20955) [n:127.0.0.1:60697_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> 2298318 INFO (qtp270024657-20955) [n:127.0.0.1:60697_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> 2298319 INFO (qtp1892567940-20957) [n:127.0.0.1:54423_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> 2298319 INFO (qtp1892567940-20957) [n:127.0.0.1:54423_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> 2298319 INFO (qtp270024657-20955) [n:127.0.0.1:60697_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2298319 INFO (qtp270024657-20955) [n:127.0.0.1:60697_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2298320 INFO (qtp1892567940-20957) [n:127.0.0.1:54423_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2298320 INFO (qtp1892567940-20957) [n:127.0.0.1:54423_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2298320 INFO (qtp270024657-20955) [n:127.0.0.1:60697_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@24b81759[onlyinzk_shard1_replica_n1] main]
[junit4] 2> 2298321 INFO (qtp1892567940-20957) [n:127.0.0.1:54423_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@5e10a005[onlyinzk_shard2_replica_n2] main]
[junit4] 2> 2298322 INFO (qtp270024657-20955) [n:127.0.0.1:60697_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> 2298323 INFO (qtp1892567940-20957) [n:127.0.0.1:54423_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> 2298323 INFO (qtp270024657-20955) [n:127.0.0.1:60697_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> 2298323 INFO (qtp1892567940-20957) [n:127.0.0.1:54423_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> 2298324 INFO (qtp270024657-20955) [n:127.0.0.1:60697_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> 2298324 INFO (qtp270024657-20955) [n:127.0.0.1:60697_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 1582804468727021568
[junit4] 2> 2298324 INFO (qtp1892567940-20957) [n:127.0.0.1:54423_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> 2298324 INFO (qtp1892567940-20957) [n:127.0.0.1:54423_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 1582804468727021568
[junit4] 2> 2298325 INFO (searcherExecutor-8400-thread-1-processing-n:127.0.0.1:60697_solr x:onlyinzk_shard1_replica_n1 s:shard1 c:onlyinzk r:core_node3) [n:127.0.0.1:60697_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@24b81759[onlyinzk_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2298325 INFO (searcherExecutor-8399-thread-1-processing-n:127.0.0.1:54423_solr x:onlyinzk_shard2_replica_n2 s:shard2 c:onlyinzk r:core_node4) [n:127.0.0.1:54423_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@5e10a005[onlyinzk_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2298332 INFO (qtp1892567940-20957) [n:127.0.0.1:54423_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> 2298332 INFO (qtp1892567940-20957) [n:127.0.0.1:54423_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> 2298332 INFO (qtp1892567940-20957) [n:127.0.0.1:54423_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:54423/solr/onlyinzk_shard2_replica_n2/
[junit4] 2> 2298332 INFO (qtp1892567940-20957) [n:127.0.0.1:54423_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> 2298332 INFO (qtp1892567940-20957) [n:127.0.0.1:54423_solr c:onlyinzk s:shard2 r:core_node4 x:onlyinzk_shard2_replica_n2] o.a.s.c.SyncStrategy http://127.0.0.1:54423/solr/onlyinzk_shard2_replica_n2/ has no replicas
[junit4] 2> 2298332 INFO (qtp270024657-20955) [n:127.0.0.1:60697_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> 2298332 INFO (qtp270024657-20955) [n:127.0.0.1:60697_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> 2298332 INFO (qtp1892567940-20957) [n:127.0.0.1:54423_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> 2298332 INFO (qtp270024657-20955) [n:127.0.0.1:60697_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:60697/solr/onlyinzk_shard1_replica_n1/
[junit4] 2> 2298332 INFO (qtp270024657-20955) [n:127.0.0.1:60697_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> 2298332 INFO (qtp270024657-20955) [n:127.0.0.1:60697_solr c:onlyinzk s:shard1 r:core_node3 x:onlyinzk_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:60697/solr/onlyinzk_shard1_replica_n1/ has no replicas
[junit4] 2> 2298333 INFO (qtp270024657-20955) [n:127.0.0.1:60697_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> 2298339 INFO (qtp1892567940-20957) [n:127.0.0.1:54423_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:54423/solr/onlyinzk_shard2_replica_n2/ shard2
[junit4] 2> 2298341 INFO (qtp270024657-20955) [n:127.0.0.1:60697_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:60697/solr/onlyinzk_shard1_replica_n1/ shard1
[junit4] 2> 2298443 INFO (zkCallback-3125-thread-1-processing-n:127.0.0.1:60697_solr) [n:127.0.0.1:60697_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> 2298443 INFO (zkCallback-3127-thread-1-processing-n:127.0.0.1:54423_solr) [n:127.0.0.1:54423_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> 2298443 INFO (zkCallback-3127-thread-2-processing-n:127.0.0.1:54423_solr) [n:127.0.0.1:54423_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> 2298446 INFO (qtp270024657-20955) [n:127.0.0.1:60697_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> 2298448 INFO (qtp270024657-20955) [n:127.0.0.1:60697_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=3234
[junit4] 2> 2298491 INFO (qtp1892567940-20957) [n:127.0.0.1:54423_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> 2298494 INFO (qtp1892567940-20957) [n:127.0.0.1:54423_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=3279
[junit4] 2> 2298496 INFO (qtp425568925-20947) [n:127.0.0.1:62610_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> 2298595 INFO (zkCallback-3127-thread-2-processing-n:127.0.0.1:54423_solr) [n:127.0.0.1:54423_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> 2298595 INFO (zkCallback-3125-thread-1-processing-n:127.0.0.1:60697_solr) [n:127.0.0.1:60697_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> 2298595 INFO (zkCallback-3127-thread-1-processing-n:127.0.0.1:54423_solr) [n:127.0.0.1:54423_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> 2298892 INFO (OverseerCollectionConfigSetProcessor-98925279023005705-127.0.0.1:60697_solr-n_0000000000) [n:127.0.0.1:60697_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> 2299501 INFO (qtp425568925-20947) [n:127.0.0.1:62610_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=4618
[junit4] 2> 2299501 INFO (qtp425568925-20951) [n:127.0.0.1:62610_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2299502 INFO (qtp425568925-20951) [n:127.0.0.1:62610_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> 2299502 INFO (TEST-CollectionsAPIDistributedZkTest.deleteCollectionOnlyInZk-seed#[39C95B6195AFBB9F]) [ ] o.a.s.SolrTestCaseJ4 ###Ending deleteCollectionOnlyInZk
[junit4] 2> 2299537 INFO (TEST-CollectionsAPIDistributedZkTest.testCreateShouldFailOnExistingCore-seed#[39C95B6195AFBB9F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateShouldFailOnExistingCore
[junit4] 2> 2299541 INFO (TEST-CollectionsAPIDistributedZkTest.testCreateShouldFailOnExistingCore-seed#[39C95B6195AFBB9F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2299543 INFO (qtp425568925-20952) [n:127.0.0.1:62610_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> 2299555 INFO (OverseerThreadFactory-8392-thread-3-processing-n:127.0.0.1:60697_solr) [n:127.0.0.1:60697_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 2299556 INFO (qtp270024657-20967) [n:127.0.0.1:60697_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.onlyinzk.shard1.replica_n1, tag=null
[junit4] 2> 2299556 INFO (qtp1892567940-20958) [n:127.0.0.1:54423_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.onlyinzk.shard2.replica_n2, tag=null
[junit4] 2> 2299556 INFO (qtp270024657-20967) [n:127.0.0.1:60697_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@959ce22: rootName = null, domain = solr.core.onlyinzk.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.onlyinzk.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@55715ed
[junit4] 2> 2299556 INFO (qtp1892567940-20958) [n:127.0.0.1:54423_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1d02609f: rootName = null, domain = solr.core.onlyinzk.shard2.replica_n2, service url = null, agent id = null] for registry solr.core.onlyinzk.shard2.replica_n2 / com.codahale.metrics.MetricRegistry@48a950e4
[junit4] 2> 2299570 INFO (qtp1892567940-20958) [n:127.0.0.1:54423_solr ] o.a.s.c.SolrCore [onlyinzk_shard2_replica_n2] CLOSING SolrCore org.apache.solr.core.SolrCore@6256c314
[junit4] 2> 2299570 INFO (qtp1892567940-20958) [n:127.0.0.1:54423_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.onlyinzk.shard2.replica_n2, tag=1649853204
[junit4] 2> 2299570 INFO (qtp1892567940-20958) [n:127.0.0.1:54423_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.onlyinzk.shard2.leader, tag=1649853204
[junit4] 2> 2299570 INFO (qtp270024657-20967) [n:127.0.0.1:60697_solr ] o.a.s.c.SolrCore [onlyinzk_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@5b0d1628
[junit4] 2> 2299570 INFO (qtp270024657-20967) [n:127.0.0.1:60697_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.onlyinzk.shard1.replica_n1, tag=1527584296
[junit4] 2> 2299570 INFO (qtp270024657-20967) [n:127.0.0.1:60697_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.onlyinzk.shard1.leader, tag=1527584296
[junit4] 2> 2299573 INFO (qtp270024657-20967) [n:127.0.0.1:60697_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=onlyinzk_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=17
[junit4] 2> 2299573 INFO (qtp1892567940-20958) [n:127.0.0.1:54423_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=onlyinzk_shard2_replica_n2&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=17
[junit4] 2> 2300279 INFO (qtp425568925-20952) [n:127.0.0.1:62610_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=onlyinzk&action=DELETE&wt=javabin&version=2} status=0 QTime=736
[junit4] 2> 2300279 INFO (qtp425568925-20963) [n:127.0.0.1:62610_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf&name=halfcollectionblocker&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2300283 INFO (OverseerThreadFactory-8392-thread-4-processing-n:127.0.0.1:60697_solr) [n:127.0.0.1:60697_solr ] o.a.s.c.CreateCollectionCmd Create collection halfcollectionblocker
[junit4] 2> 2300283 INFO (OverseerCollectionConfigSetProcessor-98925279023005705-127.0.0.1:60697_solr-n_0000000000) [n:127.0.0.1:60697_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> 2300283 WARN (OverseerThreadFactory-8392-thread-4-processing-n:127.0.0.1:60697_solr) [n:127.0.0.1:60697_solr ] o.a.s.c.CreateCollectionCmd It is unusual to create a collection (halfcollectionblocker) without cores.
[junit4] 2> 2300497 INFO (qtp425568925-20963) [n:127.0.0.1:62610_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> 2300498 INFO (qtp425568925-20963) [n:127.0.0.1:62610_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf&name=halfcollectionblocker&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=&wt=javabin&version=2} status=0 QTime=218
[junit4] 2> 2300498 INFO (qtp425568925-20945) [n:127.0.0.1:62610_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:43905_solr&name=halfcollection_shard1_replica_n1&action=ADDREPLICA&collection=halfcollectionblocker&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2300502 INFO (OverseerCollectionConfigSetProcessor-98925279023005705-127.0.0.1:60697_solr-n_0000000000) [n:127.0.0.1:60697_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> 2300503 INFO (OverseerThreadFactory-8392-thread-5-processing-n:127.0.0.1:60697_solr) [n:127.0.0.1:60697_solr ] o.a.s.c.AddReplicaCmd Node Identified 127.0.0.1:43905_solr for creating new replica
[junit4] 2> 2300506 INFO (OverseerStateUpdate-98925279023005705-127.0.0.1:60697_solr-n_0000000000) [n:127.0.0.1:60697_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"addreplica",
[junit4] 2> "collection":"halfcollectionblocker",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"halfcollection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:43905/solr",
[junit4] 2> "node_name":"127.0.0.1:43905_solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 2300716 INFO (qtp871521207-20972) [n:127.0.0.1:43905_solr ] o.a.s.u.TestInjection Inject random core creation delay of 2s
[junit4] 2> 2302718 INFO (qtp871521207-20972) [n:127.0.0.1:43905_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node21&collection.configName=conf&name=halfcollection_shard1_replica_n1&action=CREATE&collection=halfcollectionblocker&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2302719 INFO (qtp871521207-20972) [n:127.0.0.1:43905_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2302727 INFO (zkCallback-3128-thread-1-processing-n:127.0.0.1:43905_solr) [n:127.0.0.1:43905_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfcollectionblocker/state.json] for collection [halfcollectionblocker] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2302727 INFO (zkCallback-3128-thread-2-processing-n:127.0.0.1:43905_solr) [n:127.0.0.1:43905_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfcollectionblocker/state.json] for collection [halfcollectionblocker] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2302734 INFO (qtp871521207-20972) [n:127.0.0.1:43905_solr c:halfcollectionblocker s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2302738 INFO (qtp871521207-20972) [n:127.0.0.1:43905_solr c:halfcollectionblocker s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.s.IndexSchema [halfcollection_shard1_replica_n1] Schema name=minimal
[junit4] 2> 2302739 INFO (qtp871521207-20972) [n:127.0.0.1:43905_solr c:halfcollectionblocker s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2302739 INFO (qtp871521207-20972) [n:127.0.0.1:43905_solr c:halfcollectionblocker s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'halfcollection_shard1_replica_n1' using configuration from collection halfcollectionblocker, trusted=true
[junit4] 2> 2302740 INFO (qtp871521207-20972) [n:127.0.0.1:43905_solr c:halfcollectionblocker s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.halfcollectionblocker.shard1.core_node21' (registry 'solr.core.halfcollectionblocker.shard1.core_node21') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60102d0c
[junit4] 2> 2302740 INFO (qtp871521207-20972) [n:127.0.0.1:43905_solr c:halfcollectionblocker s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2302740 INFO (qtp871521207-20972) [n:127.0.0.1:43905_solr c:halfcollectionblocker s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.c.SolrCore [[halfcollection_shard1_replica_n1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_39C95B6195AFBB9F-001/tempDir-001/node4/halfcollection_shard1_replica_n1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_39C95B6195AFBB9F-001/tempDir-001/node4/halfcollection_shard1_replica_n1/data/]
[junit4] 2> 2302781 INFO (qtp871521207-20972) [n:127.0.0.1:43905_solr c:halfcollectionblocker s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2302781 INFO (qtp871521207-20972) [n:127.0.0.1:43905_solr c:halfcollectionblocker s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2302782 INFO (qtp871521207-20972) [n:127.0.0.1:43905_solr c:halfcollectionblocker s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2302782 INFO (qtp871521207-20972) [n:127.0.0.1:43905_solr c:halfcollectionblocker s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2302783 INFO (qtp871521207-20972) [n:127.0.0.1:43905_solr c:halfcollectionblocker s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@417543f2[halfcollection_shard1_replica_n1] main]
[junit4] 2> 2302784 INFO (qtp871521207-20972) [n:127.0.0.1:43905_solr c:halfcollectionblocker s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 2302784 INFO (qtp871521207-20972) [n:127.0.0.1:43905_solr c:halfcollectionblocker s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2302785 INFO (qtp871521207-20972) [n:127.0.0.1:43905_solr c:halfcollectionblocker s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2302785 INFO (searcherExecutor-8409-thread-1-processing-n:127.0.0.1:43905_solr x:halfcollection_shard1_replica_n1 s:shard1 c:halfcollectionblocker r:core_node21) [n:127.0.0.1:43905_solr c:halfcollectionblocker s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.c.SolrCore [halfcollection_shard1_replica_n1] Registered new searcher Searcher@417543f2[halfcollection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2302785 INFO (qtp871521207-20972) [n:127.0.0.1:43905_solr c:halfcollectionblocker s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1582804473404719104
[junit4] 2> 2302791 INFO (qtp871521207-20972) [n:127.0.0.1:43905_solr c:halfcollectionblocker s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2302791 INFO (qtp871521207-20972) [n:127.0.0.1:43905_solr c:halfcollectionblocker s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2302791 INFO (qtp871521207-20972) [n:127.0.0.1:43905_solr c:halfcollectionblocker s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:43905/solr/halfcollection_shard1_replica_n1/
[junit4] 2> 2302791 INFO (qtp871521207-20972) [n:127.0.0.1:43905_solr c:halfcollectionblocker s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2302791 INFO (qtp871521207-20972) [n:127.0.0.1:43905_solr c:halfcollectionblocker s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:43905/solr/halfcollection_shard1_replica_n1/ has no replicas
[junit4] 2> 2302791 INFO (qtp871521207-20972) [n:127.0.0.1:43905_solr c:halfcollectionblocker s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 2302795 INFO (qtp871521207-20972) [n:127.0.0.1:43905_solr c:halfcollectionblocker s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:43905/solr/halfcollection_shard1_replica_n1/ shard1
[junit4] 2> 2302898 INFO (zkCallback-3128-thread-1-processing-n:127.0.0.1:43905_solr) [n:127.0.0.1:43905_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfcollectionblocker/state.json] for collection [halfcollectionblocker] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2302898 INFO (zkCallback-3128-thread-2-processing-n:127.0.0.1:43905_solr) [n:127.0.0.1:43905_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfcollectionblocker/state.json] for collection [halfcollectionblocker] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2302946 INFO (qtp871521207-20972) [n:127.0.0.1:43905_solr c:halfcollectionblocker s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2302949 INFO (qtp871521207-20972) [n:127.0.0.1:43905_solr c:halfcollectionblocker s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node21&collection.configName=conf&name=halfcollection_shard1_replica_n1&action=CREATE&collection=halfcollectionblocker&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2233
[junit4] 2> 2302955 INFO (qtp425568925-20945) [n:127.0.0.1:62610_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:43905_solr&name=halfcollection_shard1_replica_n1&action=ADDREPLICA&collection=halfcollectionblocker&shard=shard1&type=NRT&wt=javabin&version=2} status=0 QTime=2456
[junit4] 2> 2302955 INFO (qtp425568925-20948) [n:127.0.0.1:62610_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf&name=halfcollectionblocker2&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2302960 INFO (OverseerThreadFactory-8392-thread-5-processing-n:127.0.0.1:60697_solr) [n:127.0.0.1:60697_solr ] o.a.s.c.CreateCollectionCmd Create collection halfcollectionblocker2
[junit4] 2> 2302961 INFO (OverseerCollectionConfigSetProcessor-98925279023005705-127.0.0.1:60697_solr-n_0000000000) [n:127.0.0.1:60697_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> 2302962 WARN (OverseerThreadFactory-8392-thread-5-processing-n:127.0.0.1:60697_solr) [n:127.0.0.1:60697_solr ] o.a.s.c.CreateCollectionCmd It is unusual to create a collection (halfcollectionblocker2) without cores.
[junit4] 2> 2303076 INFO (zkCallback-3128-thread-2-processing-n:127.0.0.1:43905_solr) [n:127.0.0.1:43905_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfcollectionblocker/state.json] for collection [halfcollectionblocker] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2303076 INFO (zkCallback-3128-thread-1-processing-n:127.0.0.1:43905_solr) [n:127.0.0.1:43905_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfcollectionblocker/state.json] for collection [halfcollectionblocker] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2303181 INFO (qtp425568925-20948) [n:127.0.0.1:62610_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> 2303182 INFO (qtp425568925-20948) [n:127.0.0.1:62610_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf&name=halfcollectionblocker2&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=&wt=javabin&version=2} status=0 QTime=226
[junit4] 2> 2303182 INFO (qtp425568925-20949) [n:127.0.0.1:62610_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:54423_solr&name=halfcollection_shard1_replica_n1&action=ADDREPLICA&collection=halfcollectionblocker2&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2303189 INFO (OverseerCollectionConfigSetProcessor-98925279023005705-127.0.0.1:60697_solr-n_0000000000) [n:127.0.0.1:60697_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> 2303192 INFO (OverseerThreadFactory-8392-thread-5-processing-n:127.0.0.1:60697_solr) [n:127.0.0.1:60697_solr ] o.a.s.c.AddReplicaCmd Node Identified 127.0.0.1:54423_solr for creating new replica
[junit4] 2> 2303196 INFO (OverseerStateUpdate-98925279023005705-127.0.0.1:60697_solr-n_0000000000) [n:127.0.0.1:60697_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"addreplica",
[junit4] 2> "collection":"halfcollectionblocker2",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"halfcollection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:54423/solr",
[junit4] 2> "node_name":"127.0.0.1:54423_solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 2303411 INFO (qtp1892567940-20959) [n:127.0.0.1:54423_solr ] o.a.s.u.TestInjection Inject random core creation delay of 2s
[junit4] 2> 2305412 INFO (qtp1892567940-20959) [n:127.0.0.1:54423_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node21&collection.configName=conf&name=halfcollection_shard1_replica_n1&action=CREATE&collection=halfcollectionblocker2&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2305421 INFO (zkCallback-3127-thread-2-processing-n:127.0.0.1:54423_solr) [n:127.0.0.1:54423_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfcollectionblocker2/state.json] for collection [halfcollectionblocker2] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2305421 INFO (zkCallback-3127-thread-1-processing-n:127.0.0.1:54423_solr) [n:127.0.0.1:54423_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfcollectionblocker2/state.json] for collection [halfcollectionblocker2] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2305462 INFO (qtp1892567940-20959) [n:127.0.0.1:54423_solr c:halfcollectionblocker2 s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2305467 INFO (qtp1892567940-20959) [n:127.0.0.1:54423_solr c:halfcollectionblocker2 s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.s.IndexSchema [halfcollection_shard1_replica_n1] Schema name=minimal
[junit4] 2> 2305468 INFO (qtp1892567940-20959) [n:127.0.0.1:54423_solr c:halfcollectionblocker2 s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2305468 INFO (qtp1892567940-20959) [n:127.0.0.1:54423_solr c:halfcollectionblocker2 s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'halfcollection_shard1_replica_n1' using configuration from collection halfcollectionblocker2, trusted=true
[junit4] 2> 2305468 INFO (qtp1892567940-20959) [n:127.0.0.1:54423_solr c:halfcollectionblocker2 s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.halfcollectionblocker2.shard1.core_node21' (registry 'solr.core.halfcollectionblocker2.shard1.core_node21') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60102d0c
[junit4] 2> 2305468 INFO (qtp1892567940-20959) [n:127.0.0.1:54423_solr c:halfcollectionblocker2 s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2305469 INFO (qtp1892567940-20959) [n:127.0.0.1:54423_solr c:halfcollectionblocker2 s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.c.SolrCore [[halfcollection_shard1_replica_n1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_39C95B6195AFBB9F-001/tempDir-001/node3/halfcollection_shard1_replica_n1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_39C95B6195AFBB9F-001/tempDir-001/node3/halfcollection_shard1_replica_n1/data/]
[junit4] 2> 2305503 INFO (qtp1892567940-20959) [n:127.0.0.1:54423_solr c:halfcollectionblocker2 s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2305503 INFO (qtp1892567940-20959) [n:127.0.0.1:54423_solr c:halfcollectionblocker2 s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2305504 INFO (qtp1892567940-20959) [n:127.0.0.1:54423_solr c:halfcollectionblocker2 s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2305504 INFO (qtp1892567940-20959) [n:127.0.0.1:54423_solr c:halfcollectionblocker2 s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2305505 INFO (qtp1892567940-20959) [n:127.0.0.1:54423_solr c:halfcollectionblocker2 s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@250924d5[halfcollection_shard1_replica_n1] main]
[junit4] 2> 2305506 INFO (qtp1892567940-20959) [n:127.0.0.1:54423_solr c:halfcollectionblocker2 s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 2305507 INFO (qtp1892567940-20959) [n:127.0.0.1:54423_solr c:halfcollectionblocker2 s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2305507 INFO (qtp1892567940-20959) [n:127.0.0.1:54423_solr c:halfcollectionblocker2 s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2305508 INFO (searcherExecutor-8414-thread-1-processing-n:127.0.0.1:54423_solr x:halfcollection_shard1_replica_n1 s:shard1 c:halfcollectionblocker2 r:core_node21) [n:127.0.0.1:54423_solr c:halfcollectionblocker2 s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.c.SolrCore [halfcollection_shard1_replica_n1] Registered new searcher Searcher@250924d5[halfcollection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2305508 INFO (qtp1892567940-20959) [n:127.0.0.1:54423_solr c:halfcollectionblocker2 s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1582804476259991552
[junit4] 2> 2305513 INFO (qtp1892567940-20959) [n:127.0.0.1:54423_solr c:halfcollectionblocker2 s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2305513 INFO (qtp1892567940-20959) [n:127.0.0.1:54423_solr c:halfcollectionblocker2 s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2305513 INFO (qtp1892567940-20959) [n:127.0.0.1:54423_solr c:halfcollectionblocker2 s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:54423/solr/halfcollection_shard1_replica_n1/
[junit4] 2> 2305513 INFO (qtp1892567940-20959) [n:127.0.0.1:54423_solr c:halfcollectionblocker2 s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2305513 INFO (qtp1892567940-20959) [n:127.0.0.1:54423_solr c:halfcollectionblocker2 s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:54423/solr/halfcollection_shard1_replica_n1/ has no replicas
[junit4] 2> 2305513 INFO (qtp1892567940-20959) [n:127.0.0.1:54423_solr c:halfcollectionblocker2 s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 2305517 INFO (qtp1892567940-20959) [n:127.0.0.1:54423_solr c:halfcollectionblocker2 s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:54423/solr/halfcollection_shard1_replica_n1/ shard1
[junit4] 2> 2305623 INFO (zkCallback-3127-thread-2-processing-n:127.0.0.1:54423_solr) [n:127.0.0.1:54423_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfcollectionblocker2/state.json] for collection [halfcollectionblocker2] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2305623 INFO (zkCallback-3127-thread-1-processing-n:127.0.0.1:54423_solr) [n:127.0.0.1:54423_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfcollectionblocker2/state.json] for collection [halfcollectionblocker2] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2305672 INFO (qtp1892567940-20959) [n:127.0.0.1:54423_solr c:halfcollectionblocker2 s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2305674 INFO (qtp1892567940-20959) [n:127.0.0.1:54423_solr c:halfcollectionblocker2 s:shard1 r:core_node21 x:halfcollection_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node21&collection.configName=conf&name=halfcollection_shard1_replica_n1&action=CREATE&collection=halfcollectionblocker2&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2263
[junit4] 2> 2305677 INFO (qtp425568925-20949) [n:127.0.0.1:62610_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:54423_solr&name=halfcollection_shard1_replica_n1&action=ADDREPLICA&collection=halfcollectionblocker2&shard=shard1&type=NRT&wt=javabin&version=2} status=0 QTime=2494
[junit4] 2> 2305678 INFO (qtp425568925-20947) [n:127.0.0.1:62610_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf&name=halfcollection&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=127.0.0.1:43905_solr,127.0.0.1:54423_solr&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2305680 INFO (OverseerThreadFactory-8392-thread-5-processing-n:127.0.0.1:60697_solr) [n:127.0.0.1:60697_solr ] o.a.s.c.CreateCollectionCmd Create collection halfcollection
[junit4] 2> 2305680 INFO (OverseerCollectionConfigSetProcessor-98925279023005705-127.0.0.1:60697_solr-n_0000000000) [n:127.0.0.1:60697_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000012 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 2305788 INFO (zkCallback-3127-thread-1-processing-n:127.0.0.1:54423_solr) [n:127.0.0.1:54423_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfcollectionblocker2/state.json] for collection [halfcollectionblocker2] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2305788 INFO (zkCallback-3127-thread-2-processing-n:127.0.0.1:54423_solr) [n:127.0.0.1:54423_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/halfcollectionblocker2/state.json] for collection [halfcollectionblocker2] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2305897 INFO (OverseerStateUpdate-98925279023005705-127.0.0.1:60697_solr-n_0000000000) [n:127.0.0.1:60697_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"halfcollection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"halfcollection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:54423/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2305904 INFO (OverseerStateUpdate-98925279023005705-127.0.0.1:60697_solr-n_0000000000) [n:127.0.0.1:60697_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"halfcollection",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"halfcollection_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:43905/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2306105 INFO (qtp1892567940-20960) [n:127.0.0.1:54423_solr ] o.a.s.u.TestInjection Inject random core creation delay of 2s
[junit4] 2> 2306106 INFO (qtp871521207-20953) [n:127.0.0.1:43905_solr ] o.a.s.u.TestInjection Inject random core creation delay of 2s
[junit4] 2> 2308105 INFO (qtp1892567940-20960) [n:127.0.0.1:54423_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=halfcollection_shard1_replica_n1&action=CREATE&numShards=2&collection=halfcollection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2308105 WARN (qtp1892567940-20960) [n:127.0.0.1:54423_solr ] o.a.s.c.CoreContainer Creating a core with existing name is not allowed
[junit4] 2> 2308106 ERROR (qtp1892567940-20960) [n:127.0.0.1:54423_solr ] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: Core with name 'halfcollection_shard1_replica_n1' already exists.
[junit4] 2> at org.apache.solr.core.CoreContainer.create(CoreContainer.java:930)
[junit4] 2> at org.apache.solr.handler.admin.CoreAdminOperation.lambda$static$0(CoreAdminOperation.java:91)
[junit4] 2> at org.apache.solr.handler.admin.CoreAdminOperation.execute(CoreAdminOperation.java:384)
[junit4] 2> at org.apache.solr.handler.admin.CoreAdminHandler$CallInfo.call(CoreAdminHandler.java:389)
[junit4] 2> at org.apache.solr.handler.admin.CoreAdminHandler.handleRequestBody(CoreAdminHandler.java:174)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:735)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:716)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:497)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:382)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.han
[...truncated too long message...]
oseExecutor-235-thread-1) [n:127.0.0.1:55443_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1.shard2.replica_n2, tag=690313736
[junit4] 2> 127017 INFO (coreCloseExecutor-235-thread-1) [n:127.0.0.1:55443_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@64b2ee0b: rootName = solr_55443, domain = solr.core.collection1.shard2.replica_n2, service url = null, agent id = null] for registry solr.core.collection1.shard2.replica_n2 / com.codahale.metrics.MetricRegistry@2dc99e01
[junit4] 2> 127018 INFO (jetty-closer-4-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:54271_solr
[junit4] 2> 127019 INFO (zkCallback-24-thread-2-processing-n:127.0.0.1:36573_solr) [n:127.0.0.1:36573_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 127020 INFO (zkCallback-23-thread-3-processing-n:127.0.0.1:54271_solr) [n:127.0.0.1:54271_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 127021 INFO (zkCallback-1620-thread-1-processing-n:127.0.0.1:36573_solr x:collection1_shard1_replica_n1 s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:36573_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 127021 INFO (zkCallback-22-thread-2-processing-n:127.0.0.1:55443_solr) [n:127.0.0.1:55443_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 127021 INFO (jetty-closer-4-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 127024 INFO (coreCloseExecutor-234-thread-1) [n:127.0.0.1:36573_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1.shard1.leader, tag=13456165
[junit4] 2> 127032 INFO (jetty-closer-4-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3f7a6f64{/solr,null,UNAVAILABLE}
[junit4] 2> 127036 INFO (coreCloseExecutor-235-thread-1) [n:127.0.0.1:55443_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1.shard2.leader, tag=690313736
[junit4] 2> 127043 INFO (jetty-closer-4-thread-4) [ ] o.a.s.c.Overseer Overseer (id=98925431059316746-127.0.0.1:36573_solr-n_0000000000) closing
[junit4] 2> 127044 INFO (OverseerStateUpdate-98925431059316746-127.0.0.1:36573_solr-n_0000000000) [n:127.0.0.1:36573_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:36573_solr
[junit4] 2> 127047 WARN (OverseerAutoScalingTriggerThread-98925431059316746-127.0.0.1:36573_solr-n_0000000000) [n:127.0.0.1:36573_solr ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 127054 INFO (jetty-closer-4-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5d3dd2c4{/solr,null,UNAVAILABLE}
[junit4] 2> 128562 INFO (jetty-closer-4-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5bd15a6a{/solr,null,UNAVAILABLE}
[junit4] 2> 128565 ERROR (SUITE-StreamExpressionTest-seed#[5B7B0EE40B6887BC]-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> 128569 INFO (SUITE-StreamExpressionTest-seed#[5B7B0EE40B6887BC]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:62206 62206
[junit4] 2> 138689 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxnFactory Ignoring unexpected runtime exception
[junit4] 2> java.nio.channels.CancelledKeyException
[junit4] 2> at sun.nio.ch.SelectionKeyImpl.ensureValid(SelectionKeyImpl.java:73)
[junit4] 2> at sun.nio.ch.SelectionKeyImpl.readyOps(SelectionKeyImpl.java:87)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:182)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 138691 INFO (Thread-12) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:62206 62206
[junit4] 2> 138695 WARN (Thread-12) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 100 /solr/aliases.json
[junit4] 2> 100 /solr/clusterprops.json
[junit4] 2> 14 /solr/configs/conf
[junit4] 2> 7 /solr/configs/ml
[junit4] 2> 4 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 100 /solr/clusterstate.json
[junit4] 2> 38 /solr/collections/destinationCollection/state.json
[junit4] 2> 26 /solr/collections/parallelDestinationCollection1/state.json
[junit4] 2> 20 /solr/collections/parallelDestinationCollection/state.json
[junit4] 2> 10 /solr/collections/mainCorpus1/state.json
[junit4] 2> 10 /solr/collections/destination/state.json
[junit4] 2> 10 /solr/collections/destination1/state.json
[junit4] 2> 10 /solr/collections/collection1/state.json
[junit4] 2> 10 /solr/collections/workQueue1/state.json
[junit4] 2> 10 /solr/collections/workQueue/state.json
[junit4] 2> 10 /solr/collections/uknownCollection/state.json
[junit4] 2> 10 /solr/collections/modelCollection/state.json
[junit4] 2> 10 /solr/collections/checkpointCollection/state.json
[junit4] 2> 10 /solr/collections/mainCorpus/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 100 /solr/live_nodes
[junit4] 2> 100 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_5B7B0EE40B6887BC-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {expr_s=Lucene50(blocksize=128), name_s=BlockTreeOrds(blocksize=128), terms_ss=PostingsFormat(name=LuceneVarGapDocFreqInterval), join2_s=PostingsFormat(name=LuceneVarGapDocFreqInterval), field_s=Lucene50(blocksize=128), multiDefault=PostingsFormat(name=LuceneVarGapDocFreqInterval), subject=PostingsFormat(name=LuceneVarGapDocFreqInterval), a1_s=PostingsFormat(name=LuceneVarGapDocFreqInterval), a2_s=BlockTreeOrds(blocksize=128), level1_s=Lucene50(blocksize=128), body_t=BlockTreeOrds(blocksize=128), s_multi=BlockTreeOrds(blocksize=128), col_s=PostingsFormat(name=LuceneVarGapDocFreqInterval), a_ss=Lucene50(blocksize=128), level2_s=Lucene50(blocksize=128), a_s=BlockTreeOrds(blocksize=128), tv_text=Lucene50(blocksize=128), term_s=PostingsFormat(name=LuceneVarGapDocFreqInterval), a_t=Lucene50(blocksize=128), text_s=Lucene50(blocksize=128), id=Lucene50(blocksize=128), text=BlockTreeOrds(blocksize=128), featureSet_s=PostingsFormat(name=LuceneVarGapDocFreqInterval), checkpoint_ss=BlockTreeOrds(blocksize=128), test_t=BlockTreeOrds(blocksize=128), ident_s=PostingsFormat(name=LuceneVarGapDocFreqInterval), whitetok=BlockTreeOrds(blocksize=128), side_s=BlockTreeOrds(blocksize=128)}, docValues:{expr_s=DocValuesFormat(name=Direct), name_s=DocValuesFormat(name=Memory), iteration_i=DocValuesFormat(name=Direct), terms_ss=DocValuesFormat(name=Lucene70), join2_s=DocValuesFormat(name=Lucene70), field_s=DocValuesFormat(name=Lucene70), multiDefault=DocValuesFormat(name=Lucene70), price_f=DocValuesFormat(name=Lucene70), a1_s=DocValuesFormat(name=Lucene70), intDefault=DocValuesFormat(name=Lucene70), a_f=DocValuesFormat(name=Lucene70), a2_s=DocValuesFormat(name=Memory), join1_i=DocValuesFormat(name=Direct), falseNegative_i=DocValuesFormat(name=Lucene70), a_i=DocValuesFormat(name=Direct), test_i=DocValuesFormat(name=Lucene70), join3_i=DocValuesFormat(name=Memory), falsePositive_i=DocValuesFormat(name=Lucene70), field_i=DocValuesFormat(name=Lucene70), level1_s=DocValuesFormat(name=Direct), s_multi=DocValuesFormat(name=Memory), col_s=DocValuesFormat(name=Lucene70), a_ss=DocValuesFormat(name=Lucene70), level2_s=DocValuesFormat(name=Lucene70), a_s=DocValuesFormat(name=Memory), score_f=DocValuesFormat(name=Memory), term_s=DocValuesFormat(name=Lucene70), text_s=DocValuesFormat(name=Direct), id=DocValuesFormat(name=Direct), timestamp=DocValuesFormat(name=Lucene70), i_multi=DocValuesFormat(name=Direct), featureSet_s=DocValuesFormat(name=Lucene70), trueNegative_i=DocValuesFormat(name=Memory), index_i=DocValuesFormat(name=Lucene70), b_ls=DocValuesFormat(name=Lucene70), test_dt=DocValuesFormat(name=Direct), out_i=DocValuesFormat(name=Lucene70), checkpoint_ss=DocValuesFormat(name=Memory), ident_s=DocValuesFormat(name=Lucene70), order_i=DocValuesFormat(name=Lucene70), _version_=DocValuesFormat(name=Lucene70), side_s=DocValuesFormat(name=Memory), truePositive_i=DocValuesFormat(name=Direct), miles_i=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=1727, maxMBSortInHeap=7.640102589879356, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@573d22c5), locale=sr, timezone=Asia/Anadyr
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_152 (64-bit)/cpus=3,threads=1,free=81589704,total=192425984
[junit4] 2> NOTE: All tests run in this JVM: [JsonValidatorTest, SolrExceptionTest, SolrExampleJettyTest, GreaterThanEqualToEvaluatorTest, TestConfigSetAdminRequest, StreamExpressionTest]
[junit4] Completed [47/146 (1!)] on J1 in 127.62s, 125 tests, 1 failure <<< FAILURES!
[...truncated 46600 lines...]
[JENKINS] Lucene-Solr-master-Solaris (64bit/jdk1.8.0) - Build #
1502 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Solaris/1502/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
2 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation
Error Message:
2 threads leaked from SUITE scope at org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation: 1) Thread[id=26185, name=jetty-launcher-4215-thread-1-EventThread, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037) at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328) at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277) at org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323) at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105) at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288) at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279) at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41) at org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244) at org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44) at org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61) at org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67) at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:530) at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:505) 2) Thread[id=26189, name=jetty-launcher-4215-thread-2-EventThread, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037) at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328) at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277) at org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323) at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105) at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288) at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279) at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41) at org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244) at org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44) at org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61) at org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67) at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:530) at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:505)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from SUITE scope at org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation:
1) Thread[id=26185, name=jetty-launcher-4215-thread-1-EventThread, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037)
at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277)
at org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323)
at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105)
at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288)
at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279)
at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41)
at org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244)
at org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44)
at org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61)
at org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67)
at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:530)
at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:505)
2) Thread[id=26189, name=jetty-launcher-4215-thread-2-EventThread, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037)
at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277)
at org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323)
at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105)
at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288)
at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279)
at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41)
at org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244)
at org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44)
at org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61)
at org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67)
at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:530)
at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:505)
at __randomizedtesting.SeedInfo.seed([A40E31BF3AE97F14]:0)
FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI
Error Message:
Error from server at http://127.0.0.1:42522/solr/awhollynewcollection_0_shard2_replica_n2: ClusterState says we are the leader (http://127.0.0.1:42522/solr/awhollynewcollection_0_shard2_replica_n2), but locally we don't think so. Request came from null
Stack Trace:
org.apache.solr.client.solrj.impl.CloudSolrClient$RouteException: Error from server at http://127.0.0.1:42522/solr/awhollynewcollection_0_shard2_replica_n2: ClusterState says we are the leader (http://127.0.0.1:42522/solr/awhollynewcollection_0_shard2_replica_n2), but locally we don't think so. Request came from null
at __randomizedtesting.SeedInfo.seed([A40E31BF3AE97F14:EC7B450B3CDA5081]:0)
at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:541)
at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1008)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:875)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:937)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:937)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:937)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:937)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:937)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:808)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:178)
at org.apache.solr.client.solrj.request.UpdateRequest.commit(UpdateRequest.java:233)
at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI(CollectionsAPIDistributedZkTest.java:459)
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)
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:42522/solr/awhollynewcollection_0_shard2_replica_n2: ClusterState says we are the leader (http://127.0.0.1:42522/solr/awhollynewcollection_0_shard2_replica_n2), but locally we don't think so. Request came from null
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:658)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
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.lambda$directUpdate$0(CloudSolrClient.java:518)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
... 1 more
Build Log:
[...truncated 12681 lines...]
[junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
[junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A40E31BF3AE97F14-001/init-core-data-001
[junit4] 2> 1854465 WARN (SUITE-CollectionsAPIDistributedZkTest-seed#[A40E31BF3AE97F14]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=25 numCloses=25
[junit4] 2> 1854465 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[A40E31BF3AE97F14]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1854467 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[A40E31BF3AE97F14]-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> 1854467 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[A40E31BF3AE97F14]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1854467 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[A40E31BF3AE97F14]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A40E31BF3AE97F14-001/tempDir-001
[junit4] 2> 1854468 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[A40E31BF3AE97F14]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1854468 INFO (Thread-4693) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1854468 INFO (Thread-4693) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1854470 ERROR (Thread-4693) [ ] 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> 1854568 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[A40E31BF3AE97F14]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:65097
[junit4] 2> 1854575 INFO (jetty-launcher-2913-thread-1) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1854575 INFO (jetty-launcher-2913-thread-2) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1854575 INFO (jetty-launcher-2913-thread-3) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1854576 INFO (jetty-launcher-2913-thread-4) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1854579 INFO (jetty-launcher-2913-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@19f7121a{/solr,null,AVAILABLE}
[junit4] 2> 1854579 INFO (jetty-launcher-2913-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@16439443{/solr,null,AVAILABLE}
[junit4] 2> 1854579 INFO (jetty-launcher-2913-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@707d2a12{HTTP/1.1,[http/1.1]}{127.0.0.1:61293}
[junit4] 2> 1854579 INFO (jetty-launcher-2913-thread-3) [ ] o.e.j.s.Server Started @1856413ms
[junit4] 2> 1854579 INFO (jetty-launcher-2913-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=61293}
[junit4] 2> 1854579 INFO (jetty-launcher-2913-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6baa882a{HTTP/1.1,[http/1.1]}{127.0.0.1:59792}
[junit4] 2> 1854579 ERROR (jetty-launcher-2913-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1854579 INFO (jetty-launcher-2913-thread-1) [ ] o.e.j.s.Server Started @1856413ms
[junit4] 2> 1854579 INFO (jetty-launcher-2913-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@ced1c28{/solr,null,AVAILABLE}
[junit4] 2> 1854580 INFO (jetty-launcher-2913-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=59792}
[junit4] 2> 1854580 ERROR (jetty-launcher-2913-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1854580 INFO (jetty-launcher-2913-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@30e22d3c{/solr,null,AVAILABLE}
[junit4] 2> 1854580 INFO (jetty-launcher-2913-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 1854580 INFO (jetty-launcher-2913-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1854580 INFO (jetty-launcher-2913-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1854580 INFO (jetty-launcher-2913-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@635fe25b{HTTP/1.1,[http/1.1]}{127.0.0.1:42173}
[junit4] 2> 1854580 INFO (jetty-launcher-2913-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-11-01T05:37:23.553Z
[junit4] 2> 1854580 INFO (jetty-launcher-2913-thread-4) [ ] o.e.j.s.Server Started @1856414ms
[junit4] 2> 1854580 INFO (jetty-launcher-2913-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42173}
[junit4] 2> 1854580 ERROR (jetty-launcher-2913-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1854580 INFO (jetty-launcher-2913-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 1854580 INFO (jetty-launcher-2913-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1854580 INFO (jetty-launcher-2913-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1854580 INFO (jetty-launcher-2913-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-11-01T05:37:23.553Z
[junit4] 2> 1854580 INFO (jetty-launcher-2913-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 1854581 INFO (jetty-launcher-2913-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1854581 INFO (jetty-launcher-2913-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1854581 INFO (jetty-launcher-2913-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-11-01T05:37:23.554Z
[junit4] 2> 1854580 INFO (jetty-launcher-2913-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@34033258{HTTP/1.1,[http/1.1]}{127.0.0.1:42522}
[junit4] 2> 1854581 INFO (jetty-launcher-2913-thread-2) [ ] o.e.j.s.Server Started @1856415ms
[junit4] 2> 1854581 INFO (jetty-launcher-2913-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42522}
[junit4] 2> 1854582 ERROR (jetty-launcher-2913-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1854582 INFO (jetty-launcher-2913-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 1854582 INFO (jetty-launcher-2913-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1854582 INFO (jetty-launcher-2913-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1854582 INFO (jetty-launcher-2913-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-11-01T05:37:23.555Z
[junit4] 2> 1854584 INFO (jetty-launcher-2913-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1854584 INFO (jetty-launcher-2913-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1854585 INFO (jetty-launcher-2913-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1854585 INFO (jetty-launcher-2913-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1854588 INFO (jetty-launcher-2913-thread-4) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1854588 INFO (jetty-launcher-2913-thread-4) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1854588 INFO (jetty-launcher-2913-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1854589 INFO (jetty-launcher-2913-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1854588 INFO (jetty-launcher-2913-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1854589 INFO (jetty-launcher-2913-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1854591 INFO (jetty-launcher-2913-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1854591 INFO (jetty-launcher-2913-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1854592 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 0x15f761587f20002, 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> 1854595 INFO (jetty-launcher-2913-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:65097/solr
[junit4] 2> 1854597 INFO (jetty-launcher-2913-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:65097/solr
[junit4] 2> 1854597 INFO (jetty-launcher-2913-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:65097/solr
[junit4] 2> 1854600 INFO (jetty-launcher-2913-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:65097/solr
[junit4] 2> 1854781 INFO (jetty-launcher-2913-thread-2) [n:127.0.0.1:42522_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1854781 INFO (jetty-launcher-2913-thread-2) [n:127.0.0.1:42522_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:42522_solr
[junit4] 2> 1854783 INFO (jetty-launcher-2913-thread-2) [n:127.0.0.1:42522_solr ] o.a.s.c.Overseer Overseer (id=98927551672614923-127.0.0.1:42522_solr-n_0000000000) starting
[junit4] 2> 1854797 INFO (jetty-launcher-2913-thread-2) [n:127.0.0.1:42522_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42522_solr
[junit4] 2> 1854803 INFO (zkCallback-2932-thread-1-processing-n:127.0.0.1:59792_solr) [n:127.0.0.1:59792_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1854804 INFO (jetty-launcher-2913-thread-1) [n:127.0.0.1:59792_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1854804 INFO (OverseerStateUpdate-98927551672614923-127.0.0.1:42522_solr-n_0000000000) [n:127.0.0.1:42522_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1854808 INFO (jetty-launcher-2913-thread-4) [n:127.0.0.1:42173_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1854809 INFO (jetty-launcher-2913-thread-1) [n:127.0.0.1:59792_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59792_solr
[junit4] 2> 1854811 INFO (zkCallback-2934-thread-1-processing-n:127.0.0.1:42522_solr) [n:127.0.0.1:42522_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1854812 INFO (zkCallback-2932-thread-1-processing-n:127.0.0.1:59792_solr) [n:127.0.0.1:59792_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1854813 INFO (zkCallback-2933-thread-1-processing-n:127.0.0.1:42173_solr) [n:127.0.0.1:42173_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1854813 INFO (jetty-launcher-2913-thread-4) [n:127.0.0.1:42173_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1854816 INFO (jetty-launcher-2913-thread-4) [n:127.0.0.1:42173_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42173_solr
[junit4] 2> 1854819 INFO (zkCallback-2933-thread-1-processing-n:127.0.0.1:42173_solr) [n:127.0.0.1:42173_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1854819 INFO (zkCallback-2932-thread-1-processing-n:127.0.0.1:59792_solr) [n:127.0.0.1:59792_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1854820 INFO (zkCallback-2934-thread-1-processing-n:127.0.0.1:42522_solr) [n:127.0.0.1:42522_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1854877 INFO (jetty-launcher-2913-thread-1) [n:127.0.0.1:59792_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4ffccd9c
[junit4] 2> 1854885 INFO (jetty-launcher-2913-thread-1) [n:127.0.0.1:59792_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4ffccd9c
[junit4] 2> 1854885 INFO (jetty-launcher-2913-thread-1) [n:127.0.0.1:59792_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4ffccd9c
[junit4] 2> 1854887 INFO (jetty-launcher-2913-thread-1) [n:127.0.0.1:59792_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A40E31BF3AE97F14-001/tempDir-001/node1
[junit4] 2> 1854893 INFO (jetty-launcher-2913-thread-2) [n:127.0.0.1:42522_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4ffccd9c
[junit4] 2> 1854902 INFO (jetty-launcher-2913-thread-2) [n:127.0.0.1:42522_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4ffccd9c
[junit4] 2> 1854903 INFO (jetty-launcher-2913-thread-2) [n:127.0.0.1:42522_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4ffccd9c
[junit4] 2> 1854904 INFO (jetty-launcher-2913-thread-2) [n:127.0.0.1:42522_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A40E31BF3AE97F14-001/tempDir-001/node2
[junit4] 2> 1854929 INFO (jetty-launcher-2913-thread-4) [n:127.0.0.1:42173_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4ffccd9c
[junit4] 2> 1854934 WARN (jetty-launcher-2913-thread-2) [n:127.0.0.1:42522_solr ] o.a.s.m.r.j.JmxMetricsReporter Unable to register gauge
[junit4] 2> javax.management.InstanceNotFoundException: solr:dom1=jvm,name0=memory,name1=pools,name2=Par-Survivor-Space,name=usage
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1095)
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:427)
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit4] 2> at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:546)
[junit4] 2> at org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter$JmxListener.registerMBean(JmxMetricsReporter.java:530)
[junit4] 2> at org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter$JmxListener.onGaugeAdded(JmxMetricsReporter.java:565)
[junit4] 2> at com.codahale.metrics.MetricRegistry.notifyListenerOfAddedMetric(MetricRegistry.java:454)
[junit4] 2> at com.codahale.metrics.MetricRegistry.onMetricAdded(MetricRegistry.java:448)
[junit4] 2> at com.codahale.metrics.MetricRegistry.register(MetricRegistry.java:89)
[junit4] 2> at org.apache.solr.metrics.SolrMetricManager.registerAll(SolrMetricManager.java:525)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.setupJvmMetrics(SolrDispatchFilter.java:214)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.init(SolrDispatchFilter.java:186)
[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> 1854936 INFO (jetty-launcher-2913-thread-4) [n:127.0.0.1:42173_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4ffccd9c
[junit4] 2> 1854937 INFO (jetty-launcher-2913-thread-4) [n:127.0.0.1:42173_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4ffccd9c
[junit4] 2> 1854938 INFO (jetty-launcher-2913-thread-4) [n:127.0.0.1:42173_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A40E31BF3AE97F14-001/tempDir-001/node4
[junit4] 2> 1854941 INFO (jetty-launcher-2913-thread-3) [n:127.0.0.1:61293_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1854943 INFO (jetty-launcher-2913-thread-3) [n:127.0.0.1:61293_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1854945 INFO (jetty-launcher-2913-thread-3) [n:127.0.0.1:61293_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61293_solr
[junit4] 2> 1854947 INFO (zkCallback-2933-thread-1-processing-n:127.0.0.1:42173_solr) [n:127.0.0.1:42173_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1854947 INFO (zkCallback-2932-thread-1-processing-n:127.0.0.1:59792_solr) [n:127.0.0.1:59792_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1854964 INFO (zkCallback-2934-thread-1-processing-n:127.0.0.1:42522_solr) [n:127.0.0.1:42522_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1854966 INFO (zkCallback-2935-thread-1-processing-n:127.0.0.1:61293_solr) [n:127.0.0.1:61293_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1855118 INFO (jetty-launcher-2913-thread-3) [n:127.0.0.1:61293_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4ffccd9c
[junit4] 2> 1855123 INFO (jetty-launcher-2913-thread-3) [n:127.0.0.1:61293_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4ffccd9c
[junit4] 2> 1855124 INFO (jetty-launcher-2913-thread-3) [n:127.0.0.1:61293_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4ffccd9c
[junit4] 2> 1855124 INFO (jetty-launcher-2913-thread-3) [n:127.0.0.1:61293_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A40E31BF3AE97F14-001/tempDir-001/node3
[junit4] 2> 1855150 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 0x15f761587f2000d, 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> 1855153 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[A40E31BF3AE97F14]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1855155 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[A40E31BF3AE97F14]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:65097/solr ready
[junit4] 2> 1855182 INFO (TEST-CollectionsAPIDistributedZkTest.testCollectionsAPI-seed#[A40E31BF3AE97F14]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCollectionsAPI
[junit4] 2> 1855184 INFO (TEST-CollectionsAPIDistributedZkTest.testCollectionsAPI-seed#[A40E31BF3AE97F14]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1855187 INFO (qtp820274126-17667) [n:127.0.0.1:61293_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params async=fb041d48-216d-41c9-9f39-799afedccb1f&replicationFactor=1&collection.configName=conf2&maxShardsPerNode=2&name=awhollynewcollection_0&nrtReplicas=1&action=CREATE&numShards=5&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1855189 INFO (qtp820274126-17667) [n:127.0.0.1:61293_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=fb041d48-216d-41c9-9f39-799afedccb1f&replicationFactor=1&collection.configName=conf2&maxShardsPerNode=2&name=awhollynewcollection_0&nrtReplicas=1&action=CREATE&numShards=5&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1855192 INFO (OverseerThreadFactory-7116-thread-1-processing-n:127.0.0.1:42522_solr) [n:127.0.0.1:42522_solr ] o.a.s.c.CreateCollectionCmd Create collection awhollynewcollection_0
[junit4] 2> 1855200 INFO (zkCallback-2944-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeCreated path:/collections/awhollynewcollection_0/state.json] for collection [awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1855304 INFO (OverseerStateUpdate-98927551672614923-127.0.0.1:42522_solr-n_0000000000) [n:127.0.0.1:42522_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"awhollynewcollection_0",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"awhollynewcollection_0_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:59792/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1855307 INFO (OverseerStateUpdate-98927551672614923-127.0.0.1:42522_solr-n_0000000000) [n:127.0.0.1:42522_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"awhollynewcollection_0",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"awhollynewcollection_0_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:42522/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1855310 INFO (OverseerStateUpdate-98927551672614923-127.0.0.1:42522_solr-n_0000000000) [n:127.0.0.1:42522_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"awhollynewcollection_0",
[junit4] 2> "shard":"shard3",
[junit4] 2> "core":"awhollynewcollection_0_shard3_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:61293/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1855312 INFO (OverseerStateUpdate-98927551672614923-127.0.0.1:42522_solr-n_0000000000) [n:127.0.0.1:42522_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"awhollynewcollection_0",
[junit4] 2> "shard":"shard4",
[junit4] 2> "core":"awhollynewcollection_0_shard4_replica_n6",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:42173/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1855315 INFO (OverseerStateUpdate-98927551672614923-127.0.0.1:42522_solr-n_0000000000) [n:127.0.0.1:42522_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"awhollynewcollection_0",
[junit4] 2> "shard":"shard5",
[junit4] 2> "core":"awhollynewcollection_0_shard5_replica_n8",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:59792/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1855417 INFO (zkCallback-2944-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/awhollynewcollection_0/state.json] for collection [awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1855525 INFO (qtp417198643-17690) [n:127.0.0.1:42522_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf2&newCollection=true&collection=awhollynewcollection_0&version=2&replicaType=NRT&async=fb041d48-216d-41c9-9f39-799afedccb1f844344460217368&coreNodeName=core_node5&name=awhollynewcollection_0_shard2_replica_n2&action=CREATE&numShards=5&shard=shard2&wt=javabin} status=0 QTime=0
[junit4] 2> 1855526 INFO (qtp805569476-17663) [n:127.0.0.1:59792_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf2&newCollection=true&collection=awhollynewcollection_0&version=2&replicaType=NRT&async=fb041d48-216d-41c9-9f39-799afedccb1f844344457431382&coreNodeName=core_node3&name=awhollynewcollection_0_shard1_replica_n1&action=CREATE&numShards=5&shard=shard1&wt=javabin} status=0 QTime=0
[junit4] 2> 1855526 INFO (parallelCoreAdminExecutor-7118-thread-1-processing-n:127.0.0.1:42522_solr fb041d48-216d-41c9-9f39-799afedccb1f844344460217368 CREATE) [n:127.0.0.1:42522_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf2&newCollection=true&collection=awhollynewcollection_0&version=2&replicaType=NRT&async=fb041d48-216d-41c9-9f39-799afedccb1f844344460217368&coreNodeName=core_node5&name=awhollynewcollection_0_shard2_replica_n2&action=CREATE&numShards=5&shard=shard2&wt=javabin
[junit4] 2> 1855529 INFO (parallelCoreAdminExecutor-7117-thread-1-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344457431382 CREATE) [n:127.0.0.1:59792_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf2&newCollection=true&collection=awhollynewcollection_0&version=2&replicaType=NRT&async=fb041d48-216d-41c9-9f39-799afedccb1f844344457431382&coreNodeName=core_node3&name=awhollynewcollection_0_shard1_replica_n1&action=CREATE&numShards=5&shard=shard1&wt=javabin
[junit4] 2> 1855529 INFO (parallelCoreAdminExecutor-7118-thread-1-processing-n:127.0.0.1:42522_solr fb041d48-216d-41c9-9f39-799afedccb1f844344460217368 CREATE) [n:127.0.0.1:42522_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1855529 INFO (qtp820274126-17680) [n:127.0.0.1:61293_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf2&newCollection=true&collection=awhollynewcollection_0&version=2&replicaType=NRT&async=fb041d48-216d-41c9-9f39-799afedccb1f844344463297441&coreNodeName=core_node7&name=awhollynewcollection_0_shard3_replica_n4&action=CREATE&numShards=5&shard=shard3&wt=javabin} status=0 QTime=0
[junit4] 2> 1855530 INFO (parallelCoreAdminExecutor-7117-thread-1-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344457431382 CREATE) [n:127.0.0.1:59792_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1855531 INFO (parallelCoreAdminExecutor-7123-thread-1-processing-n:127.0.0.1:61293_solr fb041d48-216d-41c9-9f39-799afedccb1f844344463297441 CREATE) [n:127.0.0.1:61293_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf2&newCollection=true&collection=awhollynewcollection_0&version=2&replicaType=NRT&async=fb041d48-216d-41c9-9f39-799afedccb1f844344463297441&coreNodeName=core_node7&name=awhollynewcollection_0_shard3_replica_n4&action=CREATE&numShards=5&shard=shard3&wt=javabin
[junit4] 2> 1855533 INFO (parallelCoreAdminExecutor-7123-thread-1-processing-n:127.0.0.1:61293_solr fb041d48-216d-41c9-9f39-799afedccb1f844344463297441 CREATE) [n:127.0.0.1:61293_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1855533 INFO (qtp805569476-17665) [n:127.0.0.1:59792_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf2&newCollection=true&collection=awhollynewcollection_0&version=2&replicaType=NRT&async=fb041d48-216d-41c9-9f39-799afedccb1f844344468697888&coreNodeName=core_node10&name=awhollynewcollection_0_shard5_replica_n8&action=CREATE&numShards=5&shard=shard5&wt=javabin} status=0 QTime=7
[junit4] 2> 1855534 INFO (qtp890055569-17673) [n:127.0.0.1:42173_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf2&newCollection=true&collection=awhollynewcollection_0&version=2&replicaType=NRT&async=fb041d48-216d-41c9-9f39-799afedccb1f844344465948674&coreNodeName=core_node9&name=awhollynewcollection_0_shard4_replica_n6&action=CREATE&numShards=5&shard=shard4&wt=javabin} status=0 QTime=1
[junit4] 2> 1855535 INFO (parallelCoreAdminExecutor-7121-thread-1-processing-n:127.0.0.1:42173_solr fb041d48-216d-41c9-9f39-799afedccb1f844344465948674 CREATE) [n:127.0.0.1:42173_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf2&newCollection=true&collection=awhollynewcollection_0&version=2&replicaType=NRT&async=fb041d48-216d-41c9-9f39-799afedccb1f844344465948674&coreNodeName=core_node9&name=awhollynewcollection_0_shard4_replica_n6&action=CREATE&numShards=5&shard=shard4&wt=javabin
[junit4] 2> 1855535 INFO (parallelCoreAdminExecutor-7117-thread-2-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344468697888 CREATE) [n:127.0.0.1:59792_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf2&newCollection=true&collection=awhollynewcollection_0&version=2&replicaType=NRT&async=fb041d48-216d-41c9-9f39-799afedccb1f844344468697888&coreNodeName=core_node10&name=awhollynewcollection_0_shard5_replica_n8&action=CREATE&numShards=5&shard=shard5&wt=javabin
[junit4] 2> 1855536 INFO (parallelCoreAdminExecutor-7121-thread-1-processing-n:127.0.0.1:42173_solr fb041d48-216d-41c9-9f39-799afedccb1f844344465948674 CREATE) [n:127.0.0.1:42173_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1855538 INFO (qtp890055569-17675) [n:127.0.0.1:42173_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : fb041d48-216d-41c9-9f39-799afedccb1f844344465948674
[junit4] 2> 1855541 INFO (qtp890055569-17675) [n:127.0.0.1:42173_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=fb041d48-216d-41c9-9f39-799afedccb1f844344465948674&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 1855661 INFO (zkCallback-2932-thread-1-processing-n:127.0.0.1:59792_solr) [n:127.0.0.1:59792_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/awhollynewcollection_0/state.json] for collection [awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1855661 INFO (zkCallback-2934-thread-1-processing-n:127.0.0.1:42522_solr) [n:127.0.0.1:42522_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/awhollynewcollection_0/state.json] for collection [awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1855662 INFO (zkCallback-2944-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/awhollynewcollection_0/state.json] for collection [awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1855662 INFO (zkCallback-2933-thread-1-processing-n:127.0.0.1:42173_solr) [n:127.0.0.1:42173_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/awhollynewcollection_0/state.json] for collection [awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1855662 INFO (zkCallback-2932-thread-2-processing-n:127.0.0.1:59792_solr) [n:127.0.0.1:59792_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/awhollynewcollection_0/state.json] for collection [awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1855662 INFO (zkCallback-2934-thread-2-processing-n:127.0.0.1:42522_solr) [n:127.0.0.1:42522_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/awhollynewcollection_0/state.json] for collection [awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1855663 INFO (zkCallback-2933-thread-2-processing-n:127.0.0.1:42173_solr) [n:127.0.0.1:42173_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/awhollynewcollection_0/state.json] for collection [awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1855663 INFO (zkCallback-2935-thread-1-processing-n:127.0.0.1:61293_solr) [n:127.0.0.1:61293_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/awhollynewcollection_0/state.json] for collection [awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1855663 INFO (zkCallback-2935-thread-2-processing-n:127.0.0.1:61293_solr) [n:127.0.0.1:61293_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/awhollynewcollection_0/state.json] for collection [awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1856545 INFO (qtp890055569-17674) [n:127.0.0.1:42173_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : fb041d48-216d-41c9-9f39-799afedccb1f844344465948674
[junit4] 2> 1856545 INFO (qtp890055569-17674) [n:127.0.0.1:42173_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=fb041d48-216d-41c9-9f39-799afedccb1f844344465948674&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1856561 INFO (parallelCoreAdminExecutor-7117-thread-1-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344457431382 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1856565 INFO (parallelCoreAdminExecutor-7123-thread-1-processing-n:127.0.0.1:61293_solr fb041d48-216d-41c9-9f39-799afedccb1f844344463297441 CREATE) [n:127.0.0.1:61293_solr c:awhollynewcollection_0 s:shard3 r:core_node7 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1856580 INFO (parallelCoreAdminExecutor-7121-thread-1-processing-n:127.0.0.1:42173_solr fb041d48-216d-41c9-9f39-799afedccb1f844344465948674 CREATE) [n:127.0.0.1:42173_solr c:awhollynewcollection_0 s:shard4 r:core_node9 x:awhollynewcollection_0_shard4_replica_n6] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1856582 INFO (parallelCoreAdminExecutor-7123-thread-1-processing-n:127.0.0.1:61293_solr fb041d48-216d-41c9-9f39-799afedccb1f844344463297441 CREATE) [n:127.0.0.1:61293_solr c:awhollynewcollection_0 s:shard3 r:core_node7 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.s.IndexSchema [awhollynewcollection_0_shard3_replica_n4] Schema name=minimal
[junit4] 2> 1856585 INFO (parallelCoreAdminExecutor-7123-thread-1-processing-n:127.0.0.1:61293_solr fb041d48-216d-41c9-9f39-799afedccb1f844344463297441 CREATE) [n:127.0.0.1:61293_solr c:awhollynewcollection_0 s:shard3 r:core_node7 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1856585 INFO (parallelCoreAdminExecutor-7123-thread-1-processing-n:127.0.0.1:61293_solr fb041d48-216d-41c9-9f39-799afedccb1f844344463297441 CREATE) [n:127.0.0.1:61293_solr c:awhollynewcollection_0 s:shard3 r:core_node7 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.c.CoreContainer Creating SolrCore 'awhollynewcollection_0_shard3_replica_n4' using configuration from collection awhollynewcollection_0, trusted=true
[junit4] 2> 1856585 INFO (parallelCoreAdminExecutor-7123-thread-1-processing-n:127.0.0.1:61293_solr fb041d48-216d-41c9-9f39-799afedccb1f844344463297441 CREATE) [n:127.0.0.1:61293_solr c:awhollynewcollection_0 s:shard3 r:core_node7 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.awhollynewcollection_0.shard3.replica_n4' (registry 'solr.core.awhollynewcollection_0.shard3.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4ffccd9c
[junit4] 2> 1856585 INFO (parallelCoreAdminExecutor-7123-thread-1-processing-n:127.0.0.1:61293_solr fb041d48-216d-41c9-9f39-799afedccb1f844344463297441 CREATE) [n:127.0.0.1:61293_solr c:awhollynewcollection_0 s:shard3 r:core_node7 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1856585 INFO (parallelCoreAdminExecutor-7123-thread-1-processing-n:127.0.0.1:61293_solr fb041d48-216d-41c9-9f39-799afedccb1f844344463297441 CREATE) [n:127.0.0.1:61293_solr c:awhollynewcollection_0 s:shard3 r:core_node7 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.c.SolrCore [[awhollynewcollection_0_shard3_replica_n4] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A40E31BF3AE97F14-001/tempDir-001/node3/awhollynewcollection_0_shard3_replica_n4], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A40E31BF3AE97F14-001/tempDir-001/node3/awhollynewcollection_0_shard3_replica_n4/data/]
[junit4] 2> 1856587 INFO (parallelCoreAdminExecutor-7117-thread-1-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344457431382 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.s.IndexSchema [awhollynewcollection_0_shard1_replica_n1] Schema name=minimal
[junit4] 2> 1856589 INFO (parallelCoreAdminExecutor-7117-thread-1-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344457431382 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1856589 INFO (parallelCoreAdminExecutor-7117-thread-1-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344457431382 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'awhollynewcollection_0_shard1_replica_n1' using configuration from collection awhollynewcollection_0, trusted=true
[junit4] 2> 1856589 INFO (parallelCoreAdminExecutor-7117-thread-2-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344468697888 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard5 r:core_node10 x:awhollynewcollection_0_shard5_replica_n8] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1856589 INFO (parallelCoreAdminExecutor-7117-thread-1-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344457431382 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.awhollynewcollection_0.shard1.replica_n1' (registry 'solr.core.awhollynewcollection_0.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4ffccd9c
[junit4] 2> 1856589 INFO (parallelCoreAdminExecutor-7117-thread-1-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344457431382 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1856589 INFO (parallelCoreAdminExecutor-7117-thread-1-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344457431382 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.c.SolrCore [[awhollynewcollection_0_shard1_replica_n1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A40E31BF3AE97F14-001/tempDir-001/node1/awhollynewcollection_0_shard1_replica_n1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A40E31BF3AE97F14-001/tempDir-001/node1/awhollynewcollection_0_shard1_replica_n1/data/]
[junit4] 2> 1856592 INFO (parallelCoreAdminExecutor-7121-thread-1-processing-n:127.0.0.1:42173_solr fb041d48-216d-41c9-9f39-799afedccb1f844344465948674 CREATE) [n:127.0.0.1:42173_solr c:awhollynewcollection_0 s:shard4 r:core_node9 x:awhollynewcollection_0_shard4_replica_n6] o.a.s.s.IndexSchema [awhollynewcollection_0_shard4_replica_n6] Schema name=minimal
[junit4] 2> 1856594 INFO (parallelCoreAdminExecutor-7121-thread-1-processing-n:127.0.0.1:42173_solr fb041d48-216d-41c9-9f39-799afedccb1f844344465948674 CREATE) [n:127.0.0.1:42173_solr c:awhollynewcollection_0 s:shard4 r:core_node9 x:awhollynewcollection_0_shard4_replica_n6] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1856594 INFO (parallelCoreAdminExecutor-7121-thread-1-processing-n:127.0.0.1:42173_solr fb041d48-216d-41c9-9f39-799afedccb1f844344465948674 CREATE) [n:127.0.0.1:42173_solr c:awhollynewcollection_0 s:shard4 r:core_node9 x:awhollynewcollection_0_shard4_replica_n6] o.a.s.c.CoreContainer Creating SolrCore 'awhollynewcollection_0_shard4_replica_n6' using configuration from collection awhollynewcollection_0, trusted=true
[junit4] 2> 1856594 INFO (parallelCoreAdminExecutor-7121-thread-1-processing-n:127.0.0.1:42173_solr fb041d48-216d-41c9-9f39-799afedccb1f844344465948674 CREATE) [n:127.0.0.1:42173_solr c:awhollynewcollection_0 s:shard4 r:core_node9 x:awhollynewcollection_0_shard4_replica_n6] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.awhollynewcollection_0.shard4.replica_n6' (registry 'solr.core.awhollynewcollection_0.shard4.replica_n6') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4ffccd9c
[junit4] 2> 1856595 INFO (parallelCoreAdminExecutor-7121-thread-1-processing-n:127.0.0.1:42173_solr fb041d48-216d-41c9-9f39-799afedccb1f844344465948674 CREATE) [n:127.0.0.1:42173_solr c:awhollynewcollection_0 s:shard4 r:core_node9 x:awhollynewcollection_0_shard4_replica_n6] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1856595 INFO (parallelCoreAdminExecutor-7121-thread-1-processing-n:127.0.0.1:42173_solr fb041d48-216d-41c9-9f39-799afedccb1f844344465948674 CREATE) [n:127.0.0.1:42173_solr c:awhollynewcollection_0 s:shard4 r:core_node9 x:awhollynewcollection_0_shard4_replica_n6] o.a.s.c.SolrCore [[awhollynewcollection_0_shard4_replica_n6] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A40E31BF3AE97F14-001/tempDir-001/node4/awhollynewcollection_0_shard4_replica_n6], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A40E31BF3AE97F14-001/tempDir-001/node4/awhollynewcollection_0_shard4_replica_n6/data/]
[junit4] 2> 1856611 INFO (parallelCoreAdminExecutor-7118-thread-1-processing-n:127.0.0.1:42522_solr fb041d48-216d-41c9-9f39-799afedccb1f844344460217368 CREATE) [n:127.0.0.1:42522_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1856618 INFO (parallelCoreAdminExecutor-7117-thread-2-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344468697888 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard5 r:core_node10 x:awhollynewcollection_0_shard5_replica_n8] o.a.s.s.IndexSchema [awhollynewcollection_0_shard5_replica_n8] Schema name=minimal
[junit4] 2> 1856619 INFO (parallelCoreAdminExecutor-7117-thread-2-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344468697888 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard5 r:core_node10 x:awhollynewcollection_0_shard5_replica_n8] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1856619 INFO (parallelCoreAdminExecutor-7117-thread-2-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344468697888 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard5 r:core_node10 x:awhollynewcollection_0_shard5_replica_n8] o.a.s.c.CoreContainer Creating SolrCore 'awhollynewcollection_0_shard5_replica_n8' using configuration from collection awhollynewcollection_0, trusted=true
[junit4] 2> 1856619 INFO (parallelCoreAdminExecutor-7117-thread-2-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344468697888 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard5 r:core_node10 x:awhollynewcollection_0_shard5_replica_n8] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.awhollynewcollection_0.shard5.replica_n8' (registry 'solr.core.awhollynewcollection_0.shard5.replica_n8') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4ffccd9c
[junit4] 2> 1856620 INFO (parallelCoreAdminExecutor-7117-thread-2-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344468697888 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard5 r:core_node10 x:awhollynewcollection_0_shard5_replica_n8] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1856620 INFO (parallelCoreAdminExecutor-7117-thread-2-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344468697888 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard5 r:core_node10 x:awhollynewcollection_0_shard5_replica_n8] o.a.s.c.SolrCore [[awhollynewcollection_0_shard5_replica_n8] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A40E31BF3AE97F14-001/tempDir-001/node1/awhollynewcollection_0_shard5_replica_n8], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A40E31BF3AE97F14-001/tempDir-001/node1/awhollynewcollection_0_shard5_replica_n8/data/]
[junit4] 2> 1856626 INFO (parallelCoreAdminExecutor-7118-thread-1-processing-n:127.0.0.1:42522_solr fb041d48-216d-41c9-9f39-799afedccb1f844344460217368 CREATE) [n:127.0.0.1:42522_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.s.IndexSchema [awhollynewcollection_0_shard2_replica_n2] Schema name=minimal
[junit4] 2> 1856628 INFO (parallelCoreAdminExecutor-7118-thread-1-processing-n:127.0.0.1:42522_solr fb041d48-216d-41c9-9f39-799afedccb1f844344460217368 CREATE) [n:127.0.0.1:42522_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1856629 INFO (parallelCoreAdminExecutor-7118-thread-1-processing-n:127.0.0.1:42522_solr fb041d48-216d-41c9-9f39-799afedccb1f844344460217368 CREATE) [n:127.0.0.1:42522_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'awhollynewcollection_0_shard2_replica_n2' using configuration from collection awhollynewcollection_0, trusted=true
[junit4] 2> 1856629 INFO (parallelCoreAdminExecutor-7118-thread-1-processing-n:127.0.0.1:42522_solr fb041d48-216d-41c9-9f39-799afedccb1f844344460217368 CREATE) [n:127.0.0.1:42522_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.awhollynewcollection_0.shard2.replica_n2' (registry 'solr.core.awhollynewcollection_0.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4ffccd9c
[junit4] 2> 1856629 INFO (parallelCoreAdminExecutor-7118-thread-1-processing-n:127.0.0.1:42522_solr fb041d48-216d-41c9-9f39-799afedccb1f844344460217368 CREATE) [n:127.0.0.1:42522_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1856629 INFO (parallelCoreAdminExecutor-7118-thread-1-processing-n:127.0.0.1:42522_solr fb041d48-216d-41c9-9f39-799afedccb1f844344460217368 CREATE) [n:127.0.0.1:42522_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.c.SolrCore [[awhollynewcollection_0_shard2_replica_n2] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A40E31BF3AE97F14-001/tempDir-001/node2/awhollynewcollection_0_shard2_replica_n2], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A40E31BF3AE97F14-001/tempDir-001/node2/awhollynewcollection_0_shard2_replica_n2/data/]
[junit4] 2> 1856687 INFO (parallelCoreAdminExecutor-7121-thread-1-processing-n:127.0.0.1:42173_solr fb041d48-216d-41c9-9f39-799afedccb1f844344465948674 CREATE) [n:127.0.0.1:42173_solr c:awhollynewcollection_0 s:shard4 r:core_node9 x:awhollynewcollection_0_shard4_replica_n6] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1856687 INFO (parallelCoreAdminExecutor-7121-thread-1-processing-n:127.0.0.1:42173_solr fb041d48-216d-41c9-9f39-799afedccb1f844344465948674 CREATE) [n:127.0.0.1:42173_solr c:awhollynewcollection_0 s:shard4 r:core_node9 x:awhollynewcollection_0_shard4_replica_n6] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1856688 INFO (parallelCoreAdminExecutor-7121-thread-1-processing-n:127.0.0.1:42173_solr fb041d48-216d-41c9-9f39-799afedccb1f844344465948674 CREATE) [n:127.0.0.1:42173_solr c:awhollynewcollection_0 s:shard4 r:core_node9 x:awhollynewcollection_0_shard4_replica_n6] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1856688 INFO (parallelCoreAdminExecutor-7121-thread-1-processing-n:127.0.0.1:42173_solr fb041d48-216d-41c9-9f39-799afedccb1f844344465948674 CREATE) [n:127.0.0.1:42173_solr c:awhollynewcollection_0 s:shard4 r:core_node9 x:awhollynewcollection_0_shard4_replica_n6] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1856689 INFO (parallelCoreAdminExecutor-7121-thread-1-processing-n:127.0.0.1:42173_solr fb041d48-216d-41c9-9f39-799afedccb1f844344465948674 CREATE) [n:127.0.0.1:42173_solr c:awhollynewcollection_0 s:shard4 r:core_node9 x:awhollynewcollection_0_shard4_replica_n6] o.a.s.s.SolrIndexSearcher Opening [Searcher@6dc10144[awhollynewcollection_0_shard4_replica_n6] main]
[junit4] 2> 1856690 INFO (parallelCoreAdminExecutor-7121-thread-1-processing-n:127.0.0.1:42173_solr fb041d48-216d-41c9-9f39-799afedccb1f844344465948674 CREATE) [n:127.0.0.1:42173_solr c:awhollynewcollection_0 s:shard4 r:core_node9 x:awhollynewcollection_0_shard4_replica_n6] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf2
[junit4] 2> 1856690 INFO (parallelCoreAdminExecutor-7121-thread-1-processing-n:127.0.0.1:42173_solr fb041d48-216d-41c9-9f39-799afedccb1f844344465948674 CREATE) [n:127.0.0.1:42173_solr c:awhollynewcollection_0 s:shard4 r:core_node9 x:awhollynewcollection_0_shard4_replica_n6] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf2
[junit4] 2> 1856691 INFO (parallelCoreAdminExecutor-7121-thread-1-processing-n:127.0.0.1:42173_solr fb041d48-216d-41c9-9f39-799afedccb1f844344465948674 CREATE) [n:127.0.0.1:42173_solr c:awhollynewcollection_0 s:shard4 r:core_node9 x:awhollynewcollection_0_shard4_replica_n6] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1856691 INFO (parallelCoreAdminExecutor-7121-thread-1-processing-n:127.0.0.1:42173_solr fb041d48-216d-41c9-9f39-799afedccb1f844344465948674 CREATE) [n:127.0.0.1:42173_solr c:awhollynewcollection_0 s:shard4 r:core_node9 x:awhollynewcollection_0_shard4_replica_n6] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1582840829091774464
[junit4] 2> 1856691 INFO (parallelCoreAdminExecutor-7117-thread-1-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344457431382 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1856691 INFO (parallelCoreAdminExecutor-7117-thread-1-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344457431382 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1856692 INFO (parallelCoreAdminExecutor-7117-thread-1-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344457431382 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1856692 INFO (parallelCoreAdminExecutor-7117-thread-1-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344457431382 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1856693 INFO (parallelCoreAdminExecutor-7117-thread-1-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344457431382 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4057429[awhollynewcollection_0_shard1_replica_n1] main]
[junit4] 2> 1856695 INFO (parallelCoreAdminExecutor-7117-thread-1-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344457431382 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf2
[junit4] 2> 1856697 INFO (parallelCoreAdminExecutor-7121-thread-1-processing-n:127.0.0.1:42173_solr fb041d48-216d-41c9-9f39-799afedccb1f844344465948674 CREATE) [n:127.0.0.1:42173_solr c:awhollynewcollection_0 s:shard4 r:core_node9 x:awhollynewcollection_0_shard4_replica_n6] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1856697 INFO (parallelCoreAdminExecutor-7121-thread-1-processing-n:127.0.0.1:42173_solr fb041d48-216d-41c9-9f39-799afedccb1f844344465948674 CREATE) [n:127.0.0.1:42173_solr c:awhollynewcollection_0 s:shard4 r:core_node9 x:awhollynewcollection_0_shard4_replica_n6] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1856697 INFO (parallelCoreAdminExecutor-7121-thread-1-processing-n:127.0.0.1:42173_solr fb041d48-216d-41c9-9f39-799afedccb1f844344465948674 CREATE) [n:127.0.0.1:42173_solr c:awhollynewcollection_0 s:shard4 r:core_node9 x:awhollynewcollection_0_shard4_replica_n6] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:42173/solr/awhollynewcollection_0_shard4_replica_n6/
[junit4] 2> 1856697 INFO (parallelCoreAdminExecutor-7121-thread-1-processing-n:127.0.0.1:42173_solr fb041d48-216d-41c9-9f39-799afedccb1f844344465948674 CREATE) [n:127.0.0.1:42173_solr c:awhollynewcollection_0 s:shard4 r:core_node9 x:awhollynewcollection_0_shard4_replica_n6] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1856697 INFO (parallelCoreAdminExecutor-7121-thread-1-processing-n:127.0.0.1:42173_solr fb041d48-216d-41c9-9f39-799afedccb1f844344465948674 CREATE) [n:127.0.0.1:42173_solr c:awhollynewcollection_0 s:shard4 r:core_node9 x:awhollynewcollection_0_shard4_replica_n6] o.a.s.c.SyncStrategy http://127.0.0.1:42173/solr/awhollynewcollection_0_shard4_replica_n6/ has no replicas
[junit4] 2> 1856697 INFO (parallelCoreAdminExecutor-7121-thread-1-processing-n:127.0.0.1:42173_solr fb041d48-216d-41c9-9f39-799afedccb1f844344465948674 CREATE) [n:127.0.0.1:42173_solr c:awhollynewcollection_0 s:shard4 r:core_node9 x:awhollynewcollection_0_shard4_replica_n6] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 1856702 INFO (parallelCoreAdminExecutor-7121-thread-1-processing-n:127.0.0.1:42173_solr fb041d48-216d-41c9-9f39-799afedccb1f844344465948674 CREATE) [n:127.0.0.1:42173_solr c:awhollynewcollection_0 s:shard4 r:core_node9 x:awhollynewcollection_0_shard4_replica_n6] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:42173/solr/awhollynewcollection_0_shard4_replica_n6/ shard4
[junit4] 2> 1856703 INFO (searcherExecutor-7127-thread-1-processing-x:awhollynewcollection_0_shard4_replica_n6 r:core_node9 fb041d48-216d-41c9-9f39-799afedccb1f844344465948674 CREATE n:127.0.0.1:42173_solr s:shard4 c:awhollynewcollection_0) [n:127.0.0.1:42173_solr c:awhollynewcollection_0 s:shard4 r:core_node9 x:awhollynewcollection_0_shard4_replica_n6] o.a.s.c.SolrCore [awhollynewcollection_0_shard4_replica_n6] Registered new searcher Searcher@6dc10144[awhollynewcollection_0_shard4_replica_n6] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1856704 INFO (parallelCoreAdminExecutor-7117-thread-1-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344457431382 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf2
[junit4] 2> 1856729 INFO (parallelCoreAdminExecutor-7117-thread-2-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344468697888 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard5 r:core_node10 x:awhollynewcollection_0_shard5_replica_n8] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1856729 INFO (parallelCoreAdminExecutor-7117-thread-2-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344468697888 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard5 r:core_node10 x:awhollynewcollection_0_shard5_replica_n8] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1856731 INFO (parallelCoreAdminExecutor-7117-thread-2-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344468697888 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard5 r:core_node10 x:awhollynewcollection_0_shard5_replica_n8] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1856731 INFO (parallelCoreAdminExecutor-7117-thread-2-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344468697888 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard5 r:core_node10 x:awhollynewcollection_0_shard5_replica_n8] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1856732 INFO (parallelCoreAdminExecutor-7117-thread-2-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344468697888 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard5 r:core_node10 x:awhollynewcollection_0_shard5_replica_n8] o.a.s.s.SolrIndexSearcher Opening [Searcher@77f8cf31[awhollynewcollection_0_shard5_replica_n8] main]
[junit4] 2> 1856732 INFO (parallelCoreAdminExecutor-7117-thread-1-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344457431382 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1856733 INFO (parallelCoreAdminExecutor-7117-thread-2-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344468697888 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard5 r:core_node10 x:awhollynewcollection_0_shard5_replica_n8] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf2
[junit4] 2> 1856733 INFO (parallelCoreAdminExecutor-7123-thread-1-processing-n:127.0.0.1:61293_solr fb041d48-216d-41c9-9f39-799afedccb1f844344463297441 CREATE) [n:127.0.0.1:61293_solr c:awhollynewcollection_0 s:shard3 r:core_node7 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1856733 INFO (parallelCoreAdminExecutor-7123-thread-1-processing-n:127.0.0.1:61293_solr fb041d48-216d-41c9-9f39-799afedccb1f844344463297441 CREATE) [n:127.0.0.1:61293_solr c:awhollynewcollection_0 s:shard3 r:core_node7 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1856733 INFO (searcherExecutor-7126-thread-1-processing-x:awhollynewcollection_0_shard1_replica_n1 r:core_node3 fb041d48-216d-41c9-9f39-799afedccb1f844344457431382 CREATE n:127.0.0.1:59792_solr s:shard1 c:awhollynewcollection_0) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.c.SolrCore [awhollynewcollection_0_shard1_replica_n1] Registered new searcher Searcher@4057429[awhollynewcollection_0_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1856734 INFO (parallelCoreAdminExecutor-7117-thread-2-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344468697888 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard5 r:core_node10 x:awhollynewcollection_0_shard5_replica_n8] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf2
[junit4] 2> 1856734 INFO (parallelCoreAdminExecutor-7117-thread-2-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344468697888 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard5 r:core_node10 x:awhollynewcollection_0_shard5_replica_n8] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1856734 INFO (parallelCoreAdminExecutor-7123-thread-1-processing-n:127.0.0.1:61293_solr fb041d48-216d-41c9-9f39-799afedccb1f844344463297441 CREATE) [n:127.0.0.1:61293_solr c:awhollynewcollection_0 s:shard3 r:core_node7 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1856734 INFO (parallelCoreAdminExecutor-7117-thread-2-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344468697888 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard5 r:core_node10 x:awhollynewcollection_0_shard5_replica_n8] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1582840829136863232
[junit4] 2> 1856734 INFO (parallelCoreAdminExecutor-7123-thread-1-processing-n:127.0.0.1:61293_solr fb041d48-216d-41c9-9f39-799afedccb1f844344463297441 CREATE) [n:127.0.0.1:61293_solr c:awhollynewcollection_0 s:shard3 r:core_node7 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1856735 INFO (parallelCoreAdminExecutor-7123-thread-1-processing-n:127.0.0.1:61293_solr fb041d48-216d-41c9-9f39-799afedccb1f844344463297441 CREATE) [n:127.0.0.1:61293_solr c:awhollynewcollection_0 s:shard3 r:core_node7 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.s.SolrIndexSearcher Opening [Searcher@55f1e77f[awhollynewcollection_0_shard3_replica_n4] main]
[junit4] 2> 1856736 INFO (parallelCoreAdminExecutor-7123-thread-1-processing-n:127.0.0.1:61293_solr fb041d48-216d-41c9-9f39-799afedccb1f844344463297441 CREATE) [n:127.0.0.1:61293_solr c:awhollynewcollection_0 s:shard3 r:core_node7 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf2
[junit4] 2> 1856736 INFO (parallelCoreAdminExecutor-7117-thread-1-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344457431382 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1582840829138960384
[junit4] 2> 1856737 INFO (parallelCoreAdminExecutor-7123-thread-1-processing-n:127.0.0.1:61293_solr fb041d48-216d-41c9-9f39-799afedccb1f844344463297441 CREATE) [n:127.0.0.1:61293_solr c:awhollynewcollection_0 s:shard3 r:core_node7 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf2
[junit4] 2> 1856738 INFO (parallelCoreAdminExecutor-7123-thread-1-processing-n:127.0.0.1:61293_solr fb041d48-216d-41c9-9f39-799afedccb1f844344463297441 CREATE) [n:127.0.0.1:61293_solr c:awhollynewcollection_0 s:shard3 r:core_node7 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1856738 INFO (parallelCoreAdminExecutor-7123-thread-1-processing-n:127.0.0.1:61293_solr fb041d48-216d-41c9-9f39-799afedccb1f844344463297441 CREATE) [n:127.0.0.1:61293_solr c:awhollynewcollection_0 s:shard3 r:core_node7 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1582840829141057536
[junit4] 2> 1856740 INFO (searcherExecutor-7125-thread-1-processing-x:awhollynewcollection_0_shard3_replica_n4 r:core_node7 fb041d48-216d-41c9-9f39-799afedccb1f844344463297441 CREATE n:127.0.0.1:61293_solr s:shard3 c:awhollynewcollection_0) [n:127.0.0.1:61293_solr c:awhollynewcollection_0 s:shard3 r:core_node7 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.c.SolrCore [awhollynewcollection_0_shard3_replica_n4] Registered new searcher Searcher@55f1e77f[awhollynewcollection_0_shard3_replica_n4] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1856740 INFO (parallelCoreAdminExecutor-7117-thread-2-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344468697888 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard5 r:core_node10 x:awhollynewcollection_0_shard5_replica_n8] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1856740 INFO (parallelCoreAdminExecutor-7117-thread-2-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344468697888 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard5 r:core_node10 x:awhollynewcollection_0_shard5_replica_n8] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1856740 INFO (parallelCoreAdminExecutor-7117-thread-2-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344468697888 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard5 r:core_node10 x:awhollynewcollection_0_shard5_replica_n8] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:59792/solr/awhollynewcollection_0_shard5_replica_n8/
[junit4] 2> 1856740 INFO (parallelCoreAdminExecutor-7117-thread-2-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344468697888 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard5 r:core_node10 x:awhollynewcollection_0_shard5_replica_n8] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1856740 INFO (parallelCoreAdminExecutor-7117-thread-2-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344468697888 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard5 r:core_node10 x:awhollynewcollection_0_shard5_replica_n8] o.a.s.c.SyncStrategy http://127.0.0.1:59792/solr/awhollynewcollection_0_shard5_replica_n8/ has no replicas
[junit4] 2> 1856741 INFO (parallelCoreAdminExecutor-7117-thread-2-processing-n:127.0.0.1:59792_solr fb041d48-216d-41c9-9f39-799afedccb1f844344468697888 CREATE) [n:127.0.0.1:59792_solr c:awhollynewcollection_0 s:shard5 r:core_node10 x:awhollynewcollection_0_shard5_replica_n8] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 1856743 INFO (parallelCoreAdminExecutor-7123-thread-1-processing-n:127.0.0.1:61293_solr fb041d48-216d-41c9-9f39-799afedccb1f844344463297441 CREATE) [n:127.0.0.1:61293_solr c:awhollynewcollection_0 s:shard3 r:core_node7 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1856744 INFO (parallelCoreAdminExecutor-7123-thread-1-processing-n:127.0.0.1:61293_solr fb041d48-216d-41c9-9f39-799afedccb1f844344463297441 CREATE) [n:127.0.0.1:61293_solr c:awhollynewcollection_0 s:shard3 r:core_node7 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1856744 INFO (parallelCoreAdminExecutor-7123-thread-1-processing-n:127.0.0.1:61293_solr fb041d48-216d-41c9-9f39-799afedccb1f844344463297441 CREATE) [n:127.0.0.1:61293_solr c:awhollynewcollection_0 s:shard3 r:core_node7 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:61293/solr/awhollynewcollection_0_shard3_replica_n4/
[junit4] 2> 1856744 INFO (parallelCoreAdminExecutor-712
[...truncated too long message...]
TestCoreAdminApis, TestLegacyNumericRangeQueryBuilder, CdcrUpdateLogTest, ClassificationUpdateProcessorFactoryTest, TestTolerantUpdateProcessorCloud, TestBulkSchemaAPI, UtilsToolTest, TestShardHandlerFactory, ChaosMonkeyNothingIsSafeWithPullReplicasTest, SecurityConfHandlerTest, TestExpandComponent, HdfsBasicDistributedZkTest, TestCollectionAPIs, TestCryptoKeys, CoreMergeIndexesAdminHandlerTest, AtomicUpdateProcessorFactoryTest, TestBlendedInfixSuggestions, LukeRequestHandlerTest, ExecutePlanActionTest, RestartWhileUpdatingTest, NodeLostTriggerTest, MetricUtilsTest, CacheHeaderTest, CurrencyFieldTypeTest, HdfsTlogReplayBufferedWhileIndexingTest, SoftAutoCommitTest, TestNestedDocsSort, TestRemoteStreaming, TestClusterStateMutator, TestDistribDocBasedVersion, BlockJoinFacetRandomTest, TestFieldCollectionResource, SolrCloudExampleTest, ManagedSchemaRoundRobinCloudTest, TestSolrCloudSnapshots, HLLUtilTest, TermVectorComponentTest, SolrShardReporterTest, ConcurrentDeleteAndCreateCollectionTest, TestQueryUtils, TestFilteredDocIdSet, CoreAdminHandlerTest, UpdateRequestProcessorFactoryTest, CloudMLTQParserTest, TestConfigSetProperties, TestStressReorder, TestPostingsSolrHighlighter, PolyFieldTest, SampleTest, ZkStateWriterTest, TestSolrConfigHandler, SSLMigrationTest, SimpleFacetsTest, TestSolrCoreParser, TestPerFieldSimilarity, SystemInfoHandlerTest, MoveReplicaHDFSTest, HdfsSyncSliceTest, TestPivotHelperCode, DocValuesTest, TestNoOpRegenerator, TestSkipOverseerOperations, TestCorePropertiesReload, MetricsHandlerTest, TestSolrJ, RecoveryAfterSoftCommitTest, DirectSolrSpellCheckerTest, TestNumericRangeQuery32, FullHLLTest, TestNumericTerms64, PKIAuthenticationIntegrationTest, TestFaceting, TestPartialUpdateDeduplication, RuleEngineTest, TestDistribIDF, TestConfig, TestIntervalFaceting, TestSolrIndexConfig, ZkNodePropsTest, VersionInfoTest, TestBM25SimilarityFactory, DistribDocExpirationUpdateProcessorTest, AnalyticsQueryTest, TestRandomFaceting, TestElisionMultitermQuery, TestCSVLoader, TestRawTransformer, TestSystemIdResolver, PingRequestHandlerTest, TestRebalanceLeaders, BasicFunctionalityTest, TestPKIAuthenticationPlugin, TestSubQueryTransformer, DistributedMLTComponentTest, TestStressLucene, SystemLogListenerTest, TestSchemaNameResource, TestBadConfig, HealthCheckHandlerTest, SynonymTokenizerTest, JsonLoaderTest, QueryElevationComponentTest, OpenExchangeRatesOrgProviderTest, DocExpirationUpdateProcessorFactoryTest, BigEndianAscendingWordDeserializerTest, TestSolrQueryResponse, DistributedFacetPivotLargeTest, TestReqParamsAPI, AnalysisAfterCoreReloadTest, TestBinaryResponseWriter, TestCollectionsAPIViaSolrCloudCluster, TestTrieFacet, TestDocSet, TestFreeTextSuggestions, SpatialHeatmapFacetsTest, TestShortCircuitedRequests, IndexSchemaRuntimeFieldTest, DocValuesNotIndexedTest, OverseerRolesTest, TestAuthenticationFramework, DebugComponentTest, TestExportWriter, PeerSyncTest, SOLR749Test, BasicAuthIntegrationTest, TestPushWriter, TestLMJelinekMercerSimilarityFactory, TestCloudPivotFacet, TestAddFieldRealTimeGet, FieldMutatingUpdateProcessorTest, TestManagedResourceStorage, JvmMetricsTest, TestStandardQParsers, RequestLoggingTest, TestTrie, TestAnalyzedSuggestions, TestFieldCache, TestUseDocValuesAsStored2, TestQueryTypes, TestManagedStopFilterFactory, HdfsNNFailoverTest, TestLegacyFieldReuse, TestSerializedLuceneMatchVersion, QueryResultKeyTest, CloudExitableDirectoryReaderTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, SyncSliceTest, OpenCloseCoreStressTest, BasicZkTest, FullSolrCloudDistribCmdsTest, LeaderElectionTest, TestZkChroot, ShardRoutingCustomTest, TestDistributedSearch, TestRecovery, TestRealTimeGet, TestJoin, TestStressVersions, TestRangeQuery, TestGroupingSearch, SolrCmdDistributorTest, BadIndexSchemaTest, TestSort, TestFiltering, TestIndexSearcher, ShowFileRequestHandlerTest, SolrIndexSplitterTest, SolrRequestParserTest, SuggesterTest, SuggesterWFSTTest, SchemaVersionSpecificBehaviorTest, QueryEqualityTest, DistanceFunctionTest, TestSolrDeletionPolicy1, SolrInfoBeanTest, DisMaxRequestHandlerTest, DocumentAnalysisRequestHandlerTest, TestOmitPositions, TermsComponentTest, SolrPluginUtilsTest, TestCollationField, MBeansHandlerTest, BadComponentTest, TestBinaryField, TestSearchPerf, NumericFieldsTest, MinimalSchemaTest, ExternalFileFieldSortTest, SpellPossibilityIteratorTest, EchoParamsTest, TimeZoneUtilsTest, ScriptEngineTest, PluginInfoTest, ChaosMonkeyNothingIsSafeTest, AsyncCallRequestStatusResponseTest, BaseCdcrDistributedZkTest, CdcrReplicationDistributedZkTest, CdcrReplicationHandlerTest, CdcrRequestHandlerTest, CollectionReloadTest, CollectionStateFormat2Test, CollectionTooManyReplicasTest, CustomCollectionTest, DeleteStatusTest, DistributedQueueTest, LeaderFailureAfterFreshStartTest, LeaderInitiatedRecoveryOnShardRestartTest, MigrateRouteKeyTest, MultiThreadedOCPTest, OverseerCollectionConfigSetProcessorTest, RemoteQueryErrorTest, ReplaceNodeTest, SaslZkACLProviderTest, SolrCLIZkUtilsTest, TestCloudInspectUtil, TestCloudJSONFacetJoinDomain, TestCloudSearcherWarming, TestExclusionRuleCollectionAccess, TestHdfsCloudBackupRestore, TestLeaderInitiatedRecoveryThread, TestOnReconnectListenerSupport, TestPullReplica, TestRandomFlRTGCloud, TestReplicaProperties, TestSegmentSorting, TestSolrCloudWithSecureImpersonation]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSolrCloudWithSecureImpersonation -Dtests.seed=A40E31BF3AE97F14 -Dtests.slow=true -Dtests.locale=zh-HK -Dtests.timezone=Africa/Lagos -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | TestSolrCloudWithSecureImpersonation (suite) <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from SUITE scope at org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation:
[junit4] > 1) Thread[id=26185, name=jetty-launcher-4215-thread-1-EventThread, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
[junit4] > at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277)
[junit4] > at org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323)
[junit4] > at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105)
[junit4] > at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288)
[junit4] > at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279)
[junit4] > at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41)
[junit4] > at org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244)
[junit4] > at org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44)
[junit4] > at org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61)
[junit4] > at org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67)
[junit4] > at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:530)
[junit4] > at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:505)
[junit4] > 2) Thread[id=26189, name=jetty-launcher-4215-thread-2-EventThread, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
[junit4] > at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277)
[junit4] > at org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323)
[junit4] > at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105)
[junit4] > at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288)
[junit4] > at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279)
[junit4] > at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41)
[junit4] > at org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244)
[junit4] > at org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44)
[junit4] > at org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61)
[junit4] > at org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67)
[junit4] > at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:530)
[junit4] > at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:505)
[junit4] > at __randomizedtesting.SeedInfo.seed([A40E31BF3AE97F14]:0)
[junit4] Completed [564/747 (2!)] on J1 in 46.02s, 12 tests, 1 error <<< FAILURES!
[...truncated 47811 lines...]