Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2188/

1 tests failed.
FAILED:  
org.apache.solr.client.solrj.impl.CloudSolrClientTest.testRetryUpdatesWhenClusterStateIsStale

Error Message:
Could not load collection from ZK: stale_state_test_col

Stack Trace:
org.apache.solr.common.SolrException: Could not load collection from ZK: 
stale_state_test_col
        at 
__randomizedtesting.SeedInfo.seed([7239F41862A29BC2:C6086CF0814BEDEE]:0)
        at 
org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1122)
        at 
org.apache.solr.common.cloud.ZkStateReader$LazyCollectionRef.get(ZkStateReader.java:647)
        at 
org.apache.solr.common.cloud.ClusterState.getCollectionOrNull(ClusterState.java:130)
        at 
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:154)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClientTest.testRetryUpdatesWhenClusterStateIsStale(CloudSolrClientTest.java:834)
        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 
org.junit.rules.ExpectedException$ExpectedExceptionStatement.evaluate(ExpectedException.java:110)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java: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.zookeeper.KeeperException$SessionExpiredException: 
KeeperErrorCode = Session expired for 
/collections/stale_state_test_col/state.json
        at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
        at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
        at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1212)
        at 
org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:332)
        at 
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
        at 
org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:332)
        at 
org.apache.solr.common.cloud.ZkStateReader.fetchCollectionState(ZkStateReader.java:1134)
        at 
org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1120)
        ... 44 more




Build Log:
[...truncated 15034 lines...]
   [junit4] Suite: org.apache.solr.client.solrj.impl.CloudSolrClientTest
   [junit4]   2> Creating dataDir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.impl.CloudSolrClientTest_7239F41862A29BC2-001/init-core-data-001
   [junit4]   2> 354058 WARN  
(SUITE-CloudSolrClientTest-seed#[7239F41862A29BC2]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=21 numCloses=21
   [junit4]   2> 354058 INFO  
(SUITE-CloudSolrClientTest-seed#[7239F41862A29BC2]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 354121 INFO  
(SUITE-CloudSolrClientTest-seed#[7239F41862A29BC2]-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> 354121 INFO  
(SUITE-CloudSolrClientTest-seed#[7239F41862A29BC2]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 354121 INFO  
(SUITE-CloudSolrClientTest-seed#[7239F41862A29BC2]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.impl.CloudSolrClientTest_7239F41862A29BC2-001/tempDir-001
   [junit4]   2> 354121 INFO  
(SUITE-CloudSolrClientTest-seed#[7239F41862A29BC2]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 354136 INFO  (Thread-243) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 354136 INFO  (Thread-243) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 354181 ERROR (Thread-243) [    ] 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> 354246 INFO  
(SUITE-CloudSolrClientTest-seed#[7239F41862A29BC2]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:46538
   [junit4]   2> 354405 INFO  (jetty-launcher-200-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 354406 INFO  (jetty-launcher-200-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 354407 INFO  (jetty-launcher-200-thread-3) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 354408 INFO  (jetty-launcher-200-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@590901b2{/solr,null,AVAILABLE}
   [junit4]   2> 354424 INFO  (jetty-launcher-200-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@55cb49c7{HTTP/1.1,[http/1.1]}{127.0.0.1:46119}
   [junit4]   2> 354424 INFO  (jetty-launcher-200-thread-1) [    ] 
o.e.j.s.Server Started @374880ms
   [junit4]   2> 354424 INFO  (jetty-launcher-200-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=46119}
   [junit4]   2> 354424 ERROR (jetty-launcher-200-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 354424 INFO  (jetty-launcher-200-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 354424 INFO  (jetty-launcher-200-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 354424 INFO  (jetty-launcher-200-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 354424 INFO  (jetty-launcher-200-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-11-24T00:31:31.687Z
   [junit4]   2> 354470 INFO  (jetty-launcher-200-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@32b4ea85{/solr,null,AVAILABLE}
   [junit4]   2> 354471 INFO  (jetty-launcher-200-thread-2) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@4346de9d{HTTP/1.1,[http/1.1]}{127.0.0.1:46551}
   [junit4]   2> 354471 INFO  (jetty-launcher-200-thread-2) [    ] 
o.e.j.s.Server Started @374927ms
   [junit4]   2> 354471 INFO  (jetty-launcher-200-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=46551}
   [junit4]   2> 354471 ERROR (jetty-launcher-200-thread-2) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 354471 INFO  (jetty-launcher-200-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 354471 INFO  (jetty-launcher-200-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 354471 INFO  (jetty-launcher-200-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 354471 INFO  (jetty-launcher-200-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-11-24T00:31:31.734Z
   [junit4]   2> 354519 INFO  (jetty-launcher-200-thread-3) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@6bacd553{/solr,null,AVAILABLE}
   [junit4]   2> 354519 INFO  (jetty-launcher-200-thread-3) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@44b1c1cc{HTTP/1.1,[http/1.1]}{127.0.0.1:43666}
   [junit4]   2> 354519 INFO  (jetty-launcher-200-thread-3) [    ] 
o.e.j.s.Server Started @374975ms
   [junit4]   2> 354519 INFO  (jetty-launcher-200-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=43666}
   [junit4]   2> 354519 ERROR (jetty-launcher-200-thread-3) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 354519 INFO  (jetty-launcher-200-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 354519 INFO  (jetty-launcher-200-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 354519 INFO  (jetty-launcher-200-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 354519 INFO  (jetty-launcher-200-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-11-24T00:31:31.782Z
   [junit4]   2> 354653 INFO  (jetty-launcher-200-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 354730 INFO  (jetty-launcher-200-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 354812 INFO  (jetty-launcher-200-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 355141 INFO  (jetty-launcher-200-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46538/solr
   [junit4]   2> 355186 INFO  (jetty-launcher-200-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46538/solr
   [junit4]   2> 355251 INFO  (jetty-launcher-200-thread-3) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46538/solr
   [junit4]   2> 355509 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 0x15feb6fc44b0004, 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> 355519 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 0x15feb6fc44b0005, 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> 355552 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 0x15feb6fc44b0006, 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> 356359 WARN  (jetty-launcher-200-thread-1) 
[n:127.0.0.1:46119_solr    ] o.a.s.c.ZkController The _default configset could 
not be uploaded. Please provide 'solr.default.confdir' parameter that points to 
a configset intended to be the default. Current 'solr.default.confdir' value: 
null
   [junit4]   2> 356542 WARN  (jetty-launcher-200-thread-2) 
[n:127.0.0.1:46551_solr    ] o.a.s.c.ZkController The _default configset could 
not be uploaded. Please provide 'solr.default.confdir' parameter that points to 
a configset intended to be the default. Current 'solr.default.confdir' value: 
null
   [junit4]   2> 356543 WARN  (jetty-launcher-200-thread-3) 
[n:127.0.0.1:43666_solr    ] o.a.s.c.ZkController The _default configset could 
not be uploaded. Please provide 'solr.default.confdir' parameter that points to 
a configset intended to be the default. Current 'solr.default.confdir' value: 
null
   [junit4]   2> 356581 INFO  (jetty-launcher-200-thread-1) 
[n:127.0.0.1:46119_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 356581 INFO  (jetty-launcher-200-thread-3) 
[n:127.0.0.1:43666_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 356616 INFO  (jetty-launcher-200-thread-2) 
[n:127.0.0.1:46551_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 356616 INFO  (jetty-launcher-200-thread-1) 
[n:127.0.0.1:46119_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:46119_solr
   [junit4]   2> 356617 INFO  (jetty-launcher-200-thread-3) 
[n:127.0.0.1:43666_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:43666_solr
   [junit4]   2> 356635 INFO  (jetty-launcher-200-thread-2) 
[n:127.0.0.1:46551_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:46551_solr
   [junit4]   2> 356654 INFO  (jetty-launcher-200-thread-1) 
[n:127.0.0.1:46119_solr    ] o.a.s.c.Overseer Overseer 
(id=99056582092587015-127.0.0.1:46119_solr-n_0000000000) starting
   [junit4]   2> 356760 INFO  
(zkCallback-217-thread-1-processing-n:127.0.0.1:46551_solr) 
[n:127.0.0.1:46551_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 356800 INFO  
(zkCallback-215-thread-1-processing-n:127.0.0.1:46119_solr) 
[n:127.0.0.1:46119_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 356800 INFO  
(zkCallback-216-thread-1-processing-n:127.0.0.1:43666_solr) 
[n:127.0.0.1:43666_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 356835 INFO  
(zkCallback-217-thread-2-processing-n:127.0.0.1:46551_solr) 
[n:127.0.0.1:46551_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 356910 INFO  (jetty-launcher-200-thread-1) 
[n:127.0.0.1:46119_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:46119_solr
   [junit4]   2> 356972 INFO  
(zkCallback-216-thread-1-processing-n:127.0.0.1:43666_solr) 
[n:127.0.0.1:43666_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 356980 INFO  
(zkCallback-217-thread-2-processing-n:127.0.0.1:46551_solr) 
[n:127.0.0.1:46551_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 356980 INFO  
(zkCallback-215-thread-2-processing-n:127.0.0.1:46119_solr) 
[n:127.0.0.1:46119_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 357881 INFO  (jetty-launcher-200-thread-2) 
[n:127.0.0.1:46551_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46551.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2bbf094a
   [junit4]   2> 358100 INFO  (jetty-launcher-200-thread-2) 
[n:127.0.0.1:46551_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46551.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2bbf094a
   [junit4]   2> 358139 INFO  (jetty-launcher-200-thread-3) 
[n:127.0.0.1:43666_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_43666.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2bbf094a
   [junit4]   2> 358322 INFO  (jetty-launcher-200-thread-2) 
[n:127.0.0.1:46551_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46551.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2bbf094a
   [junit4]   2> 358335 INFO  (jetty-launcher-200-thread-2) 
[n:127.0.0.1:46551_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.impl.CloudSolrClientTest_7239F41862A29BC2-001/tempDir-001/node2/.
   [junit4]   2> 358496 INFO  (jetty-launcher-200-thread-1) 
[n:127.0.0.1:46119_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46119.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2bbf094a
   [junit4]   2> 358516 INFO  (jetty-launcher-200-thread-3) 
[n:127.0.0.1:43666_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_43666.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2bbf094a
   [junit4]   2> 358517 INFO  (jetty-launcher-200-thread-3) 
[n:127.0.0.1:43666_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_43666.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2bbf094a
   [junit4]   2> 358518 INFO  (jetty-launcher-200-thread-3) 
[n:127.0.0.1:43666_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.impl.CloudSolrClientTest_7239F41862A29BC2-001/tempDir-001/node3/.
   [junit4]   2> 358606 INFO  (jetty-launcher-200-thread-1) 
[n:127.0.0.1:46119_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46119.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2bbf094a
   [junit4]   2> 358606 INFO  (jetty-launcher-200-thread-1) 
[n:127.0.0.1:46119_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46119.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2bbf094a
   [junit4]   2> 358607 INFO  (jetty-launcher-200-thread-1) 
[n:127.0.0.1:46119_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.impl.CloudSolrClientTest_7239F41862A29BC2-001/tempDir-001/node1/.
   [junit4]   2> 359387 INFO  
(SUITE-CloudSolrClientTest-seed#[7239F41862A29BC2]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
   [junit4]   2> 359388 INFO  
(SUITE-CloudSolrClientTest-seed#[7239F41862A29BC2]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46538/solr ready
   [junit4]   2> 359456 INFO  (qtp178876608-1066) [n:127.0.0.1:43666_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=1&collection.configName=conf&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 359548 INFO  
(OverseerThreadFactory-394-thread-1-processing-n:127.0.0.1:46119_solr) 
[n:127.0.0.1:46119_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
collection1
   [junit4]   2> 360325 INFO  
(OverseerStateUpdate-99056582092587015-127.0.0.1:46119_solr-n_0000000000) 
[n:127.0.0.1:46119_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"collection1_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:46551/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 360626 INFO  
(OverseerStateUpdate-99056582092587015-127.0.0.1:46119_solr-n_0000000000) 
[n:127.0.0.1:46119_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"collection1_shard2_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:43666/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 360967 INFO  (qtp137904084-1057) [n:127.0.0.1:46551_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 360976 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 360976 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 361029 INFO  (qtp137904084-1057) [n:127.0.0.1:46551_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 361322 INFO  
(zkCallback-217-thread-2-processing-n:127.0.0.1:46551_solr) 
[n:127.0.0.1:46551_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 361322 INFO  
(zkCallback-216-thread-1-processing-n:127.0.0.1:43666_solr) 
[n:127.0.0.1:43666_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 361322 INFO  
(zkCallback-216-thread-2-processing-n:127.0.0.1:43666_solr) 
[n:127.0.0.1:43666_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 361330 INFO  
(zkCallback-217-thread-1-processing-n:127.0.0.1:46551_solr) 
[n:127.0.0.1:46551_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 362260 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 362281 INFO  (qtp137904084-1057) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 362426 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.s.IndexSchema [collection1_shard2_replica_n2] Schema name=test
   [junit4]   2> 362531 INFO  (qtp137904084-1057) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.s.IndexSchema [collection1_shard1_replica_n1] Schema name=test
   [junit4]   2> 363326 WARN  (qtp178876608-1064) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for 
multiple copyFields (2)
   [junit4]   2> 363326 WARN  (qtp178876608-1064) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple 
copyFields (3)
   [junit4]   2> 363327 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 363327 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n2' using 
configuration from collection collection1, trusted=true
   [junit4]   2> 363367 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_43666.solr.core.collection1.shard2.replica_n2' (registry 
'solr.core.collection1.shard2.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2bbf094a
   [junit4]   2> 363367 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 363367 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.c.SolrCore [[collection1_shard2_replica_n2] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.impl.CloudSolrClientTest_7239F41862A29BC2-001/tempDir-001/node3/collection1_shard2_replica_n2],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.impl.CloudSolrClientTest_7239F41862A29BC2-001/tempDir-001/node3/./collection1_shard2_replica_n2/data/]
   [junit4]   2> 363419 WARN  (qtp137904084-1057) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for 
multiple copyFields (2)
   [junit4]   2> 363419 WARN  (qtp137904084-1057) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple 
copyFields (3)
   [junit4]   2> 363419 INFO  (qtp137904084-1057) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 363419 INFO  (qtp137904084-1057) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n1' using 
configuration from collection collection1, trusted=true
   [junit4]   2> 363430 INFO  (qtp137904084-1057) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46551.solr.core.collection1.shard1.replica_n1' (registry 
'solr.core.collection1.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2bbf094a
   [junit4]   2> 363430 INFO  (qtp137904084-1057) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 363430 INFO  (qtp137904084-1057) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.SolrCore [[collection1_shard1_replica_n1] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.impl.CloudSolrClientTest_7239F41862A29BC2-001/tempDir-001/node2/collection1_shard1_replica_n1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.impl.CloudSolrClientTest_7239F41862A29BC2-001/tempDir-001/node2/./collection1_shard1_replica_n1/data/]
   [junit4]   2> 363792 INFO  (qtp137904084-1057) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 363792 INFO  (qtp137904084-1057) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 363794 INFO  (qtp137904084-1057) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 363794 INFO  (qtp137904084-1057) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 363795 INFO  (qtp137904084-1057) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@7981daa[collection1_shard1_replica_n1] main]
   [junit4]   2> 363813 INFO  (qtp137904084-1057) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 363814 INFO  (qtp137904084-1057) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 363814 INFO  (qtp137904084-1057) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 363859 INFO  
(searcherExecutor-402-thread-1-processing-n:127.0.0.1:46551_solr 
x:collection1_shard1_replica_n1 s:shard1 c:collection1 r:core_node3) 
[n:127.0.0.1:46551_solr c:collection1 s:shard1 r:core_node3 
x:collection1_shard1_replica_n1] o.a.s.c.SolrCore 
[collection1_shard1_replica_n1] Registered new searcher 
Searcher@7981daa[collection1_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 363859 INFO  (qtp137904084-1057) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1584905323672502272
   [junit4]   2> 363969 INFO  (qtp137904084-1057) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 363969 INFO  (qtp137904084-1057) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 363970 INFO  (qtp137904084-1057) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:46551/solr/collection1_shard1_replica_n1/
   [junit4]   2> 363970 INFO  (qtp137904084-1057) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 363970 INFO  (qtp137904084-1057) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.SyncStrategy http://127.0.0.1:46551/solr/collection1_shard1_replica_n1/ 
has no replicas
   [junit4]   2> 363970 INFO  (qtp137904084-1057) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 364016 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 364016 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 364017 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 364017 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 364019 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@24512393[collection1_shard2_replica_n2] main]
   [junit4]   2> 364063 INFO  (qtp137904084-1057) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:46551/solr/collection1_shard1_replica_n1/ shard1
   [junit4]   2> 364089 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 364089 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 364090 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 364090 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1584905323914723328
   [junit4]   2> 364090 INFO  
(zkCallback-217-thread-2-processing-n:127.0.0.1:46551_solr) 
[n:127.0.0.1:46551_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 364097 INFO  
(zkCallback-217-thread-1-processing-n:127.0.0.1:46551_solr) 
[n:127.0.0.1:46551_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 364112 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 364112 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 364112 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:43666/solr/collection1_shard2_replica_n2/
   [junit4]   2> 364112 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 364112 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.c.SyncStrategy http://127.0.0.1:43666/solr/collection1_shard2_replica_n2/ 
has no replicas
   [junit4]   2> 364112 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 364125 INFO  (qtp137904084-1057) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 364125 INFO  
(zkCallback-216-thread-2-processing-n:127.0.0.1:43666_solr) 
[n:127.0.0.1:43666_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 364125 INFO  
(zkCallback-216-thread-1-processing-n:127.0.0.1:43666_solr) 
[n:127.0.0.1:43666_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 364126 INFO  
(searcherExecutor-401-thread-1-processing-n:127.0.0.1:43666_solr 
x:collection1_shard2_replica_n2 s:shard2 c:collection1 r:core_node4) 
[n:127.0.0.1:43666_solr c:collection1 s:shard2 r:core_node4 
x:collection1_shard2_replica_n2] o.a.s.c.SolrCore 
[collection1_shard2_replica_n2] Registered new searcher 
Searcher@24512393[collection1_shard2_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 364185 INFO  (qtp137904084-1057) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_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=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=3218
   [junit4]   2> 364201 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:43666/solr/collection1_shard2_replica_n2/ shard2
   [junit4]   2> 364378 INFO  
(zkCallback-217-thread-2-processing-n:127.0.0.1:46551_solr) 
[n:127.0.0.1:46551_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 364378 INFO  
(zkCallback-216-thread-1-processing-n:127.0.0.1:43666_solr) 
[n:127.0.0.1:43666_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 364378 INFO  
(zkCallback-216-thread-2-processing-n:127.0.0.1:43666_solr) 
[n:127.0.0.1:43666_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 364386 INFO  
(zkCallback-217-thread-1-processing-n:127.0.0.1:46551_solr) 
[n:127.0.0.1:46551_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 364422 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 364492 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_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=collection1_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=3516
   [junit4]   2> 364908 INFO  (qtp178876608-1066) [n:127.0.0.1:43666_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> 365017 INFO  
(zkCallback-217-thread-2-processing-n:127.0.0.1:46551_solr) 
[n:127.0.0.1:46551_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 365017 INFO  
(zkCallback-216-thread-2-processing-n:127.0.0.1:43666_solr) 
[n:127.0.0.1:43666_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 365017 INFO  
(zkCallback-216-thread-1-processing-n:127.0.0.1:43666_solr) 
[n:127.0.0.1:43666_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 365025 INFO  
(zkCallback-217-thread-1-processing-n:127.0.0.1:46551_solr) 
[n:127.0.0.1:46551_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 365624 INFO  
(OverseerCollectionConfigSetProcessor-99056582092587015-127.0.0.1:46119_solr-n_0000000000)
 [n:127.0.0.1:46119_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> 365916 INFO  (qtp178876608-1066) [n:127.0.0.1:43666_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={replicationFactor=1&collection.configName=conf&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2}
 status=0 QTime=6459
   [junit4]   2> 365920 INFO  
(SUITE-CloudSolrClientTest-seed#[7239F41862A29BC2]-worker) [    ] 
o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: 
collection1 failOnTimeout:true timeout (sec):30
   [junit4]   2> 365936 INFO  
(SUITE-CloudSolrClientTest-seed#[7239F41862A29BC2]-worker) [    ] 
o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1
   [junit4]   2> 365991 INFO  (qtp137904084-1055) [n:127.0.0.1:46551_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with 
params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 365998 INFO  (qtp137904084-1055) [n:127.0.0.1:46551_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=7
   [junit4]   2> 366273 INFO  
(TEST-CloudSolrClientTest.preferLocalShardsTest-seed#[7239F41862A29BC2]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting preferLocalShardsTest
   [junit4]   2> 366346 INFO  (qtp137904084-1060) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:43666/solr/collection1_shard2_replica_n2/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1584905326241513472)} 0 37
   [junit4]   2> 366469 INFO  (qtp178876608-1068) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1584905326409285632,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 366470 INFO  (qtp178876608-1068) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 366470 INFO  (qtp137904084-1054) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1584905326410334208,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 366470 INFO  (qtp137904084-1054) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 366490 INFO  (qtp178876608-1068) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 366506 INFO  (qtp178876608-1068) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:43666/solr/collection1_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 36
   [junit4]   2> 366506 INFO  (qtp137904084-1054) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 366526 INFO  (qtp137904084-1054) [n:127.0.0.1:46551_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:43666/solr/collection1_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 55
   [junit4]   2> 366551 INFO  (qtp178876608-1067) [n:127.0.0.1:43666_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2]  
webapp=/solr path=/update 
params={_stateVer_=collection1:5&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
 (-1584905326238367744),commit=} 0 264
   [junit4]   2> 366563 INFO  (qtp178876608-1069) [n:127.0.0.1:43666_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
async=8f3a014a-2757-4700-ba89-536e6de97248&replicationFactor=3&collection.configName=conf&maxShardsPerNode=3&name=localShardsTestColl&nrtReplicas=3&action=CREATE&numShards=3&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 366584 INFO  (qtp178876608-1069) [n:127.0.0.1:43666_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={async=8f3a014a-2757-4700-ba89-536e6de97248&replicationFactor=3&collection.configName=conf&maxShardsPerNode=3&name=localShardsTestColl&nrtReplicas=3&action=CREATE&numShards=3&wt=javabin&version=2}
 status=0 QTime=21
   [junit4]   2> 366586 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=8f3a014a-2757-4700-ba89-536e6de97248&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 366600 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=8f3a014a-2757-4700-ba89-536e6de97248&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=13
   [junit4]   2> 366603 INFO  
(OverseerThreadFactory-394-thread-2-processing-n:127.0.0.1:46119_solr) 
[n:127.0.0.1:46119_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
localShardsTestColl
   [junit4]   2> 366915 INFO  
(OverseerStateUpdate-99056582092587015-127.0.0.1:46119_solr-n_0000000000) 
[n:127.0.0.1:46119_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"localShardsTestColl",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"localShardsTestColl_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:46551/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 366936 INFO  
(OverseerStateUpdate-99056582092587015-127.0.0.1:46119_solr-n_0000000000) 
[n:127.0.0.1:46119_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"localShardsTestColl",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"localShardsTestColl_shard1_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:46119/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 366992 INFO  
(OverseerStateUpdate-99056582092587015-127.0.0.1:46119_solr-n_0000000000) 
[n:127.0.0.1:46119_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"localShardsTestColl",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"localShardsTestColl_shard1_replica_n4",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:43666/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 367027 INFO  
(OverseerStateUpdate-99056582092587015-127.0.0.1:46119_solr-n_0000000000) 
[n:127.0.0.1:46119_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"localShardsTestColl",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"localShardsTestColl_shard2_replica_n6",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:46551/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 367030 INFO  
(OverseerStateUpdate-99056582092587015-127.0.0.1:46119_solr-n_0000000000) 
[n:127.0.0.1:46119_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"localShardsTestColl",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"localShardsTestColl_shard2_replica_n8",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:46119/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 367065 INFO  
(OverseerStateUpdate-99056582092587015-127.0.0.1:46119_solr-n_0000000000) 
[n:127.0.0.1:46119_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"localShardsTestColl",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"localShardsTestColl_shard2_replica_n10",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:43666/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 367100 INFO  
(OverseerStateUpdate-99056582092587015-127.0.0.1:46119_solr-n_0000000000) 
[n:127.0.0.1:46119_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"localShardsTestColl",
   [junit4]   2>   "shard":"shard3",
   [junit4]   2>   "core":"localShardsTestColl_shard3_replica_n12",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:46551/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 367104 INFO  
(OverseerStateUpdate-99056582092587015-127.0.0.1:46119_solr-n_0000000000) 
[n:127.0.0.1:46119_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"localShardsTestColl",
   [junit4]   2>   "shard":"shard3",
   [junit4]   2>   "core":"localShardsTestColl_shard3_replica_n14",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:46119/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 367139 INFO  
(OverseerStateUpdate-99056582092587015-127.0.0.1:46119_solr-n_0000000000) 
[n:127.0.0.1:46119_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"localShardsTestColl",
   [junit4]   2>   "shard":"shard3",
   [junit4]   2>   "core":"localShardsTestColl_shard3_replica_n16",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:43666/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 367455 INFO  (qtp1791219700-1045) [n:127.0.0.1:46119_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&collection=localShardsTestColl&version=2&replicaType=NRT&async=8f3a014a-2757-4700-ba89-536e6de9724811592813476637547&coreNodeName=core_node5&name=localShardsTestColl_shard1_replica_n2&action=CREATE&numShards=3&shard=shard1&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 367456 INFO  
(parallelCoreAdminExecutor-397-thread-1-processing-n:127.0.0.1:46119_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813476637547 CREATE) 
[n:127.0.0.1:46119_solr    ] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&collection=localShardsTestColl&version=2&replicaType=NRT&async=8f3a014a-2757-4700-ba89-536e6de9724811592813476637547&coreNodeName=core_node5&name=localShardsTestColl_shard1_replica_n2&action=CREATE&numShards=3&shard=shard1&wt=javabin
   [junit4]   2> 367456 INFO  
(parallelCoreAdminExecutor-397-thread-1-processing-n:127.0.0.1:46119_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813476637547 CREATE) 
[n:127.0.0.1:46119_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 367475 INFO  (qtp137904084-1058) [n:127.0.0.1:46551_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&collection=localShardsTestColl&version=2&replicaType=NRT&async=8f3a014a-2757-4700-ba89-536e6de9724811592813456484650&coreNodeName=core_node3&name=localShardsTestColl_shard1_replica_n1&action=CREATE&numShards=3&shard=shard1&wt=javabin}
 status=0 QTime=115
   [junit4]   2> 367475 INFO  
(parallelCoreAdminExecutor-395-thread-1-processing-n:127.0.0.1:46551_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813456484650 CREATE) 
[n:127.0.0.1:46551_solr    ] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&collection=localShardsTestColl&version=2&replicaType=NRT&async=8f3a014a-2757-4700-ba89-536e6de9724811592813456484650&coreNodeName=core_node3&name=localShardsTestColl_shard1_replica_n1&action=CREATE&numShards=3&shard=shard1&wt=javabin
   [junit4]   2> 367519 INFO  (qtp178876608-1063) [n:127.0.0.1:43666_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&collection=localShardsTestColl&version=2&replicaType=NRT&async=8f3a014a-2757-4700-ba89-536e6de9724811592813532924472&coreNodeName=core_node7&name=localShardsTestColl_shard1_replica_n4&action=CREATE&numShards=3&shard=shard1&wt=javabin}
 status=0 QTime=138
   [junit4]   2> 367556 INFO  
(parallelCoreAdminExecutor-396-thread-1-processing-n:127.0.0.1:43666_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813532924472 CREATE) 
[n:127.0.0.1:43666_solr    ] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&collection=localShardsTestColl&version=2&replicaType=NRT&async=8f3a014a-2757-4700-ba89-536e6de9724811592813532924472&coreNodeName=core_node7&name=localShardsTestColl_shard1_replica_n4&action=CREATE&numShards=3&shard=shard1&wt=javabin
   [junit4]   2> 367605 INFO  (qtp178876608-1067) [n:127.0.0.1:43666_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=8f3a014a-2757-4700-ba89-536e6de97248&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 367646 INFO  (qtp137904084-1057) [n:127.0.0.1:46551_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&collection=localShardsTestColl&version=2&replicaType=NRT&async=8f3a014a-2757-4700-ba89-536e6de9724811592813535570496&coreNodeName=core_node9&name=localShardsTestColl_shard2_replica_n6&action=CREATE&numShards=3&shard=shard2&wt=javabin}
 status=0 QTime=271
   [junit4]   2> 367646 INFO  
(parallelCoreAdminExecutor-395-thread-2-processing-n:127.0.0.1:46551_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813535570496 CREATE) 
[n:127.0.0.1:46551_solr    ] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&collection=localShardsTestColl&version=2&replicaType=NRT&async=8f3a014a-2757-4700-ba89-536e6de9724811592813535570496&coreNodeName=core_node9&name=localShardsTestColl_shard2_replica_n6&action=CREATE&numShards=3&shard=shard2&wt=javabin
   [junit4]   2> 367658 INFO  (qtp178876608-1067) [n:127.0.0.1:43666_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=8f3a014a-2757-4700-ba89-536e6de97248&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=52
   [junit4]   2> 367731 INFO  (qtp137904084-1055) [n:127.0.0.1:46551_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&collection=localShardsTestColl&version=2&replicaType=NRT&async=8f3a014a-2757-4700-ba89-536e6de9724811592813608772070&coreNodeName=core_node15&name=localShardsTestColl_shard3_replica_n12&action=CREATE&numShards=3&shard=shard3&wt=javabin}
 status=0 QTime=264
   [junit4]   2> 367731 INFO  
(parallelCoreAdminExecutor-395-thread-3-processing-n:127.0.0.1:46551_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813608772070 CREATE) 
[n:127.0.0.1:46551_solr    ] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&collection=localShardsTestColl&version=2&replicaType=NRT&async=8f3a014a-2757-4700-ba89-536e6de9724811592813608772070&coreNodeName=core_node15&name=localShardsTestColl_shard3_replica_n12&action=CREATE&numShards=3&shard=shard3&wt=javabin
   [junit4]   2> 367780 INFO  (qtp178876608-1066) [n:127.0.0.1:43666_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&collection=localShardsTestColl&version=2&replicaType=NRT&async=8f3a014a-2757-4700-ba89-536e6de9724811592813680517994&coreNodeName=core_node18&name=localShardsTestColl_shard3_replica_n16&action=CREATE&numShards=3&shard=shard3&wt=javabin}
 status=0 QTime=314
   [junit4]   2> 367780 INFO  
(parallelCoreAdminExecutor-396-thread-3-processing-n:127.0.0.1:43666_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813680517994 CREATE) 
[n:127.0.0.1:43666_solr    ] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&collection=localShardsTestColl&version=2&replicaType=NRT&async=8f3a014a-2757-4700-ba89-536e6de9724811592813680517994&coreNodeName=core_node18&name=localShardsTestColl_shard3_replica_n16&action=CREATE&numShards=3&shard=shard3&wt=javabin
   [junit4]   2> 367781 INFO  (qtp178876608-1070) [n:127.0.0.1:43666_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&collection=localShardsTestColl&version=2&replicaType=NRT&async=8f3a014a-2757-4700-ba89-536e6de9724811592813606186525&coreNodeName=core_node13&name=localShardsTestColl_shard2_replica_n10&action=CREATE&numShards=3&shard=shard2&wt=javabin}
 status=0 QTime=343
   [junit4]   2> 367781 INFO  
(parallelCoreAdminExecutor-396-thread-2-processing-n:127.0.0.1:43666_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813606186525 CREATE) 
[n:127.0.0.1:43666_solr    ] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&collection=localShardsTestColl&version=2&replicaType=NRT&async=8f3a014a-2757-4700-ba89-536e6de9724811592813606186525&coreNodeName=core_node13&name=localShardsTestColl_shard2_replica_n10&action=CREATE&numShards=3&shard=shard2&wt=javabin
   [junit4]   2> 367890 INFO  (qtp1791219700-1048) [n:127.0.0.1:46119_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&collection=localShardsTestColl&version=2&replicaType=NRT&async=8f3a014a-2757-4700-ba89-536e6de9724811592813570919723&coreNodeName=core_node11&name=localShardsTestColl_shard2_replica_n8&action=CREATE&numShards=3&shard=shard2&wt=javabin}
 status=0 QTime=130
   [junit4]   2> 367890 INFO  
(parallelCoreAdminExecutor-397-thread-2-processing-n:127.0.0.1:46119_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813570919723 CREATE) 
[n:127.0.0.1:46119_solr    ] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&collection=localShardsTestColl&version=2&replicaType=NRT&async=8f3a014a-2757-4700-ba89-536e6de9724811592813570919723&coreNodeName=core_node11&name=localShardsTestColl_shard2_replica_n8&action=CREATE&numShards=3&shard=shard2&wt=javabin
   [junit4]   2> 368034 INFO  (qtp1791219700-1051) [n:127.0.0.1:46119_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&collection=localShardsTestColl&version=2&replicaType=NRT&async=8f3a014a-2757-4700-ba89-536e6de9724811592813645127851&coreNodeName=core_node17&name=localShardsTestColl_shard3_replica_n14&action=CREATE&numShards=3&shard=shard3&wt=javabin}
 status=0 QTime=115
   [junit4]   2> 368035 INFO  
(parallelCoreAdminExecutor-397-thread-3-processing-n:127.0.0.1:46119_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813645127851 CREATE) 
[n:127.0.0.1:46119_solr    ] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&collection=localShardsTestColl&version=2&replicaType=NRT&async=8f3a014a-2757-4700-ba89-536e6de9724811592813645127851&coreNodeName=core_node17&name=localShardsTestColl_shard3_replica_n14&action=CREATE&numShards=3&shard=shard3&wt=javabin
   [junit4]   2> 368069 INFO  (qtp1791219700-1052) [n:127.0.0.1:46119_solr    ] 
o.a.s.h.a.CoreAdminOperation Checking request status for : 
8f3a014a-2757-4700-ba89-536e6de9724811592813645127851
   [junit4]   2> 368069 INFO  (qtp1791219700-1052) [n:127.0.0.1:46119_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=8f3a014a-2757-4700-ba89-536e6de9724811592813645127851&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 368217 INFO  
(zkCallback-215-thread-2-processing-n:127.0.0.1:46119_solr) 
[n:127.0.0.1:46119_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/localShardsTestColl/state.json] for collection 
[localShardsTestColl] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 368217 INFO  
(zkCallback-215-thread-1-processing-n:127.0.0.1:46119_solr) 
[n:127.0.0.1:46119_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/localShardsTestColl/state.json] for collection 
[localShardsTestColl] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 368217 INFO  
(zkCallback-216-thread-1-processing-n:127.0.0.1:43666_solr) 
[n:127.0.0.1:43666_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/localShardsTestColl/state.json] for collection 
[localShardsTestColl] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 368217 INFO  
(zkCallback-216-thread-2-processing-n:127.0.0.1:43666_solr) 
[n:127.0.0.1:43666_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/localShardsTestColl/state.json] for collection 
[localShardsTestColl] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 368227 INFO  
(zkCallback-217-thread-2-processing-n:127.0.0.1:46551_solr) 
[n:127.0.0.1:46551_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/localShardsTestColl/state.json] for collection 
[localShardsTestColl] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 368231 INFO  
(zkCallback-217-thread-1-processing-n:127.0.0.1:46551_solr) 
[n:127.0.0.1:46551_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/localShardsTestColl/state.json] for collection 
[localShardsTestColl] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 368753 INFO  (qtp178876608-1069) [n:127.0.0.1:43666_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=8f3a014a-2757-4700-ba89-536e6de97248&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 368767 INFO  
(parallelCoreAdminExecutor-396-thread-1-processing-n:127.0.0.1:43666_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813532924472 CREATE) 
[n:127.0.0.1:43666_solr c:localShardsTestColl s:shard1 r:core_node7 
x:localShardsTestColl_shard1_replica_n4] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 8.0.0
   [junit4]   2> 369012 INFO  (qtp178876608-1069) [n:127.0.0.1:43666_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=8f3a014a-2757-4700-ba89-536e6de97248&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=259
   [junit4]   2> 369132 INFO  (qtp1791219700-1049) [n:127.0.0.1:46119_solr    ] 
o.a.s.h.a.CoreAdminOperation Checking request status for : 
8f3a014a-2757-4700-ba89-536e6de9724811592813645127851
   [junit4]   2> 369132 INFO  (qtp1791219700-1049) [n:127.0.0.1:46119_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=8f3a014a-2757-4700-ba89-536e6de9724811592813645127851&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 369143 INFO  
(parallelCoreAdminExecutor-396-thread-3-processing-n:127.0.0.1:43666_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813680517994 CREATE) 
[n:127.0.0.1:43666_solr c:localShardsTestColl s:shard3 r:core_node18 
x:localShardsTestColl_shard3_replica_n16] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 8.0.0
   [junit4]   2> 369160 INFO  
(parallelCoreAdminExecutor-396-thread-1-processing-n:127.0.0.1:43666_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813532924472 CREATE) 
[n:127.0.0.1:43666_solr c:localShardsTestColl s:shard1 r:core_node7 
x:localShardsTestColl_shard1_replica_n4] o.a.s.s.IndexSchema 
[localShardsTestColl_shard1_replica_n4] Schema name=test
   [junit4]   2> 369477 INFO  
(parallelCoreAdminExecutor-396-thread-3-processing-n:127.0.0.1:43666_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813680517994 CREATE) 
[n:127.0.0.1:43666_solr c:localShardsTestColl s:shard3 r:core_node18 
x:localShardsTestColl_shard3_replica_n16] o.a.s.s.IndexSchema 
[localShardsTestColl_shard3_replica_n16] Schema name=test
   [junit4]   2> 369573 INFO  
(parallelCoreAdminExecutor-395-thread-3-processing-n:127.0.0.1:46551_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813608772070 CREATE) 
[n:127.0.0.1:46551_solr c:localShardsTestColl s:shard3 r:core_node15 
x:localShardsTestColl_shard3_replica_n12] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 8.0.0
   [junit4]   2> 369573 INFO  
(parallelCoreAdminExecutor-395-thread-2-processing-n:127.0.0.1:46551_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813535570496 CREATE) 
[n:127.0.0.1:46551_solr c:localShardsTestColl s:shard2 r:core_node9 
x:localShardsTestColl_shard2_replica_n6] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 8.0.0
   [junit4]   2> 369900 INFO  
(parallelCoreAdminExecutor-395-thread-1-processing-n:127.0.0.1:46551_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813456484650 CREATE) 
[n:127.0.0.1:46551_solr c:localShardsTestColl s:shard1 r:core_node3 
x:localShardsTestColl_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 8.0.0
   [junit4]   2> 369901 INFO  
(parallelCoreAdminExecutor-396-thread-2-processing-n:127.0.0.1:43666_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813606186525 CREATE) 
[n:127.0.0.1:43666_solr c:localShardsTestColl s:shard2 r:core_node13 
x:localShardsTestColl_shard2_replica_n10] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 8.0.0
   [junit4]   2> 369939 INFO  
(parallelCoreAdminExecutor-397-thread-1-processing-n:127.0.0.1:46119_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813476637547 CREATE) 
[n:127.0.0.1:46119_solr c:localShardsTestColl s:shard1 r:core_node5 
x:localShardsTestColl_shard1_replica_n2] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 8.0.0
   [junit4]   2> 370012 INFO  
(parallelCoreAdminExecutor-397-thread-3-processing-n:127.0.0.1:46119_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813645127851 CREATE) 
[n:127.0.0.1:46119_solr c:localShardsTestColl s:shard3 r:core_node17 
x:localShardsTestColl_shard3_replica_n14] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 8.0.0
   [junit4]   2> 370029 INFO  
(parallelCoreAdminExecutor-396-thread-2-processing-n:127.0.0.1:43666_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813606186525 CREATE) 
[n:127.0.0.1:43666_solr c:localShardsTestColl s:shard2 r:core_node13 
x:localShardsTestColl_shard2_replica_n10] o.a.s.s.IndexSchema 
[localShardsTestColl_shard2_replica_n10] Schema name=test
   [junit4]   2> 370032 INFO  
(parallelCoreAdminExecutor-397-thread-2-processing-n:127.0.0.1:46119_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813570919723 CREATE) 
[n:127.0.0.1:46119_solr c:localShardsTestColl s:shard2 r:core_node11 
x:localShardsTestColl_shard2_replica_n8] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 8.0.0
   [junit4]   2> 370267 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=8f3a014a-2757-4700-ba89-536e6de97248&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 370270 INFO  
(parallelCoreAdminExecutor-395-thread-1-processing-n:127.0.0.1:46551_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813456484650 CREATE) 
[n:127.0.0.1:46551_solr c:localShardsTestColl s:shard1 r:core_node3 
x:localShardsTestColl_shard1_replica_n1] o.a.s.s.IndexSchema 
[localShardsTestColl_shard1_replica_n1] Schema name=test
   [junit4]   2> 370271 INFO  
(parallelCoreAdminExecutor-395-thread-3-processing-n:127.0.0.1:46551_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813608772070 CREATE) 
[n:127.0.0.1:46551_solr c:localShardsTestColl s:shard3 r:core_node15 
x:localShardsTestColl_shard3_replica_n12] o.a.s.s.IndexSchema 
[localShardsTestColl_shard3_replica_n12] Schema name=test
   [junit4]   2> 370343 INFO  
(parallelCoreAdminExecutor-397-thread-1-processing-n:127.0.0.1:46119_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813476637547 CREATE) 
[n:127.0.0.1:46119_solr c:localShardsTestColl s:shard1 r:core_node5 
x:localShardsTestColl_shard1_replica_n2] o.a.s.s.IndexSchema 
[localShardsTestColl_shard1_replica_n2] Schema name=test
   [junit4]   2> 370350 INFO  
(parallelCoreAdminExecutor-395-thread-2-processing-n:127.0.0.1:46551_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813535570496 CREATE) 
[n:127.0.0.1:46551_solr c:localShardsTestColl s:shard2 r:core_node9 
x:localShardsTestColl_shard2_replica_n6] o.a.s.s.IndexSchema 
[localShardsTestColl_shard2_replica_n6] Schema name=test
   [junit4]   2> 370356 INFO  (qtp1791219700-1049) [n:127.0.0.1:46119_solr    ] 
o.a.s.h.a.CoreAdminOperation Checking request status for : 
8f3a014a-2757-4700-ba89-536e6de9724811592813645127851
   [junit4]   2> 370356 INFO  (qtp1791219700-1049) [n:127.0.0.1:46119_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=8f3a014a-2757-4700-ba89-536e6de9724811592813645127851&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 370383 INFO  
(parallelCoreAdminExecutor-397-thread-2-processing-n:127.0.0.1:46119_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813570919723 CREATE) 
[n:127.0.0.1:46119_solr c:localShardsTestColl s:shard2 r:core_node11 
x:localShardsTestColl_shard2_replica_n8] o.a.s.s.IndexSchema 
[localShardsTestColl_shard2_replica_n8] Schema name=test
   [junit4]   2> 370400 INFO  (qtp178876608-1064) [n:127.0.0.1:43666_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=8f3a014a-2757-4700-ba89-536e6de97248&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=133
   [junit4]   2> 370748 INFO  
(parallelCoreAdminExecutor-397-thread-3-processing-n:127.0.0.1:46119_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813645127851 CREATE) 
[n:127.0.0.1:46119_solr c:localShardsTestColl s:shard3 r:core_node17 
x:localShardsTestColl_shard3_replica_n14] o.a.s.s.IndexSchema 
[localShardsTestColl_shard3_replica_n14] Schema name=test
   [junit4]   2> 371414 INFO  (qtp178876608-1063) [n:127.0.0.1:43666_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=8f3a014a-2757-4700-ba89-536e6de97248&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 371415 INFO  (qtp178876608-1063) [n:127.0.0.1:43666_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=8f3a014a-2757-4700-ba89-536e6de97248&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 371454 INFO  (qtp1791219700-1049) [n:127.0.0.1:46119_solr    ] 
o.a.s.h.a.CoreAdminOperation Checking request status for : 
8f3a014a-2757-4700-ba89-536e6de9724811592813645127851
   [junit4]   2> 371455 INFO  (qtp1791219700-1049) [n:127.0.0.1:46119_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=8f3a014a-2757-4700-ba89-536e6de9724811592813645127851&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 371478 WARN  
(parallelCoreAdminExecutor-396-thread-1-processing-n:127.0.0.1:43666_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813532924472 CREATE) 
[n:127.0.0.1:43666_solr c:localShardsTestColl s:shard1 r:core_node7 
x:localShardsTestColl_shard1_replica_n4] o.a.s.s.IndexSchema Field 
lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
   [junit4]   2> 371478 WARN  
(parallelCoreAdminExecutor-396-thread-1-processing-n:127.0.0.1:43666_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813532924472 CREATE) 
[n:127.0.0.1:43666_solr c:localShardsTestColl s:shard1 r:core_node7 
x:localShardsTestColl_shard1_replica_n4] o.a.s.s.IndexSchema Field text is not 
multivalued and destination for multiple copyFields (3)
   [junit4]   2> 371566 INFO  
(parallelCoreAdminExecutor-396-thread-1-processing-n:127.0.0.1:43666_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813532924472 CREATE) 
[n:127.0.0.1:43666_solr c:localShardsTestColl s:shard1 r:core_node7 
x:localShardsTestColl_shard1_replica_n4] o.a.s.s.IndexSchema Loaded schema 
test/1.6 with uniqueid field id
   [junit4]   2> 371567 INFO  
(parallelCoreAdminExecutor-396-thread-1-processing-n:127.0.0.1:43666_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813532924472 CREATE) 
[n:127.0.0.1:43666_solr c:localShardsTestColl s:shard1 r:core_node7 
x:localShardsTestColl_shard1_replica_n4] o.a.s.c.CoreContainer Creating 
SolrCore 'localShardsTestColl_shard1_replica_n4' using configuration from 
collection localShardsTestColl, trusted=true
   [junit4]   2> 371567 INFO  
(parallelCoreAdminExecutor-396-thread-1-processing-n:127.0.0.1:43666_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813532924472 CREATE) 
[n:127.0.0.1:43666_solr c:localShardsTestColl s:shard1 r:core_node7 
x:localShardsTestColl_shard1_replica_n4] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_43666.solr.core.localShardsTestColl.shard1.replica_n4' 
(registry 'solr.core.localShardsTestColl.shard1.replica_n4') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2bbf094a
   [junit4]   2> 371568 INFO  
(parallelCoreAdminExecutor-396-thread-1-processing-n:127.0.0.1:43666_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813532924472 CREATE) 
[n:127.0.0.1:43666_solr c:localShardsTestColl s:shard1 r:core_node7 
x:localShardsTestColl_shard1_replica_n4] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 371841 INFO  
(parallelCoreAdminExecutor-396-thread-1-processing-n:127.0.0.1:43666_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813532924472 CREATE) 
[n:127.0.0.1:43666_solr c:localShardsTestColl s:shard1 r:core_node7 
x:localShardsTestColl_shard1_replica_n4] o.a.s.c.SolrCore 
[[localShardsTestColl_shard1_replica_n4] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.impl.CloudSolrClientTest_7239F41862A29BC2-001/tempDir-001/node3/localShardsTestColl_shard1_replica_n4],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.impl.CloudSolrClientTest_7239F41862A29BC2-001/tempDir-001/node3/./localShardsTestColl_shard1_replica_n4/data/]
   [junit4]   2> 371821 WARN  
(parallelCoreAdminExecutor-396-thread-3-processing-n:127.0.0.1:43666_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813680517994 CREATE) 
[n:127.0.0.1:43666_solr c:localShardsTestColl s:shard3 r:core_node18 
x:localShardsTestColl_shard3_replica_n16] o.a.s.s.IndexSchema Field 
lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
   [junit4]   2> 371843 WARN  
(parallelCoreAdminExecutor-396-thread-3-processing-n:127.0.0.1:43666_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813680517994 CREATE) 
[n:127.0.0.1:43666_solr c:localShardsTestColl s:shard3 r:core_node18 
x:localShardsTestColl_shard3_replica_n16] o.a.s.s.IndexSchema Field text is not 
multivalued and destination for multiple copyFields (3)
   [junit4]   2> 371843 INFO  
(parallelCoreAdminExecutor-396-thread-3-processing-n:127.0.0.1:43666_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813680517994 CREATE) 
[n:127.0.0.1:43666_solr c:localShardsTestColl s:shard3 r:core_node18 
x:localShardsTestColl_shard3_replica_n16] o.a.s.s.IndexSchema Loaded schema 
test/1.6 with uniqueid field id
   [junit4]   2> 371843 INFO  
(parallelCoreAdminExecutor-396-thread-3-processing-n:127.0.0.1:43666_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813680517994 CREATE) 
[n:127.0.0.1:43666_solr c:localShardsTestColl s:shard3 r:core_node18 
x:localShardsTestColl_shard3_replica_n16] o.a.s.c.CoreContainer Creating 
SolrCore 'localShardsTestColl_shard3_replica_n16' using configuration from 
collection localShardsTestColl, trusted=true
   [junit4]   2> 371844 INFO  
(parallelCoreAdminExecutor-396-thread-3-processing-n:127.0.0.1:43666_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813680517994 CREATE) 
[n:127.0.0.1:43666_solr c:localShardsTestColl s:shard3 r:core_node18 
x:localShardsTestColl_shard3_replica_n16] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_43666.solr.core.localShardsTestColl.shard3.replica_n16' 
(registry 'solr.core.localShardsTestColl.shard3.replica_n16') enabled at 
server: com.sun.jmx.mbeanserver.JmxMBeanServer@2bbf094a
   [junit4]   2> 371844 INFO  
(parallelCoreAdminExecutor-396-thread-3-processing-n:127.0.0.1:43666_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813680517994 CREATE) 
[n:127.0.0.1:43666_solr c:localShardsTestColl s:shard3 r:core_node18 
x:localShardsTestColl_shard3_replica_n16] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 371844 INFO  
(parallelCoreAdminExecutor-396-thread-3-processing-n:127.0.0.1:43666_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813680517994 CREATE) 
[n:127.0.0.1:43666_solr c:localShardsTestColl s:shard3 r:core_node18 
x:localShardsTestColl_shard3_replica_n16] o.a.s.c.SolrCore 
[[localShardsTestColl_shard3_replica_n16] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.impl.CloudSolrClientTest_7239F41862A29BC2-001/tempDir-001/node3/localShardsTestColl_shard3_replica_n16],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.impl.CloudSolrClientTest_7239F41862A29BC2-001/tempDir-001/node3/./localShardsTestColl_shard3_replica_n16/data/]
   [junit4]   2> 372393 WARN  
(parallelCoreAdminExecutor-397-thread-1-processing-n:127.0.0.1:46119_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813476637547 CREATE) 
[n:127.0.0.1:46119_solr c:localShardsTestColl s:shard1 r:core_node5 
x:localShardsTestColl_shard1_replica_n2] o.a.s.s.IndexSchema Field 
lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
   [junit4]   2> 372393 WARN  
(parallelCoreAdminExecutor-397-thread-1-processing-n:127.0.0.1:46119_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813476637547 CREATE) 
[n:127.0.0.1:46119_solr c:localShardsTestColl s:shard1 r:core_node5 
x:localShardsTestColl_shard1_replica_n2] o.a.s.s.IndexSchema Field text is not 
multivalued and destination for multiple copyFields (3)
   [junit4]   2> 372393 INFO  
(parallelCoreAdminExecutor-397-thread-1-processing-n:127.0.0.1:46119_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813476637547 CREATE) 
[n:127.0.0.1:46119_solr c:localShardsTestColl s:shard1 r:core_node5 
x:localShardsTestColl_shard1_replica_n2] o.a.s.s.IndexSchema Loaded schema 
test/1.6 with uniqueid field id
   [junit4]   2> 372393 INFO  
(parallelCoreAdminExecutor-397-thread-1-processing-n:127.0.0.1:46119_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813476637547 CREATE) 
[n:127.0.0.1:46119_solr c:localShardsTestColl s:shard1 r:core_node5 
x:localShardsTestColl_shard1_replica_n2] o.a.s.c.CoreContainer Creating 
SolrCore 'localShardsTestColl_shard1_replica_n2' using configuration from 
collection localShardsTestColl, trusted=true
   [junit4]   2> 372394 INFO  
(parallelCoreAdminExecutor-397-thread-1-processing-n:127.0.0.1:46119_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813476637547 CREATE) 
[n:127.0.0.1:46119_solr c:localShardsTestColl s:shard1 r:core_node5 
x:localShardsTestColl_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_46119.solr.core.localShardsTestColl.shard1.replica_n2' 
(registry 'solr.core.localShardsTestColl.shard1.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2bbf094a
   [junit4]   2> 372394 INFO  
(parallelCoreAdminExecutor-397-thread-1-processing-n:127.0.0.1:46119_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813476637547 CREATE) 
[n:127.0.0.1:46119_solr c:localShardsTestColl s:shard1 r:core_node5 
x:localShardsTestColl_shard1_replica_n2] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 372419 INFO  (qtp178876608-1063) [n:127.0.0.1:43666_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=8f3a014a-2757-4700-ba89-536e6de97248&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 372440 INFO  (qtp178876608-1063) [n:127.0.0.1:43666_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=8f3a014a-2757-4700-ba89-536e6de97248&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=20
   [junit4]   2> 372467 INFO  (qtp1791219700-1049) [n:127.0.0.1:46119_solr    ] 
o.a.s.h.a.CoreAdminOperation Checking request status for : 
8f3a014a-2757-4700-ba89-536e6de9724811592813645127851
   [junit4]   2> 372467 INFO  (qtp1791219700-1049) [n:127.0.0.1:46119_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=8f3a014a-2757-4700-ba89-536e6de9724811592813645127851&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 372553 INFO  
(parallelCoreAdminExecutor-397-thread-1-processing-n:127.0.0.1:46119_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813476637547 CREATE) 
[n:127.0.0.1:46119_solr c:localShardsTestColl s:shard1 r:core_node5 
x:localShardsTestColl_shard1_replica_n2] o.a.s.c.SolrCore 
[[localShardsTestColl_shard1_replica_n2] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.impl.CloudSolrClientTest_7239F41862A29BC2-001/tempDir-001/node1/localShardsTestColl_shard1_replica_n2],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.impl.CloudSolrClientTest_7239F41862A29BC2-001/tempDir-001/node1/./localShardsTestColl_shard1_replica_n2/data/]
   [junit4]   2> 372582 WARN  
(parallelCoreAdminExecutor-395-thread-1-processing-n:127.0.0.1:46551_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813456484650 CREATE) 
[n:127.0.0.1:46551_solr c:localShardsTestColl s:shard1 r:core_node3 
x:localShardsTestColl_shard1_replica_n1] o.a.s.s.IndexSchema Field 
lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
   [junit4]   2> 372582 WARN  
(parallelCoreAdminExecutor-395-thread-1-processing-n:127.0.0.1:46551_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813456484650 CREATE) 
[n:127.0.0.1:46551_solr c:localShardsTestColl s:shard1 r:core_node3 
x:localShardsTestColl_shard1_replica_n1] o.a.s.s.IndexSchema Field text is not 
multivalued and destination for multiple copyFields (3)
   [junit4]   2> 372583 INFO  
(parallelCoreAdminExecutor-395-thread-1-processing-n:127.0.0.1:46551_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813456484650 CREATE) 
[n:127.0.0.1:46551_solr c:localShardsTestColl s:shard1 r:core_node3 
x:localShardsTestColl_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema 
test/1.6 with uniqueid field id
   [junit4]   2> 372583 INFO  
(parallelCoreAdminExecutor-395-thread-1-processing-n:127.0.0.1:46551_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813456484650 CREATE) 
[n:127.0.0.1:46551_solr c:localShardsTestColl s:shard1 r:core_node3 
x:localShardsTestColl_shard1_replica_n1] o.a.s.c.CoreContainer Creating 
SolrCore 'localShardsTestColl_shard1_replica_n1' using configuration from 
collection localShardsTestColl, trusted=true
   [junit4]   2> 372583 INFO  
(parallelCoreAdminExecutor-395-thread-1-processing-n:127.0.0.1:46551_solr 
8f3a014a-2757-4700-ba89-536e6de9724811592813456484650 CREATE) 
[n:127.0.0.1:46551_solr c:localShardsTestColl s:shard1 r:core_node3 
x:localShardsTestColl_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_46551.solr.core.localShardsTestColl.shard1.replica_n1' 
(registry 'solr.core.localShardsTestColl.s

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

2> 471717 INFO  (coreCloseExecutor-528-thread-9) [n:127.0.0.1:46551_solr c:foo 
s:shard1 r:core_node6 x:foo_shard1_replica_n3] o.a.s.m.SolrMetricManager 
Closing metric reporters for registry=solr.core.foo.shard1.replica_n3, 
tag=1103906192
   [junit4]   2> 471717 INFO  (coreCloseExecutor-528-thread-9) 
[n:127.0.0.1:46551_solr c:foo s:shard1 r:core_node6 x:foo_shard1_replica_n3] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@55c8c9aa: rootName = 
solr_46551, domain = solr.core.foo.shard1.replica_n3, service url = null, agent 
id = null] for registry solr.core.foo.shard1.replica_n3 / 
com.codahale.metrics.MetricRegistry@6d0078c4
   [junit4]   2> 472224 INFO  (coreCloseExecutor-527-thread-7) 
[n:127.0.0.1:46119_solr c:stale_state_test_col s:shard1 r:core_node4 
x:stale_state_test_col_shard1_replica_n3] o.a.s.m.SolrMetricManager Closing 
metric reporters for 
registry=solr.collection.stale_state_test_col.shard1.leader, tag=1817148047
   [junit4]   2> 472225 INFO  (coreCloseExecutor-527-thread-5) 
[n:127.0.0.1:46119_solr c:multicollection2 s:shard1 r:core_node3 
x:multicollection2_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric 
reporters for registry=solr.core.multicollection2.shard1.replica_n1, 
tag=752920240
   [junit4]   2> 472225 INFO  (coreCloseExecutor-528-thread-9) 
[n:127.0.0.1:46551_solr c:foo s:shard1 r:core_node6 x:foo_shard1_replica_n3] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.collection.foo.shard1.leader, tag=1103906192
   [junit4]   2> 472225 INFO  (coreCloseExecutor-528-thread-10) 
[n:127.0.0.1:46551_solr c:stale_state_test_col s:shard1 r:core_node2 
x:stale_state_test_col_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing 
metric reporters for registry=solr.core.stale_state_test_col.shard1.replica_n1, 
tag=518552566
   [junit4]   2> 472225 INFO  (coreCloseExecutor-528-thread-10) 
[n:127.0.0.1:46551_solr c:stale_state_test_col s:shard1 r:core_node2 
x:stale_state_test_col_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing 
reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@272663cc: rootName 
= solr_46551, domain = solr.core.stale_state_test_col.shard1.replica_n1, 
service url = null, agent id = null] for registry 
solr.core.stale_state_test_col.shard1.replica_n1 / 
com.codahale.metrics.MetricRegistry@79a69d39
   [junit4]   2> 472371 INFO  (coreCloseExecutor-528-thread-10) 
[n:127.0.0.1:46551_solr c:stale_state_test_col s:shard1 r:core_node2 
x:stale_state_test_col_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing 
metric reporters for 
registry=solr.collection.stale_state_test_col.shard1.leader, tag=518552566
   [junit4]   2> 472371 INFO  (coreCloseExecutor-528-thread-1) 
[n:127.0.0.1:46551_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=1021929283
   [junit4]   2> 472372 INFO  (coreCloseExecutor-528-thread-6) 
[n:127.0.0.1:46551_solr c:multicollection1 s:shard1 r:core_node2 
x:multicollection1_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric 
reporters for registry=solr.collection.multicollection1.shard1.leader, 
tag=1555206564
   [junit4]   2> 472372 INFO  (coreCloseExecutor-528-thread-5) 
[n:127.0.0.1:46551_solr c:2nd_collection s:shard1 r:core_node3 
x:2nd_collection_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric 
reporters for registry=solr.collection.2nd_collection.shard1.leader, 
tag=517480304
   [junit4]   2> 472372 INFO  (coreCloseExecutor-528-thread-7) 
[n:127.0.0.1:46551_solr c:multicollection2 s:shard2 r:core_node4 
x:multicollection2_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric 
reporters for registry=solr.collection.multicollection2.shard2.leader, 
tag=1576604447
   [junit4]   2> 472454 INFO  (coreCloseExecutor-527-thread-5) 
[n:127.0.0.1:46119_solr c:multicollection2 s:shard1 r:core_node3 
x:multicollection2_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing 
reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5b52e82e: rootName 
= solr_46119, domain = solr.core.multicollection2.shard1.replica_n1, service 
url = null, agent id = null] for registry 
solr.core.multicollection2.shard1.replica_n1 / 
com.codahale.metrics.MetricRegistry@1cc60dcb
   [junit4]   2> 472609 INFO  (coreCloseExecutor-527-thread-5) 
[n:127.0.0.1:46119_solr c:multicollection2 s:shard1 r:core_node3 
x:multicollection2_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric 
reporters for registry=solr.collection.multicollection2.shard1.leader, 
tag=752920240
   [junit4]   2> 472621 INFO  (coreCloseExecutor-527-thread-4) 
[n:127.0.0.1:46119_solr c:2nd_collection s:shard2 r:core_node4 
x:2nd_collection_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric 
reporters for registry=solr.collection.2nd_collection.shard2.leader, 
tag=1358632072
   [junit4]   2> 472809 INFO  (jetty-closer-201-thread-3) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@6bacd553{/solr,null,UNAVAILABLE}
   [junit4]   2> 473590 INFO  (jetty-closer-201-thread-2) [    ] 
o.a.s.c.Overseer Overseer 
(id=99056582092587015-127.0.0.1:46119_solr-n_0000000000) closing
   [junit4]   2> 473598 INFO  
(OverseerStateUpdate-99056582092587015-127.0.0.1:46119_solr-n_0000000000) 
[n:127.0.0.1:46119_solr    ] o.a.s.c.Overseer Overseer Loop exiting : 
127.0.0.1:46119_solr
   [junit4]   2> 473736 WARN  
(OverseerAutoScalingTriggerThread-99056582092587015-127.0.0.1:46119_solr-n_0000000000)
 [n:127.0.0.1:46119_solr    ] o.a.s.c.a.OverseerTriggerThread 
OverseerTriggerThread woken up but we are closed, exiting.
   [junit4]   2> 473871 INFO  (jetty-closer-201-thread-1) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@32b4ea85{/solr,null,UNAVAILABLE}
   [junit4]   2> 474164 INFO  (jetty-closer-201-thread-2) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@590901b2{/solr,null,UNAVAILABLE}
   [junit4]   2> 474435 ERROR 
(SUITE-CloudSolrClientTest-seed#[7239F41862A29BC2]-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> 474435 INFO  
(SUITE-CloudSolrClientTest-seed#[7239F41862A29BC2]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:46538 46538
   [junit4]   2> 474567 INFO  (Thread-243) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:46538 46538
   [junit4]   2> 474568 WARN  (Thread-243) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        9       /solr/clusterprops.json
   [junit4]   2>        9       /solr/aliases.json
   [junit4]   2>        3       /solr/security.json
   [junit4]   2>        3       /solr/configs/conf
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        36      /solr/collections/localShardsTestColl/state.json
   [junit4]   2>        24      /solr/collections/foo/state.json
   [junit4]   2>        18      /solr/collections/multicollection1/state.json
   [junit4]   2>        16      
/solr/collections/stale_state_test_col/state.json
   [junit4]   2>        16      /solr/collections/collection1/state.json
   [junit4]   2>        14      /solr/collections/2nd_collection/state.json
   [junit4]   2>        14      /solr/collections/multicollection2/state.json
   [junit4]   2>        12      /solr/collections/nemesis/state.json
   [junit4]   2>        9       /solr/clusterstate.json
   [junit4]   2>        6       /solr/collections/overwrite/state.json
   [junit4]   2>        2       
/solr/collections/foo/leader_elect/shard1/election/99056582092587015-core_node5-n_0000000000
   [junit4]   2>        2       
/solr/overseer_elect/election/99056582092587015-127.0.0.1:46119_solr-n_0000000000
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        9       /solr/live_nodes
   [junit4]   2>        9       /solr/collections
   [junit4]   2> 
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.impl.CloudSolrClientTest_7239F41862A29BC2-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): 
{multiDefault=PostingsFormat(name=Direct), a_t=PostingsFormat(name=Direct), 
title_s=PostingsFormat(name=Direct), id=PostingsFormat(name=Memory), 
text=FST50}, docValues:{_version_=DocValuesFormat(name=Direct), 
multiDefault=DocValuesFormat(name=Asserting), 
title_s=DocValuesFormat(name=Asserting), 
intDefault=DocValuesFormat(name=Direct), id=DocValuesFormat(name=Lucene70), 
timestamp=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=250, 
maxMBSortInHeap=6.97257538902785, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@5f074a79),
 locale=it-IT, timezone=Brazil/West
   [junit4]   2> NOTE: Linux 4.4.0-83-generic amd64/Oracle Corporation 
1.8.0_152 (64-bit)/cpus=4,threads=1,free=266870576,total=485490688
   [junit4]   2> NOTE: All tests run in this JVM: [ModuloEvaluatorTest, 
TestDocumentObjectBinder, TestSolrProperties, HyperbolicCosineEvaluatorTest, 
OrEvaluatorTest, StreamExpressionToExpessionTest, 
CloudSolrClientMultiConstructorTest, UniformDistributionEvaluatorTest, 
JDBCStreamTest, AbsoluteValueEvaluatorTest, FacetFieldTest, 
ConcurrentUpdateSolrClientTest, TestDelegationTokenResponse, JsonValidatorTest, 
TestLBHttpSolrClient, BasicHttpSolrClientTest, CeilingEvaluatorTest, 
TestUpdateRequestCodec, QueryResponseTest, CumulativeProbabilityEvaluatorTest, 
SolrPingTest, SubtractEvaluatorTest, CorrelationEvaluatorTest, 
SolrExampleStreamingTest, SolrExampleStreamingBinaryTest, SolrExampleJettyTest, 
SolrExampleEmbeddedTest, MergeIndexesEmbeddedTest, SolrQueryTest, 
ContentStreamTest, ModifiableSolrParamsTest, TestFastInputStream, 
DocumentAnalysisResponseTest, ClientUtilsTest, UsingSolrJRefGuideExamplesTest, 
CloudSolrClientBuilderTest, CloudSolrClientTest]
   [junit4] Completed [127/146 (1!)] on J1 in 121.25s, 16 tests, 1 error <<< 
FAILURES!

[...truncated 46397 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to