You are viewing a plain text version of this content. The canonical link for it is here.
Posted to commits@solr.apache.org by is...@apache.org on 2022/10/13 07:12:33 UTC
[solr] branch jira/solr-16640 updated: More log
This is an automated email from the ASF dual-hosted git repository.
ishan pushed a commit to branch jira/solr-16640
in repository https://gitbox.apache.org/repos/asf/solr.git
The following commit(s) were added to refs/heads/jira/solr-16640 by this push:
new ab9326850ce More log
ab9326850ce is described below
commit ab9326850ceb8734a9b2dce062c1da6c49f5b630
Author: Ishan Chattopadhyaya <is...@apache.org>
AuthorDate: Thu Oct 13 12:42:22 2022 +0530
More log
---
.../solr/cloud/RefreshCollectionMessage.java | 4 +-
solr/core/tmp | 1023 ++++++++++++++++++++
2 files changed, 1026 insertions(+), 1 deletion(-)
diff --git a/solr/core/src/java/org/apache/solr/cloud/RefreshCollectionMessage.java b/solr/core/src/java/org/apache/solr/cloud/RefreshCollectionMessage.java
index 3de1a5f5566..d58fbaf0480 100644
--- a/solr/core/src/java/org/apache/solr/cloud/RefreshCollectionMessage.java
+++ b/solr/core/src/java/org/apache/solr/cloud/RefreshCollectionMessage.java
@@ -56,7 +56,9 @@ public class RefreshCollectionMessage implements Overseer.Message {
overseer.getZkStateReader().forceUpdateCollection(collection);
coll = overseer.getZkStateReader().getCollectionLive(collection);
log.info("getCollectionLive coll {}", coll);
- return clusterState.copyWith(collection, coll);
+ ClusterState copiedCS = clusterState.copyWith(collection, coll);
+ log.info("copied CS {}", copiedCS);
+ return copiedCS;
}
}
}
diff --git a/solr/core/tmp b/solr/core/tmp
new file mode 100644
index 00000000000..58c55d05f55
--- /dev/null
+++ b/solr/core/tmp
@@ -0,0 +1,1023 @@
+/usr/lib/jvm/java-11/bin/java -ea -Didea.test.cyclic.buffer.size=1048576 -javaagent:/opt/intellij-idea-community/lib/idea_rt.jar=44477:/opt/intellij-idea-community/bin -Dfile.encoding=UTF-8 -classpath /opt/intellij-idea-community/lib/idea_rt.jar:/opt/intellij-idea-community/plugins/junit/lib/junit5-rt.jar:/opt/intellij-idea-community/plugins/junit/lib/junit-rt.jar:/home/ishan/code/solr/out/test/solrj:/home/ishan/code/solr/out/production/solrj:/home/ishan/.gradle/caches/modules-2/files-2. [...]
+1566 INFO (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/ishan/code/solr/./solr/server/solr/configsets/_default/conf'
+1579 INFO (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.u.ErrorLogMuter Creating ErrorLogMuter-regex-1 for ERROR logs matching regex: ignore_exception
+1591 INFO (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.SolrTestCaseJ4 Created dataDir: /tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/data-dir-1-001
+1633 INFO (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
+1651 INFO (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.e.j.u.log Logging initialized @1680ms to org.eclipse.jetty.util.log.Slf4jLog
+1654 INFO (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
+1666 INFO (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=null
+1666 WARN (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.SolrTestCaseJ4 System property java.security.egd was not set by test runner, forcibly set to expected: file:/dev/./urandom
+1678 INFO (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in /tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001
+1683 INFO (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
+1684 INFO (ZkTestServer Run Thread) [] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
+1684 INFO (ZkTestServer Run Thread) [] o.a.s.c.ZkTestServer Starting server
+1708 WARN (ZkTestServer Run Thread) [] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
+1784 INFO (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.c.ZkTestServer start zk server on port: 40675
+1841 WARN (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.c.c.SolrZkClient Using default ZkCredentialsInjector. ZkCredentialsInjector is not secure, it creates an empty list of credentials which leads to 'OPEN_ACL_UNSAFE' ACLs to Zookeeper nodes
+1899 INFO (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.c.c.ConnectionManager Waiting up to 30000ms for client to connect to ZooKeeper
+1962 INFO (zkConnectionManagerCallback-3-thread-1) [] o.a.s.c.c.ConnectionManager zkClient has connected
+1962 INFO (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
+1963 WARN (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.c.c.SolrZkClient Using default ZkACLProvider. DefaultZkACLProvider is not secure, it creates 'OPEN_ACL_UNSAFE' ACLs to Zookeeper nodes
+2013 WARN (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.c.c.SolrZkClient Using default ZkCredentialsInjector. ZkCredentialsInjector is not secure, it creates an empty list of credentials which leads to 'OPEN_ACL_UNSAFE' ACLs to Zookeeper nodes
+2017 INFO (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.c.c.ConnectionManager Waiting up to 30000ms for client to connect to ZooKeeper
+2027 INFO (zkConnectionManagerCallback-5-thread-1) [] o.a.s.c.c.ConnectionManager zkClient has connected
+2029 INFO (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
+2029 WARN (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.c.c.SolrZkClient Using default ZkACLProvider. DefaultZkACLProvider is not secure, it creates 'OPEN_ACL_UNSAFE' ACLs to Zookeeper nodes
+2030 WARN (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.c.c.SolrZkClient Using default ZkCredentialsInjector. ZkCredentialsInjector is not secure, it creates an empty list of credentials which leads to 'OPEN_ACL_UNSAFE' ACLs to Zookeeper nodes
+2033 INFO (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.c.c.ConnectionManager Waiting up to 30000ms for client to connect to ZooKeeper
+2045 INFO (zkConnectionManagerCallback-7-thread-1) [] o.a.s.c.c.ConnectionManager zkClient has connected
+2045 INFO (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
+2046 WARN (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.c.c.SolrZkClient Using default ZkACLProvider. DefaultZkACLProvider is not secure, it creates 'OPEN_ACL_UNSAFE' ACLs to Zookeeper nodes
+2301 INFO (jetty-launcher-8-thread-1) [] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
+2301 INFO (jetty-launcher-8-thread-2) [] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
+2301 INFO (jetty-launcher-8-thread-3) [] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
+2303 INFO (jetty-launcher-8-thread-2) [] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
+2303 INFO (jetty-launcher-8-thread-1) [] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
+2303 INFO (jetty-launcher-8-thread-3) [] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
+2306 INFO (jetty-launcher-8-thread-2) [] o.e.j.s.Server jetty-9.4.48.v20220622; built: 2022-06-21T20:42:25.880Z; git: 6b67c5719d1f4371b33655ff2d047d24e171e49a; jvm 11.0.15+10
+2306 INFO (jetty-launcher-8-thread-3) [] o.e.j.s.Server jetty-9.4.48.v20220622; built: 2022-06-21T20:42:25.880Z; git: 6b67c5719d1f4371b33655ff2d047d24e171e49a; jvm 11.0.15+10
+2306 INFO (jetty-launcher-8-thread-1) [] o.e.j.s.Server jetty-9.4.48.v20220622; built: 2022-06-21T20:42:25.880Z; git: 6b67c5719d1f4371b33655ff2d047d24e171e49a; jvm 11.0.15+10
+2313 INFO (jetty-launcher-8-thread-3) [] o.e.j.s.session DefaultSessionIdManager workerName=node0
+2314 INFO (jetty-launcher-8-thread-3) [] o.e.j.s.session No SessionScavenger set, using defaults
+2315 INFO (jetty-launcher-8-thread-3) [] o.e.j.s.session node0 Scavenging every 600000ms
+2315 INFO (jetty-launcher-8-thread-1) [] o.e.j.s.session DefaultSessionIdManager workerName=node0
+2316 INFO (jetty-launcher-8-thread-1) [] o.e.j.s.session No SessionScavenger set, using defaults
+2316 INFO (jetty-launcher-8-thread-2) [] o.e.j.s.session DefaultSessionIdManager workerName=node0
+2316 INFO (jetty-launcher-8-thread-2) [] o.e.j.s.session No SessionScavenger set, using defaults
+2316 INFO (jetty-launcher-8-thread-2) [] o.e.j.s.session node0 Scavenging every 660000ms
+2316 INFO (jetty-launcher-8-thread-1) [] o.e.j.s.session node0 Scavenging every 660000ms
+2323 INFO (jetty-launcher-8-thread-1) [] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@68295695{/solr,null,AVAILABLE}
+2323 INFO (jetty-launcher-8-thread-3) [] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@27cb97fb{/solr,null,AVAILABLE}
+2324 INFO (jetty-launcher-8-thread-2) [] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4483d8f2{/solr,null,AVAILABLE}
+2331 INFO (jetty-launcher-8-thread-1) [] o.e.j.s.AbstractConnector Started ServerConnector@7d997795{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:45559}
+2331 INFO (jetty-launcher-8-thread-1) [] o.e.j.s.Server Started @2362ms
+2331 INFO (jetty-launcher-8-thread-2) [] o.e.j.s.AbstractConnector Started ServerConnector@5673084c{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:36493}
+2331 INFO (jetty-launcher-8-thread-2) [] o.e.j.s.Server Started @2362ms
+2331 INFO (jetty-launcher-8-thread-3) [] o.e.j.s.AbstractConnector Started ServerConnector@7fabc003{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:43371}
+2331 INFO (jetty-launcher-8-thread-3) [] o.e.j.s.Server Started @2362ms
+2356 ERROR (jetty-launcher-8-thread-3) [] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
+2356 ERROR (jetty-launcher-8-thread-1) [] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
+2357 INFO (jetty-launcher-8-thread-3) [] o.a.s.s.CoreContainerProvider Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
+2356 ERROR (jetty-launcher-8-thread-2) [] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
+2357 INFO (jetty-launcher-8-thread-2) [] o.a.s.s.CoreContainerProvider Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
+2357 INFO (jetty-launcher-8-thread-1) [] o.a.s.s.CoreContainerProvider Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
+2362 INFO (jetty-launcher-8-thread-2) [] o.a.s.s.CoreContainerProvider ___ _ Welcome to Apache Solr™ version 9.1.0
+2362 INFO (jetty-launcher-8-thread-3) [] o.a.s.s.CoreContainerProvider ___ _ Welcome to Apache Solr™ version 9.1.0
+2362 INFO (jetty-launcher-8-thread-1) [] o.a.s.s.CoreContainerProvider ___ _ Welcome to Apache Solr™ version 9.1.0
+2362 INFO (jetty-launcher-8-thread-2) [] o.a.s.s.CoreContainerProvider / __| ___| |_ _ Starting in cloud mode on port null
+2363 INFO (jetty-launcher-8-thread-3) [] o.a.s.s.CoreContainerProvider / __| ___| |_ _ Starting in cloud mode on port null
+2363 INFO (jetty-launcher-8-thread-2) [] o.a.s.s.CoreContainerProvider \__ \/ _ \ | '_| Install dir: /home/ishan/code/solr/./solr
+2363 INFO (jetty-launcher-8-thread-3) [] o.a.s.s.CoreContainerProvider \__ \/ _ \ | '_| Install dir: /home/ishan/code/solr/./solr
+2363 INFO (jetty-launcher-8-thread-2) [] o.a.s.s.CoreContainerProvider |___/\___/_|_| Start time: 2022-10-11T16:45:02.885205Z
+2363 INFO (jetty-launcher-8-thread-3) [] o.a.s.s.CoreContainerProvider |___/\___/_|_| Start time: 2022-10-11T16:45:02.885283Z
+2363 INFO (jetty-launcher-8-thread-3) [] o.a.s.s.CoreContainerProvider Solr Home: /tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001/node3 (source: servlet config: solr.solr.home)
+2362 INFO (jetty-launcher-8-thread-1) [] o.a.s.s.CoreContainerProvider / __| ___| |_ _ Starting in cloud mode on port null
+2363 INFO (jetty-launcher-8-thread-2) [] o.a.s.s.CoreContainerProvider Solr Home: /tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001/node2 (source: servlet config: solr.solr.home)
+2363 INFO (jetty-launcher-8-thread-1) [] o.a.s.s.CoreContainerProvider \__ \/ _ \ | '_| Install dir: /home/ishan/code/solr/./solr
+2363 INFO (jetty-launcher-8-thread-1) [] o.a.s.s.CoreContainerProvider |___/\___/_|_| Start time: 2022-10-11T16:45:02.885841Z
+2364 INFO (jetty-launcher-8-thread-1) [] o.a.s.s.CoreContainerProvider Solr Home: /tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001/node1 (source: servlet config: solr.solr.home)
+2364 WARN (jetty-launcher-8-thread-3) [] o.a.s.c.c.SolrZkClient Using default ZkCredentialsInjector. ZkCredentialsInjector is not secure, it creates an empty list of credentials which leads to 'OPEN_ACL_UNSAFE' ACLs to Zookeeper nodes
+2364 WARN (jetty-launcher-8-thread-2) [] o.a.s.c.c.SolrZkClient Using default ZkCredentialsInjector. ZkCredentialsInjector is not secure, it creates an empty list of credentials which leads to 'OPEN_ACL_UNSAFE' ACLs to Zookeeper nodes
+2364 WARN (jetty-launcher-8-thread-1) [] o.a.s.c.c.SolrZkClient Using default ZkCredentialsInjector. ZkCredentialsInjector is not secure, it creates an empty list of credentials which leads to 'OPEN_ACL_UNSAFE' ACLs to Zookeeper nodes
+2365 INFO (jetty-launcher-8-thread-3) [] o.a.s.c.c.ConnectionManager Waiting up to 30000ms for client to connect to ZooKeeper
+2374 INFO (jetty-launcher-8-thread-2) [] o.a.s.c.c.ConnectionManager Waiting up to 30000ms for client to connect to ZooKeeper
+2375 INFO (jetty-launcher-8-thread-1) [] o.a.s.c.c.ConnectionManager Waiting up to 30000ms for client to connect to ZooKeeper
+2377 INFO (zkConnectionManagerCallback-14-thread-1) [] o.a.s.c.c.ConnectionManager zkClient has connected
+2377 INFO (jetty-launcher-8-thread-2) [] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
+2377 INFO (zkConnectionManagerCallback-12-thread-1) [] o.a.s.c.c.ConnectionManager zkClient has connected
+2377 WARN (jetty-launcher-8-thread-2) [] o.a.s.c.c.SolrZkClient Using default ZkACLProvider. DefaultZkACLProvider is not secure, it creates 'OPEN_ACL_UNSAFE' ACLs to Zookeeper nodes
+2377 INFO (zkConnectionManagerCallback-13-thread-1) [] o.a.s.c.c.ConnectionManager zkClient has connected
+2377 INFO (jetty-launcher-8-thread-1) [] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
+2377 INFO (jetty-launcher-8-thread-3) [] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
+2377 WARN (jetty-launcher-8-thread-1) [] o.a.s.c.c.SolrZkClient Using default ZkACLProvider. DefaultZkACLProvider is not secure, it creates 'OPEN_ACL_UNSAFE' ACLs to Zookeeper nodes
+2377 WARN (jetty-launcher-8-thread-3) [] o.a.s.c.c.SolrZkClient Using default ZkACLProvider. DefaultZkACLProvider is not secure, it creates 'OPEN_ACL_UNSAFE' ACLs to Zookeeper nodes
+2379 INFO (jetty-launcher-8-thread-1) [] o.a.s.c.NodeConfig solr.xml found in ZooKeeper. Loading...
+2379 INFO (jetty-launcher-8-thread-3) [] o.a.s.c.NodeConfig solr.xml found in ZooKeeper. Loading...
+2380 INFO (jetty-launcher-8-thread-2) [] o.a.s.c.NodeConfig solr.xml found in ZooKeeper. Loading...
+2421 WARN (jetty-launcher-8-thread-3-SendThread(127.0.0.1:40675)) [] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10000f8ef5c0005.
+ => EndOfStreamException: Unable to read additional data from server sessionid 0x10000f8ef5c0005, likely server has closed socket
+ at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
+org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10000f8ef5c0005, likely server has closed socket
+ at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.8.0.jar:3.8.0]
+ at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.8.0.jar:3.8.0]
+ at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1282) ~[zookeeper-3.8.0.jar:3.8.0]
+2421 WARN (jetty-launcher-8-thread-1-SendThread(127.0.0.1:40675)) [] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10000f8ef5c0004.
+ => EndOfStreamException: Unable to read additional data from server sessionid 0x10000f8ef5c0004, likely server has closed socket
+ at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
+org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10000f8ef5c0004, likely server has closed socket
+ at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.8.0.jar:3.8.0]
+ at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.8.0.jar:3.8.0]
+ at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1282) ~[zookeeper-3.8.0.jar:3.8.0]
+2802 INFO (jetty-launcher-8-thread-1) [] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40675/solr
+2802 INFO (jetty-launcher-8-thread-3) [] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40675/solr
+2802 INFO (jetty-launcher-8-thread-2) [] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40675/solr
+2819 INFO (jetty-launcher-8-thread-3) [] o.a.s.c.DistributedClusterStateUpdater Creating DistributedClusterStateUpdater with useDistributedStateUpdate=true. Solr will be using distributed cluster state updates.
+2819 INFO (jetty-launcher-8-thread-1) [] o.a.s.c.DistributedClusterStateUpdater Creating DistributedClusterStateUpdater with useDistributedStateUpdate=true. Solr will be using distributed cluster state updates.
+2819 INFO (jetty-launcher-8-thread-2) [] o.a.s.c.DistributedClusterStateUpdater Creating DistributedClusterStateUpdater with useDistributedStateUpdate=true. Solr will be using distributed cluster state updates.
+2824 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:36493_solr] o.a.s.c.c.ConnectionManager Waiting up to 30000ms for client to connect to ZooKeeper
+2824 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:43371_solr] o.a.s.c.c.ConnectionManager Waiting up to 30000ms for client to connect to ZooKeeper
+2824 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:45559_solr] o.a.s.c.c.ConnectionManager Waiting up to 30000ms for client to connect to ZooKeeper
+2827 INFO (zkConnectionManagerCallback-42-thread-1) [] o.a.s.c.c.ConnectionManager zkClient has connected
+2827 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:36493_solr] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
+2828 INFO (zkConnectionManagerCallback-41-thread-1) [] o.a.s.c.c.ConnectionManager zkClient has connected
+2828 INFO (zkConnectionManagerCallback-44-thread-1) [] o.a.s.c.c.ConnectionManager zkClient has connected
+2828 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:45559_solr] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
+2828 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:43371_solr] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
+2933 WARN (jetty-launcher-8-thread-3) [n:127.0.0.1:43371_solr] o.a.s.c.ZkController Contents of zookeeper /security.json are world-readable; consider setting up ACLs as described in https://solr.apache.org/guide/solr/latest/deployment-guide/zookeeper-access-control.html
+2933 WARN (jetty-launcher-8-thread-2) [n:127.0.0.1:36493_solr] o.a.s.c.ZkController Contents of zookeeper /security.json are world-readable; consider setting up ACLs as described in https://solr.apache.org/guide/solr/latest/deployment-guide/zookeeper-access-control.html
+2934 WARN (jetty-launcher-8-thread-1) [n:127.0.0.1:45559_solr] o.a.s.c.ZkController Contents of zookeeper /security.json are world-readable; consider setting up ACLs as described in https://solr.apache.org/guide/solr/latest/deployment-guide/zookeeper-access-control.html
+2975 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:45559_solr] o.a.s.c.DistributedClusterStateUpdater Creating DistributedClusterStateUpdater with useDistributedStateUpdate=true. Solr will be using distributed cluster state updates.
+2975 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:43371_solr] o.a.s.c.DistributedClusterStateUpdater Creating DistributedClusterStateUpdater with useDistributedStateUpdate=true. Solr will be using distributed cluster state updates.
+2975 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:36493_solr] o.a.s.c.DistributedClusterStateUpdater Creating DistributedClusterStateUpdater with useDistributedStateUpdate=true. Solr will be using distributed cluster state updates.
+3010 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:45559_solr] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:45559_solr
+3014 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:43371_solr] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43371_solr
+3015 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:36493_solr] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36493_solr
+3015 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:45559_solr] o.a.s.c.Overseer Overseer (id=72058663205601288-127.0.0.1:45559_solr-n_0000000000) starting
+3033 INFO (zkCallback-39-thread-1) [] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
+3034 INFO (zkCallback-43-thread-1) [] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
+3034 INFO (zkCallback-40-thread-1) [] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
+3074 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:43371_solr] o.a.s.p.PackageLoader /packages.json updated to version -1
+3077 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:36493_solr] o.a.s.p.PackageLoader /packages.json updated to version -1
+3091 WARN (jetty-launcher-8-thread-3) [n:127.0.0.1:43371_solr] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
+3092 WARN (jetty-launcher-8-thread-2) [n:127.0.0.1:36493_solr] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
+3131 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:45559_solr] o.a.s.c.ZkController Publish node=127.0.0.1:45559_solr as DOWN
+3148 INFO (OverseerStateUpdate-72058663205601288-127.0.0.1:45559_solr-n_0000000000) [n:127.0.0.1:45559_solr] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:45559_solr
+3151 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:45559_solr] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 2147483647 cores with initial capacity of 1024
+3164 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:45559_solr] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45559_solr
+3169 INFO (zkCallback-43-thread-1) [] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
+3169 INFO (zkCallback-40-thread-1) [] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
+3170 INFO (zkCallback-39-thread-1) [] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
+3180 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:45559_solr] o.a.s.p.PackageLoader /packages.json updated to version -1
+3182 WARN (jetty-launcher-8-thread-1) [n:127.0.0.1:45559_solr] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
+3535 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:43371_solr] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001/node3
+3535 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:36493_solr] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001/node2
+3538 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:45559_solr] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001/node1
+3586 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:36493_solr] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=36493, zkHost=127.0.0.1:40675/solr}
+3586 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:45559_solr] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45559, zkHost=127.0.0.1:40675/solr}
+3587 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:43371_solr] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=43371, zkHost=127.0.0.1:40675/solr}
+3597 INFO (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=3
+3597 INFO (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.c.MiniSolrCloudCluster waitForNode: 127.0.0.1:36493_solr
+3598 WARN (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.c.c.SolrZkClient Using default ZkCredentialsInjector. ZkCredentialsInjector is not secure, it creates an empty list of credentials which leads to 'OPEN_ACL_UNSAFE' ACLs to Zookeeper nodes
+3598 INFO (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.c.c.ConnectionManager Waiting up to 15000ms for client to connect to ZooKeeper
+3600 INFO (zkConnectionManagerCallback-64-thread-1) [] o.a.s.c.c.ConnectionManager zkClient has connected
+3601 INFO (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
+3601 WARN (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.c.c.SolrZkClient Using default ZkACLProvider. DefaultZkACLProvider is not secure, it creates 'OPEN_ACL_UNSAFE' ACLs to Zookeeper nodes
+3602 INFO (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
+3603 INFO (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:40675/solr ready
+3604 INFO (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.c.MiniSolrCloudCluster waitForNode: 127.0.0.1:45559_solr
+3604 INFO (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.c.MiniSolrCloudCluster waitForNode: 127.0.0.1:43371_solr
+3604 WARN (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.c.c.SolrZkClient Using default ZkCredentialsInjector. ZkCredentialsInjector is not secure, it creates an empty list of credentials which leads to 'OPEN_ACL_UNSAFE' ACLs to Zookeeper nodes
+3605 INFO (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.c.c.ConnectionManager Waiting up to 45000ms for client to connect to ZooKeeper
+3608 INFO (zkConnectionManagerCallback-66-thread-1) [] o.a.s.c.c.ConnectionManager zkClient has connected
+3608 INFO (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
+3608 WARN (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.c.c.SolrZkClient Using default ZkACLProvider. DefaultZkACLProvider is not secure, it creates 'OPEN_ACL_UNSAFE' ACLs to Zookeeper nodes
+4023 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=30
+
+Test ignored.
+
+Test ignored.
+
+Test ignored.
+
+Test ignored.
+
+Test ignored.
+
+Test ignored.
+
+Test ignored.
+
+Test ignored.
+
+Test ignored.
+
+Test ignored.
+
+Test ignored.
+
+Test ignored.
+4064 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.a.s.SolrTestCaseJ4 ###Starting testPerReplicaStateCollection
+4080 INFO (OverseerThreadFactory-53-thread-1) [] o.a.s.c.a.c.CreateCollectionCmd Create collection testPerReplicaStateCollection
+4282 INFO (OverseerThreadFactory-53-thread-1) [] o.a.s.c.o.SliceMutator createReplica() {
+ "core":"testPerReplicaStateCollection_shard2_replica_n1",
+ "node_name":"127.0.0.1:36493_solr",
+ "base_url":"http://127.0.0.1:36493/solr",
+ "collection":"testPerReplicaStateCollection",
+ "shard":"shard2",
+ "state":"down",
+ "type":"NRT",
+ "operation":"ADDREPLICA",
+ "waitForFinalState":"false"}
+4295 INFO (OverseerThreadFactory-53-thread-1) [] o.a.s.c.o.SliceMutator createReplica() {
+ "core":"testPerReplicaStateCollection_shard1_replica_n2",
+ "node_name":"127.0.0.1:43371_solr",
+ "base_url":"http://127.0.0.1:43371/solr",
+ "collection":"testPerReplicaStateCollection",
+ "shard":"shard1",
+ "state":"down",
+ "type":"NRT",
+ "operation":"ADDREPLICA",
+ "waitForFinalState":"false"}
+4467 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=testPerReplicaStateCollection_shard1_replica_n2&action=CREATE&numShards=2&collection=testPerReplicaStateCollection&shard=shard1&wt=javabin&version=2&replicaType=NRT
+4467 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testPerReplicaStateCollection_shard2_replica_n1&action=CREATE&numShards=2&collection=testPerReplicaStateCollection&shard=shard2&wt=javabin&version=2&replicaType=NRT
+4470 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 2147483647 cores with initial capacity of 1024
+4471 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 2147483647 cores with initial capacity of 1024
+4511 WARN (qtp428470070-58) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.c.SolrConfig You should not use LATEST as luceneMatchVersion property: if you use this setting, and then Solr upgrades to a newer release of Lucene, sizable changes may happen. If precise back compatibility is important then you should instead explicitly specify an actual Lucene version.
+4511 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:testPerReplicaStateCollection s:shard1 r:core_node4 x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.3.0
+4511 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.3.0
+4563 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:testPerReplicaStateCollection s:shard1 r:core_node4 x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.s.IndexSchema Schema name=test
+4565 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.s.IndexSchema Schema name=test
+4766 WARN (qtp492762252-51) [n:127.0.0.1:43371_solr c:testPerReplicaStateCollection s:shard1 r:core_node4 x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
+4766 WARN (qtp492762252-51) [n:127.0.0.1:43371_solr c:testPerReplicaStateCollection s:shard1 r:core_node4 x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
+4766 WARN (qtp428470070-58) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
+4766 WARN (qtp428470070-58) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
+4767 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
+4767 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:testPerReplicaStateCollection s:shard1 r:core_node4 x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
+4772 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'testPerReplicaStateCollection_shard2_replica_n1' using configuration from configset conf, trusted=true
+4772 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:testPerReplicaStateCollection s:shard1 r:core_node4 x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'testPerReplicaStateCollection_shard1_replica_n2' using configuration from configset conf, trusted=true
+4809 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:testPerReplicaStateCollection s:shard1 r:core_node4 x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.c.SolrCore Opening new SolrCore at [/tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001/node3/testPerReplicaStateCollection_shard1_replica_n2], dataDir=[/tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001/node3/testPerReplicaStateCollection_shard1_replica_n2/data/]
+4809 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.c.SolrCore Opening new SolrCore at [/tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001/node2/testPerReplicaStateCollection_shard2_replica_n1], dataDir=[/tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001/node2/testPerReplicaStateCollection_shard2_replica_n1/data/]
+5127 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:testPerReplicaStateCollection s:shard1 r:core_node4 x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
+5127 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
+5128 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:testPerReplicaStateCollection s:shard1 r:core_node4 x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
+5128 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
+5138 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
+5138 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:testPerReplicaStateCollection s:shard1 r:core_node4 x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
+5139 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
+5139 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:testPerReplicaStateCollection s:shard1 r:core_node4 x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
+5166 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:testPerReplicaStateCollection s:shard1 r:core_node4 x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
+5172 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:testPerReplicaStateCollection s:shard1 r:core_node4 x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
+5172 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
+5172 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
+5179 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
+5179 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:testPerReplicaStateCollection s:shard1 r:core_node4 x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
+5181 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:testPerReplicaStateCollection s:shard1 r:core_node4 x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1746410359439228928
+5181 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1746410359439228928
+5194 INFO (searcherExecutor-73-thread-1-processing-127.0.0.1:36493_solr testPerReplicaStateCollection_shard2_replica_n1 testPerReplicaStateCollection shard2 core_node3) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.c.SolrCore Registered new searcher autowarm time: 0 ms
+5194 INFO (searcherExecutor-72-thread-1-processing-127.0.0.1:43371_solr testPerReplicaStateCollection_shard1_replica_n2 testPerReplicaStateCollection shard1 core_node4) [n:127.0.0.1:43371_solr c:testPerReplicaStateCollection s:shard1 r:core_node4 x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.c.SolrCore Registered new searcher autowarm time: 0 ms
+5206 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:testPerReplicaStateCollection s:shard1 r:core_node4 x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testPerReplicaStateCollection/terms/shard1 to Terms{values={core_node4=0}, version=0} for registerTerm
+5206 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testPerReplicaStateCollection/terms/shard2 to Terms{values={core_node3=0}, version=0} for registerTerm
+5210 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testPerReplicaStateCollection/leaders/shard2
+5210 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:testPerReplicaStateCollection s:shard1 r:core_node4 x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testPerReplicaStateCollection/leaders/shard1
+5217 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
+5218 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:testPerReplicaStateCollection s:shard1 r:core_node4 x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
+5218 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:testPerReplicaStateCollection s:shard1 r:core_node4 x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
+5218 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
+5218 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:testPerReplicaStateCollection s:shard1 r:core_node4 x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:43371/solr/testPerReplicaStateCollection_shard1_replica_n2/
+5218 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:36493/solr/testPerReplicaStateCollection_shard2_replica_n1/
+5220 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:testPerReplicaStateCollection s:shard1 r:core_node4 x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
+5220 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
+5221 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:36493/solr/testPerReplicaStateCollection_shard2_replica_n1/ has no replicas
+5221 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:testPerReplicaStateCollection s:shard1 r:core_node4 x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.c.SyncStrategy http://127.0.0.1:43371/solr/testPerReplicaStateCollection_shard1_replica_n2/ has no replicas
+5221 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:testPerReplicaStateCollection s:shard1 r:core_node4 x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testPerReplicaStateCollection/leaders/shard1/leader after winning as /collections/testPerReplicaStateCollection/leader_elect/shard1/election/72058663205601287-core_node4-n_0000000000
+5221 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testPerReplicaStateCollection/leaders/shard2/leader after winning as /collections/testPerReplicaStateCollection/leader_elect/shard2/election/72058663205601286-core_node3-n_0000000000
+5227 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:testPerReplicaStateCollection s:shard1 r:core_node4 x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:43371/solr/testPerReplicaStateCollection_shard1_replica_n2/ shard1
+5229 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:36493/solr/testPerReplicaStateCollection_shard2_replica_n1/ shard2
+5230 INFO (zkCallback-43-thread-2) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testPerReplicaStateCollection/state.json] for collection [testPerReplicaStateCollection] has occurred - updating... (live nodes size: [3])
+5230 INFO (zkCallback-39-thread-2) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testPerReplicaStateCollection/state.json] for collection [testPerReplicaStateCollection] has occurred - updating... (live nodes size: [3])
+5235 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:testPerReplicaStateCollection s:shard1 r:core_node4 x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.c.ZkController I am the leader, no recovery necessary
+5235 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
+5241 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=testPerReplicaStateCollection_shard1_replica_n2&action=CREATE&numShards=2&collection=testPerReplicaStateCollection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=775
+5241 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testPerReplicaStateCollection_shard2_replica_n1&action=CREATE&numShards=2&collection=testPerReplicaStateCollection&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=775
+5256 INFO (qtp1534462063-59) [n:127.0.0.1:45559_solr] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
+5259 INFO (qtp1534462063-59) [n:127.0.0.1:45559_solr] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&name=testPerReplicaStateCollection&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=1189
+5265 INFO (OverseerThreadFactory-53-thread-2) [] o.a.s.c.a.c.CreateCollectionCmd Create collection perReplicaState_test
+5265 INFO (OverseerCollectionConfigSetProcessor-72058663205601288-127.0.0.1:45559_solr-n_0000000000) [] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
+5272 INFO (OverseerThreadFactory-53-thread-2) [] o.a.s.c.o.SliceMutator createReplica() {
+ "core":"perReplicaState_test_shard2_replica_n1",
+ "node_name":"127.0.0.1:43371_solr",
+ "base_url":"http://127.0.0.1:43371/solr",
+ "collection":"perReplicaState_test",
+ "shard":"shard2",
+ "state":"down",
+ "type":"NRT",
+ "operation":"ADDREPLICA",
+ "waitForFinalState":"false"}
+5279 INFO (OverseerThreadFactory-53-thread-2) [] o.a.s.c.o.SliceMutator createReplica() {
+ "core":"perReplicaState_test_shard2_replica_n3",
+ "node_name":"127.0.0.1:36493_solr",
+ "base_url":"http://127.0.0.1:36493/solr",
+ "collection":"perReplicaState_test",
+ "shard":"shard2",
+ "state":"down",
+ "type":"NRT",
+ "operation":"ADDREPLICA",
+ "waitForFinalState":"false"}
+5283 INFO (OverseerThreadFactory-53-thread-2) [] o.a.s.c.o.SliceMutator createReplica() {
+ "core":"perReplicaState_test_shard1_replica_n5",
+ "node_name":"127.0.0.1:36493_solr",
+ "base_url":"http://127.0.0.1:36493/solr",
+ "collection":"perReplicaState_test",
+ "shard":"shard1",
+ "state":"down",
+ "type":"NRT",
+ "operation":"ADDREPLICA",
+ "waitForFinalState":"false"}
+5286 INFO (OverseerThreadFactory-53-thread-2) [] o.a.s.c.o.SliceMutator createReplica() {
+ "core":"perReplicaState_test_shard1_replica_n7",
+ "node_name":"127.0.0.1:45559_solr",
+ "base_url":"http://127.0.0.1:45559/solr",
+ "collection":"perReplicaState_test",
+ "shard":"shard1",
+ "state":"down",
+ "type":"NRT",
+ "operation":"ADDREPLICA",
+ "waitForFinalState":"false"}
+5291 INFO (qtp492762252-53) [n:127.0.0.1:43371_solr x:perReplicaState_test_shard2_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=perReplicaState_test_shard2_replica_n1&action=CREATE&numShards=2&collection=perReplicaState_test&shard=shard2&wt=javabin&version=2&replicaType=NRT
+5291 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr x:perReplicaState_test_shard2_replica_n3] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=perReplicaState_test_shard2_replica_n3&action=CREATE&numShards=2&collection=perReplicaState_test&shard=shard2&wt=javabin&version=2&replicaType=NRT
+5294 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr x:perReplicaState_test_shard1_replica_n5] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=perReplicaState_test_shard1_replica_n5&action=CREATE&numShards=2&collection=perReplicaState_test&shard=shard1&wt=javabin&version=2&replicaType=NRT
+5295 INFO (qtp1534462063-59) [n:127.0.0.1:45559_solr x:perReplicaState_test_shard1_replica_n7] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=perReplicaState_test_shard1_replica_n7&action=CREATE&numShards=2&collection=perReplicaState_test&shard=shard1&wt=javabin&version=2&replicaType=NRT
+5313 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.3.0
+5315 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.s.IndexSchema Schema name=test
+5316 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard2 r:core_node4 x:perReplicaState_test_shard2_replica_n3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.3.0
+5316 INFO (qtp492762252-53) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.3.0
+5317 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard2 r:core_node4 x:perReplicaState_test_shard2_replica_n3] o.a.s.s.IndexSchema Schema name=test
+5317 INFO (qtp492762252-53) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.s.IndexSchema Schema name=test
+5321 WARN (qtp428470070-62) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
+5321 WARN (qtp428470070-62) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
+5321 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
+5322 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.c.CoreContainer Creating SolrCore 'perReplicaState_test_shard1_replica_n5' using configuration from configset conf, trusted=true
+5322 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.c.SolrCore Opening new SolrCore at [/tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001/node2/perReplicaState_test_shard1_replica_n5], dataDir=[/tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001/node2/perReplicaState_test_shard1_replica_n5/data/]
+5323 WARN (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard2 r:core_node4 x:perReplicaState_test_shard2_replica_n3] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
+5323 WARN (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard2 r:core_node4 x:perReplicaState_test_shard2_replica_n3] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
+5323 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard2 r:core_node4 x:perReplicaState_test_shard2_replica_n3] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
+5323 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard2 r:core_node4 x:perReplicaState_test_shard2_replica_n3] o.a.s.c.CoreContainer Creating SolrCore 'perReplicaState_test_shard2_replica_n3' using configuration from configset conf, trusted=true
+5324 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard2 r:core_node4 x:perReplicaState_test_shard2_replica_n3] o.a.s.c.SolrCore Opening new SolrCore at [/tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001/node2/perReplicaState_test_shard2_replica_n3], dataDir=[/tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001/node2/perReplicaState_test_shard2_replica_n3/data/]
+5328 WARN (qtp492762252-53) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
+5328 WARN (qtp492762252-53) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
+5328 INFO (qtp492762252-53) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
+5329 INFO (qtp492762252-53) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'perReplicaState_test_shard2_replica_n1' using configuration from configset conf, trusted=true
+5329 INFO (qtp492762252-53) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.c.SolrCore Opening new SolrCore at [/tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001/node3/perReplicaState_test_shard2_replica_n1], dataDir=[/tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001/node3/perReplicaState_test_shard2_replica_n1/data/]
+5333 INFO (qtp1534462063-59) [n:127.0.0.1:45559_solr c:perReplicaState_test s:shard1 r:core_node8 x:perReplicaState_test_shard1_replica_n7] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.3.0
+5345 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
+5345 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
+5345 INFO (qtp1534462063-59) [n:127.0.0.1:45559_solr c:perReplicaState_test s:shard1 r:core_node8 x:perReplicaState_test_shard1_replica_n7] o.a.s.s.IndexSchema Schema name=test
+5347 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.u.CommitTracker Hard AutoCommit: disabled
+5347 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.u.CommitTracker Soft AutoCommit: disabled
+5347 INFO (qtp492762252-53) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
+5347 INFO (qtp492762252-53) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
+5349 INFO (qtp492762252-53) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
+5349 INFO (qtp492762252-53) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
+5351 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
+5352 INFO (qtp492762252-53) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
+5352 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
+5352 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
+5352 INFO (qtp492762252-53) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
+5352 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1746410359618535424
+5352 INFO (qtp492762252-53) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
+5353 INFO (qtp492762252-53) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1746410359619584000
+5363 INFO (searcherExecutor-83-thread-1-processing-127.0.0.1:36493_solr perReplicaState_test_shard1_replica_n5 perReplicaState_test shard1 core_node6) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.c.SolrCore Registered new searcher autowarm time: 0 ms
+5367 INFO (searcherExecutor-87-thread-1-processing-127.0.0.1:43371_solr perReplicaState_test_shard2_replica_n1 perReplicaState_test shard2 core_node2) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.c.SolrCore Registered new searcher autowarm time: 0 ms
+5373 WARN (qtp1534462063-59) [n:127.0.0.1:45559_solr c:perReplicaState_test s:shard1 r:core_node8 x:perReplicaState_test_shard1_replica_n7] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
+5373 WARN (qtp1534462063-59) [n:127.0.0.1:45559_solr c:perReplicaState_test s:shard1 r:core_node8 x:perReplicaState_test_shard1_replica_n7] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
+5373 INFO (qtp1534462063-59) [n:127.0.0.1:45559_solr c:perReplicaState_test s:shard1 r:core_node8 x:perReplicaState_test_shard1_replica_n7] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
+5373 INFO (qtp1534462063-59) [n:127.0.0.1:45559_solr c:perReplicaState_test s:shard1 r:core_node8 x:perReplicaState_test_shard1_replica_n7] o.a.s.c.CoreContainer Creating SolrCore 'perReplicaState_test_shard1_replica_n7' using configuration from configset conf, trusted=true
+5374 INFO (qtp1534462063-59) [n:127.0.0.1:45559_solr c:perReplicaState_test s:shard1 r:core_node8 x:perReplicaState_test_shard1_replica_n7] o.a.s.c.SolrCore Opening new SolrCore at [/tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001/node1/perReplicaState_test_shard1_replica_n7], dataDir=[/tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001/node1/perReplicaState_test_shard1_replica_n7/data/]
+5375 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard2 r:core_node4 x:perReplicaState_test_shard2_replica_n3] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
+5375 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard2 r:core_node4 x:perReplicaState_test_shard2_replica_n3] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
+5376 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard2 r:core_node4 x:perReplicaState_test_shard2_replica_n3] o.a.s.u.CommitTracker Hard AutoCommit: disabled
+5376 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard2 r:core_node4 x:perReplicaState_test_shard2_replica_n3] o.a.s.u.CommitTracker Soft AutoCommit: disabled
+5382 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard2 r:core_node4 x:perReplicaState_test_shard2_replica_n3] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
+5382 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.c.ZkShardTerms Successful update of terms at /collections/perReplicaState_test/terms/shard1 to Terms{values={core_node6=0}, version=0} for registerTerm
+5383 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/perReplicaState_test/leaders/shard1
+5384 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard2 r:core_node4 x:perReplicaState_test_shard2_replica_n3] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
+5385 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard2 r:core_node4 x:perReplicaState_test_shard2_replica_n3] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
+5385 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard2 r:core_node4 x:perReplicaState_test_shard2_replica_n3] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1746410359653138432
+5388 INFO (qtp492762252-53) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/perReplicaState_test/terms/shard2 to Terms{values={core_node2=0}, version=0} for registerTerm
+5389 INFO (qtp492762252-53) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/perReplicaState_test/leaders/shard2
+5389 INFO (searcherExecutor-85-thread-1-processing-127.0.0.1:36493_solr perReplicaState_test_shard2_replica_n3 perReplicaState_test shard2 core_node4) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard2 r:core_node4 x:perReplicaState_test_shard2_replica_n3] o.a.s.c.SolrCore Registered new searcher autowarm time: 0 ms
+5391 INFO (zkCallback-39-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [3])
+5392 INFO (zkCallback-39-thread-2) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [3])
+5394 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=2 found=1 timeoute in=9997ms
+5396 INFO (qtp1534462063-59) [n:127.0.0.1:45559_solr c:perReplicaState_test s:shard1 r:core_node8 x:perReplicaState_test_shard1_replica_n7] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
+5396 INFO (qtp1534462063-59) [n:127.0.0.1:45559_solr c:perReplicaState_test s:shard1 r:core_node8 x:perReplicaState_test_shard1_replica_n7] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
+5399 INFO (qtp492762252-53) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard2: total=2 found=1 timeoute in=9998ms
+5400 INFO (qtp1534462063-59) [n:127.0.0.1:45559_solr c:perReplicaState_test s:shard1 r:core_node8 x:perReplicaState_test_shard1_replica_n7] o.a.s.u.CommitTracker Hard AutoCommit: disabled
+5400 INFO (qtp1534462063-59) [n:127.0.0.1:45559_solr c:perReplicaState_test s:shard1 r:core_node8 x:perReplicaState_test_shard1_replica_n7] o.a.s.u.CommitTracker Soft AutoCommit: disabled
+5402 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard2 r:core_node4 x:perReplicaState_test_shard2_replica_n3] o.a.s.c.ZkShardTerms Successful update of terms at /collections/perReplicaState_test/terms/shard2 to Terms{values={core_node2=0, core_node4=0}, version=1} for registerTerm
+5403 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard2 r:core_node4 x:perReplicaState_test_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/perReplicaState_test/leaders/shard2
+5403 INFO (qtp1534462063-59) [n:127.0.0.1:45559_solr c:perReplicaState_test s:shard1 r:core_node8 x:perReplicaState_test_shard1_replica_n7] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
+5404 INFO (qtp1534462063-59) [n:127.0.0.1:45559_solr c:perReplicaState_test s:shard1 r:core_node8 x:perReplicaState_test_shard1_replica_n7] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
+5404 INFO (qtp1534462063-59) [n:127.0.0.1:45559_solr c:perReplicaState_test s:shard1 r:core_node8 x:perReplicaState_test_shard1_replica_n7] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
+5404 INFO (qtp1534462063-59) [n:127.0.0.1:45559_solr c:perReplicaState_test s:shard1 r:core_node8 x:perReplicaState_test_shard1_replica_n7] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1746410359673061376
+5407 INFO (searcherExecutor-98-thread-1-processing-127.0.0.1:45559_solr perReplicaState_test_shard1_replica_n7 perReplicaState_test shard1 core_node8) [n:127.0.0.1:45559_solr c:perReplicaState_test s:shard1 r:core_node8 x:perReplicaState_test_shard1_replica_n7] o.a.s.c.SolrCore Registered new searcher autowarm time: 0 ms
+5413 INFO (qtp1534462063-59) [n:127.0.0.1:45559_solr c:perReplicaState_test s:shard1 r:core_node8 x:perReplicaState_test_shard1_replica_n7] o.a.s.c.ZkShardTerms Successful update of terms at /collections/perReplicaState_test/terms/shard1 to Terms{values={core_node6=0, core_node8=0}, version=1} for registerTerm
+5413 INFO (qtp1534462063-59) [n:127.0.0.1:45559_solr c:perReplicaState_test s:shard1 r:core_node8 x:perReplicaState_test_shard1_replica_n7] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/perReplicaState_test/leaders/shard1
+5898 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
+5898 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
+5898 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:36493/solr/perReplicaState_test_shard1_replica_n5/
+5902 INFO (qtp492762252-53) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
+5902 INFO (qtp492762252-53) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
+5902 INFO (qtp492762252-53) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:43371/solr/perReplicaState_test_shard2_replica_n1/
+5908 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.u.PeerSync PeerSync: core=perReplicaState_test_shard1_replica_n5 url=http://127.0.0.1:36493/solr START replicas=[http://127.0.0.1:45559/solr/perReplicaState_test_shard1_replica_n7/] nUpdates=100
+5908 INFO (qtp492762252-53) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.u.PeerSync PeerSync: core=perReplicaState_test_shard2_replica_n1 url=http://127.0.0.1:43371/solr START replicas=[http://127.0.0.1:36493/solr/perReplicaState_test_shard2_replica_n3/] nUpdates=100
+5912 INFO (qtp492762252-53) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.u.PeerSync PeerSync: core=perReplicaState_test_shard2_replica_n1 url=http://127.0.0.1:43371/solr DONE. We have no versions. sync failed.
+5912 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.u.PeerSync PeerSync: core=perReplicaState_test_shard1_replica_n5 url=http://127.0.0.1:36493/solr DONE. We have no versions. sync failed.
+5935 INFO (qtp1534462063-63) [n:127.0.0.1:45559_solr c:perReplicaState_test s:shard1 r:core_node8 x:perReplicaState_test_shard1_replica_n7] o.a.s.c.S.Request webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=8
+5935 INFO (qtp428470070-64) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard2 r:core_node4 x:perReplicaState_test_shard2_replica_n3] o.a.s.c.S.Request webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=8
+5945 INFO (qtp492762252-53) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
+5945 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
+5946 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
+5946 INFO (qtp492762252-53) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
+5946 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/perReplicaState_test/leaders/shard1/leader after winning as /collections/perReplicaState_test/leader_elect/shard1/election/72058663205601286-core_node6-n_0000000000
+5946 INFO (qtp492762252-53) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/perReplicaState_test/leaders/shard2/leader after winning as /collections/perReplicaState_test/leader_elect/shard2/election/72058663205601287-core_node2-n_0000000000
+5959 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:36493/solr/perReplicaState_test_shard1_replica_n5/ shard1
+5959 INFO (qtp492762252-53) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:43371/solr/perReplicaState_test_shard2_replica_n1/ shard2
+5974 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.c.ZkController I am the leader, no recovery necessary
+5976 INFO (qtp492762252-53) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
+5993 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=perReplicaState_test_shard1_replica_n5&action=CREATE&numShards=2&collection=perReplicaState_test&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=700
+6001 INFO (qtp492762252-53) [n:127.0.0.1:43371_solr] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=perReplicaState_test_shard2_replica_n1&action=CREATE&numShards=2&collection=perReplicaState_test&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=710
+6413 INFO (zkCallback-43-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [3])
+6413 INFO (zkCallback-39-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [3])
+6419 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=perReplicaState_test_shard2_replica_n3&action=CREATE&numShards=2&collection=perReplicaState_test&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1128
+6442 INFO (zkCallback-43-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [3])
+6442 INFO (zkCallback-39-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [3])
+6456 INFO (qtp1534462063-59) [n:127.0.0.1:45559_solr] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=perReplicaState_test_shard1_replica_n7&action=CREATE&numShards=2&collection=perReplicaState_test&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1160
+6469 INFO (qtp1534462063-61) [n:127.0.0.1:45559_solr] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
+6471 INFO (qtp1534462063-61) [n:127.0.0.1:45559_solr] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&name=perReplicaState_test&nrtReplicas=2&action=CREATE&numShards=2&perReplicaState=true&wt=javabin&version=2} status=0 QTime=1209
+6474 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: perReplicaState_test
+6508 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr c:perReplicaState_test] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&collection=perReplicaState_test&wt=javabin&version=2} status=0 QTime=6
+6520 INFO (qtp428470070-64) [n:127.0.0.1:36493_solr c:perReplicaState_test] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&collection=perReplicaState_test&wt=javabin&version=2} status=0 QTime=4
+6531 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=4
+6543 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_test] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&collection=perReplicaState_test&wt=javabin&version=2} status=0 QTime=4
+6554 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr c:perReplicaState_test] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&collection=perReplicaState_test&wt=javabin&version=2} status=0 QTime=4
+6628 INFO (qtp492762252-50) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.c.S.Request webapp=/solr path=/admin/ping params={_stateVer_=perReplicaState_test:6&wt=javabin&version=2} hits=0 status=0 QTime=62
+6628 INFO (qtp492762252-50) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.c.S.Request webapp=/solr path=/admin/ping params={_stateVer_=perReplicaState_test:6&wt=javabin&version=2} status=0 QTime=63
+6635 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
+6635 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
+6635 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.e.j.s.Server jetty-9.4.48.v20220622; built: 2022-06-21T20:42:25.880Z; git: 6b67c5719d1f4371b33655ff2d047d24e171e49a; jvm 11.0.15+10
+6636 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.e.j.s.session DefaultSessionIdManager workerName=node0
+6636 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.e.j.s.session No SessionScavenger set, using defaults
+6636 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.e.j.s.session node0 Scavenging every 660000ms
+6637 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@577014f6{/solr,null,AVAILABLE}
+6638 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.e.j.s.AbstractConnector Started ServerConnector@100fc9c2{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:41223}
+6639 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.e.j.s.Server Started @6669ms
+6639 ERROR (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
+6639 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.a.s.s.CoreContainerProvider Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
+6639 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.a.s.s.CoreContainerProvider ___ _ Welcome to Apache Solr™ version 9.1.0
+6639 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.a.s.s.CoreContainerProvider / __| ___| |_ _ Starting in cloud mode on port null
+6639 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.a.s.s.CoreContainerProvider \__ \/ _ \ | '_| Install dir: /home/ishan/code/solr/./solr
+6639 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.a.s.s.CoreContainerProvider |___/\___/_|_| Start time: 2022-10-11T16:45:07.161705Z
+6639 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.a.s.s.CoreContainerProvider Solr Home: /tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001/node4 (source: servlet config: solr.solr.home)
+6640 WARN (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.a.s.c.c.SolrZkClient Using default ZkCredentialsInjector. ZkCredentialsInjector is not secure, it creates an empty list of credentials which leads to 'OPEN_ACL_UNSAFE' ACLs to Zookeeper nodes
+6640 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.a.s.c.c.ConnectionManager Waiting up to 30000ms for client to connect to ZooKeeper
+6647 INFO (zkConnectionManagerCallback-107-thread-1) [] o.a.s.c.c.ConnectionManager zkClient has connected
+6647 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
+6648 WARN (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.a.s.c.c.SolrZkClient Using default ZkACLProvider. DefaultZkACLProvider is not secure, it creates 'OPEN_ACL_UNSAFE' ACLs to Zookeeper nodes
+6648 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.a.s.c.NodeConfig solr.xml found in ZooKeeper. Loading...
+6758 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40675/solr
+6758 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.a.s.c.DistributedClusterStateUpdater Creating DistributedClusterStateUpdater with useDistributedStateUpdate=true. Solr will be using distributed cluster state updates.
+6759 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [n:127.0.0.1:41223_solr] o.a.s.c.c.ConnectionManager Waiting up to 30000ms for client to connect to ZooKeeper
+6761 INFO (zkConnectionManagerCallback-117-thread-1) [] o.a.s.c.c.ConnectionManager zkClient has connected
+6762 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [n:127.0.0.1:41223_solr] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
+6766 WARN (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [n:127.0.0.1:41223_solr] o.a.s.c.ZkController Contents of zookeeper /security.json are world-readable; consider setting up ACLs as described in https://solr.apache.org/guide/solr/latest/deployment-guide/zookeeper-access-control.html
+6767 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [n:127.0.0.1:41223_solr] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
+6768 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [n:127.0.0.1:41223_solr] o.a.s.c.DistributedClusterStateUpdater Creating DistributedClusterStateUpdater with useDistributedStateUpdate=true. Solr will be using distributed cluster state updates.
+6770 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [n:127.0.0.1:41223_solr] o.a.s.c.ZkController Publish node=127.0.0.1:41223_solr as DOWN
+6772 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [n:127.0.0.1:41223_solr] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 2147483647 cores with initial capacity of 1024
+6772 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [n:127.0.0.1:41223_solr] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41223_solr
+6774 INFO (zkCallback-43-thread-3) [] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
+6774 INFO (zkCallback-63-thread-1) [] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
+6774 INFO (zkCallback-39-thread-3) [] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
+6774 INFO (zkCallback-40-thread-2) [] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
+6774 INFO (zkCallback-116-thread-1) [] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
+6776 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [n:127.0.0.1:41223_solr] o.a.s.p.PackageLoader /packages.json updated to version -1
+6776 WARN (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [n:127.0.0.1:41223_solr] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
+6806 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [n:127.0.0.1:41223_solr] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001/node4
+6812 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [n:127.0.0.1:41223_solr] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=41223, zkHost=127.0.0.1:40675/solr}
+6819 INFO (OverseerCollectionConfigSetProcessor-72058663205601288-127.0.0.1:45559_solr-n_0000000000) [] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
+6820 INFO (OverseerThreadFactory-53-thread-3) [ c:perReplicaState_test s:shard1] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:41223_solr for creating new replica of shard shard1 for collection perReplicaState_test
+6822 INFO (OverseerThreadFactory-53-thread-3) [ c:perReplicaState_test s:shard1] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
+6822 INFO (OverseerThreadFactory-53-thread-3) [ c:perReplicaState_test s:shard1] o.a.s.c.o.SliceMutator createReplica() {
+ "core":"perReplicaState_test_shard1_replica_n9",
+ "node_name":"127.0.0.1:41223_solr",
+ "base_url":"http://127.0.0.1:41223/solr",
+ "collection":"perReplicaState_test",
+ "shard":"shard1",
+ "state":"down",
+ "type":"NRT",
+ "operation":"addreplica"}
+6825 INFO (zkCallback-40-thread-2) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [4])
+6825 INFO (zkCallback-43-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [4])
+6825 INFO (zkCallback-39-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [4])
+6826 INFO (zkCallback-43-thread-2) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [4])
+6826 INFO (zkCallback-39-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [4])
+6826 INFO (zkCallback-39-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [4])
+6826 INFO (zkCallback-43-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [4])
+6827 INFO (zkCallback-40-thread-2) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [4])
+6827 INFO (zkCallback-40-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [4])
+6827 INFO (zkCallback-40-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [4])
+6835 INFO (qtp965371294-159) [n:127.0.0.1:41223_solr x:perReplicaState_test_shard1_replica_n9] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node10&collection.configName=conf&name=perReplicaState_test_shard1_replica_n9&action=CREATE&collection=perReplicaState_test&shard=shard1&wt=javabin&version=2&replicaType=NRT
+6842 INFO (zkCallback-43-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [4])
+6842 INFO (zkCallback-39-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [4])
+6842 INFO (zkCallback-40-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [4])
+6850 INFO (qtp965371294-159) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.3.0
+6857 INFO (qtp965371294-159) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.s.IndexSchema Schema name=test
+6864 WARN (qtp965371294-159) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
+6864 WARN (qtp965371294-159) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
+6865 INFO (qtp965371294-159) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
+6865 INFO (qtp965371294-159) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.c.CoreContainer Creating SolrCore 'perReplicaState_test_shard1_replica_n9' using configuration from configset conf, trusted=true
+6865 INFO (qtp965371294-159) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.c.SolrCore Opening new SolrCore at [/tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001/node4/perReplicaState_test_shard1_replica_n9], dataDir=[/tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001/node4/perReplicaState_test_shard1_replica_n9/data/]
+6883 INFO (qtp965371294-159) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
+6884 INFO (qtp965371294-159) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
+6885 INFO (qtp965371294-159) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.u.CommitTracker Hard AutoCommit: disabled
+6885 INFO (qtp965371294-159) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.u.CommitTracker Soft AutoCommit: disabled
+6888 INFO (qtp965371294-159) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
+6889 INFO (qtp965371294-159) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
+6889 INFO (qtp965371294-159) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
+6889 INFO (qtp965371294-159) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1746410361230196736
+6892 INFO (searcherExecutor-124-thread-1-processing-127.0.0.1:41223_solr perReplicaState_test_shard1_replica_n9 perReplicaState_test shard1 core_node10) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.c.SolrCore Registered new searcher autowarm time: 0 ms
+6896 INFO (qtp965371294-159) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.c.ZkShardTerms Successful update of terms at /collections/perReplicaState_test/terms/shard1 to Terms{values={core_node6=0, core_node10=0, core_node8=0}, version=2} for registerTerm
+6897 INFO (qtp965371294-159) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/perReplicaState_test/leaders/shard1
+6904 INFO (qtp965371294-159) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.c.ZkController Core needs to recover:perReplicaState_test_shard1_replica_n9
+6905 INFO (updateExecutor-113-thread-1-processing-127.0.0.1:41223_solr perReplicaState_test_shard1_replica_n9 perReplicaState_test shard1 core_node10) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.u.DefaultSolrCoreState Running recovery
+6907 INFO (qtp965371294-159) [n:127.0.0.1:41223_solr] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node10&collection.configName=conf&name=perReplicaState_test_shard1_replica_n9&action=CREATE&collection=perReplicaState_test&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=72
+6907 INFO (recoveryExecutor-115-thread-1-processing-127.0.0.1:41223_solr perReplicaState_test_shard1_replica_n9 perReplicaState_test shard1 core_node10) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.c.RecoveryStrategy Starting recovery process. recoveringAfterStartup=true
+6907 INFO (recoveryExecutor-115-thread-1-processing-127.0.0.1:41223_solr perReplicaState_test_shard1_replica_n9 perReplicaState_test shard1 core_node10) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.c.RecoveryStrategy startupVersions is empty
+6911 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.c.S.Request webapp=/solr path=/admin/ping params={wt=javabin&version=2} hits=0 status=0 QTime=0
+6911 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.c.S.Request webapp=/solr path=/admin/ping params={wt=javabin&version=2} status=0 QTime=0
+6912 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr c:perReplicaState_test] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=ADDREPLICA&collection=perReplicaState_test&shard=shard1&type=NRT&wt=javabin&version=2} status=0 QTime=97
+6912 INFO (recoveryExecutor-115-thread-1-processing-127.0.0.1:41223_solr perReplicaState_test_shard1_replica_n9 perReplicaState_test shard1 core_node10) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.c.RecoveryStrategy Begin buffering updates. core=[perReplicaState_test_shard1_replica_n9]
+6913 INFO (recoveryExecutor-115-thread-1-processing-127.0.0.1:41223_solr perReplicaState_test_shard1_replica_n9 perReplicaState_test shard1 core_node10) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
+6914 INFO (recoveryExecutor-115-thread-1-processing-127.0.0.1:41223_solr perReplicaState_test_shard1_replica_n9 perReplicaState_test shard1 core_node10) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.c.RecoveryStrategy Publishing state of core [perReplicaState_test_shard1_replica_n9] as recovering, leader is [http://127.0.0.1:36493/solr/perReplicaState_test_shard1_replica_n5/] and I am [http://127.0.0.1:41223/solr/pe [...]
+6916 INFO (zkCallback-116-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [4])
+6916 INFO (zkCallback-43-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [4])
+6917 INFO (zkCallback-40-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [4])
+6917 INFO (zkCallback-39-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [4])
+6917 INFO (zkCallback-116-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [4])
+6918 INFO (zkCallback-116-thread-2) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [4])
+6918 INFO (zkCallback-116-thread-4) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [4])
+6919 INFO (recoveryExecutor-115-thread-1-processing-127.0.0.1:41223_solr perReplicaState_test_shard1_replica_n9 perReplicaState_test shard1 core_node10) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.c.RecoveryStrategy Sending prep recovery command to [http://127.0.0.1:36493/solr]; [WaitForState: action=PREPRECOVERY&core=perReplicaState_test_shard1_replica_n5&nodeName=127.0.0.1:41223_solr&coreNodeName=core_node10&sta [...]
+6932 INFO (qtp428470070-64) [n:127.0.0.1:36493_solr x:perReplicaState_test_shard1_replica_n5] o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node10, state: recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
+6946 INFO (qtp428470070-64) [n:127.0.0.1:36493_solr] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=perReplicaState_test, shard=shard1, thisCore=perReplicaState_test_shard1_replica_n5, leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true, currentState=recovering, localState=active, nodeName=127.0.0.1:41223_solr, coreNodeName=core_node10, onlyIfActiveCheckResult=false, nodeProps: core_node10:{
+ "core":"perReplicaState_test_shard1_replica_n9",
+ "core_node_name":"core_node10",
+ "node_name":"127.0.0.1:41223_solr",
+ "base_url":"http://127.0.0.1:41223/solr",
+ "state":"recovering",
+ "collection":"perReplicaState_test",
+ "shard":"shard1",
+ "type":"NRT"}
+6947 INFO (qtp428470070-64) [n:127.0.0.1:36493_solr] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=perReplicaState_test, shard=shard1, thisCore=perReplicaState_test_shard1_replica_n5, leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true, currentState=recovering, localState=active, nodeName=127.0.0.1:41223_solr, coreNodeName=core_node10, onlyIfActiveCheckResult=false, nodeProps: core_node10:{
+ "core":"perReplicaState_test_shard1_replica_n9",
+ "core_node_name":"core_node10",
+ "node_name":"127.0.0.1:41223_solr",
+ "base_url":"http://127.0.0.1:41223/solr",
+ "state":"recovering",
+ "collection":"perReplicaState_test",
+ "shard":"shard1",
+ "type":"NRT"}
+6947 INFO (qtp428470070-64) [n:127.0.0.1:36493_solr] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=perReplicaState_test, shard=shard1, thisCore=perReplicaState_test_shard1_replica_n5, leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true, currentState=recovering, localState=active, nodeName=127.0.0.1:41223_solr, coreNodeName=core_node10, onlyIfActiveCheckResult=false, nodeProps: core_node10:{
+ "core":"perReplicaState_test_shard1_replica_n9",
+ "core_node_name":"core_node10",
+ "node_name":"127.0.0.1:41223_solr",
+ "base_url":"http://127.0.0.1:41223/solr",
+ "state":"recovering",
+ "collection":"perReplicaState_test",
+ "shard":"shard1",
+ "type":"NRT"}
+6947 INFO (qtp428470070-64) [n:127.0.0.1:36493_solr] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={nodeName=127.0.0.1:41223_solr&onlyIfLeaderActive=true&core=perReplicaState_test_shard1_replica_n5&coreNodeName=core_node10&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2} status=0 QTime=15
+6978 INFO (OverseerThreadFactory-53-thread-4) [] o.a.s.c.a.c.CreateCollectionCmd Create collection perReplicaState_testv2
+6978 INFO (OverseerCollectionConfigSetProcessor-72058663205601288-127.0.0.1:45559_solr-n_0000000000) [] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may have disconnected from ZooKeeper
+6984 INFO (OverseerThreadFactory-53-thread-4) [] o.a.s.c.o.SliceMutator createReplica() {
+ "core":"perReplicaState_testv2_shard1_replica_n1",
+ "node_name":"127.0.0.1:43371_solr",
+ "base_url":"http://127.0.0.1:43371/solr",
+ "collection":"perReplicaState_testv2",
+ "shard":"shard1",
+ "state":"down",
+ "type":"NRT",
+ "operation":"ADDREPLICA",
+ "waitForFinalState":"false"}
+6987 INFO (OverseerThreadFactory-53-thread-4) [] o.a.s.c.o.SliceMutator createReplica() {
+ "core":"perReplicaState_testv2_shard1_replica_n3",
+ "node_name":"127.0.0.1:45559_solr",
+ "base_url":"http://127.0.0.1:45559/solr",
+ "collection":"perReplicaState_testv2",
+ "shard":"shard1",
+ "state":"down",
+ "type":"NRT",
+ "operation":"ADDREPLICA",
+ "waitForFinalState":"false"}
+6991 INFO (OverseerThreadFactory-53-thread-4) [] o.a.s.c.o.SliceMutator createReplica() {
+ "core":"perReplicaState_testv2_shard2_replica_n5",
+ "node_name":"127.0.0.1:41223_solr",
+ "base_url":"http://127.0.0.1:41223/solr",
+ "collection":"perReplicaState_testv2",
+ "shard":"shard2",
+ "state":"down",
+ "type":"NRT",
+ "operation":"ADDREPLICA",
+ "waitForFinalState":"false"}
+6994 INFO (OverseerThreadFactory-53-thread-4) [] o.a.s.c.o.SliceMutator createReplica() {
+ "core":"perReplicaState_testv2_shard2_replica_n7",
+ "node_name":"127.0.0.1:36493_solr",
+ "base_url":"http://127.0.0.1:36493/solr",
+ "collection":"perReplicaState_testv2",
+ "shard":"shard2",
+ "state":"down",
+ "type":"NRT",
+ "operation":"ADDREPLICA",
+ "waitForFinalState":"false"}
+6997 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr x:perReplicaState_testv2_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=perReplicaState_testv2_shard1_replica_n1&action=CREATE&numShards=2&collection=perReplicaState_testv2&shard=shard1&wt=javabin&version=2&replicaType=NRT
+6998 INFO (qtp1534462063-63) [n:127.0.0.1:45559_solr x:perReplicaState_testv2_shard1_replica_n3] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=perReplicaState_testv2_shard1_replica_n3&action=CREATE&numShards=2&collection=perReplicaState_testv2&shard=shard1&wt=javabin&version=2&replicaType=NRT
+6998 INFO (qtp965371294-160) [n:127.0.0.1:41223_solr x:perReplicaState_testv2_shard2_replica_n5] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=perReplicaState_testv2_shard2_replica_n5&action=CREATE&numShards=2&collection=perReplicaState_testv2&shard=shard2&wt=javabin&version=2&replicaType=NRT
+6999 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr x:perReplicaState_testv2_shard2_replica_n7] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=perReplicaState_testv2_shard2_replica_n7&action=CREATE&numShards=2&collection=perReplicaState_testv2&shard=shard2&wt=javabin&version=2&replicaType=NRT
+7013 INFO (zkCallback-39-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [4])
+7020 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:perReplicaState_testv2 s:shard1 r:core_node2 x:perReplicaState_testv2_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.3.0
+7020 INFO (qtp965371294-160) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.3.0
+7022 INFO (qtp965371294-160) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.s.IndexSchema Schema name=test
+7022 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:perReplicaState_testv2 s:shard1 r:core_node2 x:perReplicaState_testv2_shard1_replica_n1] o.a.s.s.IndexSchema Schema name=test
+7025 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_testv2 s:shard2 r:core_node8 x:perReplicaState_testv2_shard2_replica_n7] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.3.0
+7025 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_testv2 s:shard2 r:core_node8 x:perReplicaState_testv2_shard2_replica_n7] o.a.s.s.IndexSchema Schema name=test
+7028 WARN (qtp965371294-160) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
+7028 WARN (qtp965371294-160) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
+7028 INFO (qtp965371294-160) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
+7028 INFO (qtp965371294-160) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.c.CoreContainer Creating SolrCore 'perReplicaState_testv2_shard2_replica_n5' using configuration from configset conf, trusted=true
+7029 WARN (qtp492762252-51) [n:127.0.0.1:43371_solr c:perReplicaState_testv2 s:shard1 r:core_node2 x:perReplicaState_testv2_shard1_replica_n1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
+7029 WARN (qtp492762252-51) [n:127.0.0.1:43371_solr c:perReplicaState_testv2 s:shard1 r:core_node2 x:perReplicaState_testv2_shard1_replica_n1] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
+7030 INFO (qtp965371294-160) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.c.SolrCore Opening new SolrCore at [/tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001/node4/perReplicaState_testv2_shard2_replica_n5], dataDir=[/tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001/node4/perReplicaState_testv2_shard2_replica_n5/data/]
+7030 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:perReplicaState_testv2 s:shard1 r:core_node2 x:perReplicaState_testv2_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
+7031 WARN (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_testv2 s:shard2 r:core_node8 x:perReplicaState_testv2_shard2_replica_n7] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
+7031 WARN (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_testv2 s:shard2 r:core_node8 x:perReplicaState_testv2_shard2_replica_n7] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
+7031 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_testv2 s:shard2 r:core_node8 x:perReplicaState_testv2_shard2_replica_n7] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
+7032 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_testv2 s:shard2 r:core_node8 x:perReplicaState_testv2_shard2_replica_n7] o.a.s.c.CoreContainer Creating SolrCore 'perReplicaState_testv2_shard2_replica_n7' using configuration from configset conf, trusted=true
+7032 INFO (qtp1534462063-63) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.3.0
+7032 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_testv2 s:shard2 r:core_node8 x:perReplicaState_testv2_shard2_replica_n7] o.a.s.c.SolrCore Opening new SolrCore at [/tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001/node2/perReplicaState_testv2_shard2_replica_n7], dataDir=[/tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001/node2/perReplicaState_testv2_shard2_replica_n7/data/]
+7032 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:perReplicaState_testv2 s:shard1 r:core_node2 x:perReplicaState_testv2_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'perReplicaState_testv2_shard1_replica_n1' using configuration from configset conf, trusted=true
+7032 INFO (qtp1534462063-63) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.s.IndexSchema Schema name=test
+7036 WARN (qtp1534462063-63) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
+7036 WARN (qtp1534462063-63) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
+7037 INFO (qtp1534462063-63) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
+7037 INFO (qtp1534462063-63) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.c.CoreContainer Creating SolrCore 'perReplicaState_testv2_shard1_replica_n3' using configuration from configset conf, trusted=true
+7037 INFO (qtp1534462063-63) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.c.SolrCore Opening new SolrCore at [/tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001/node1/perReplicaState_testv2_shard1_replica_n3], dataDir=[/tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001/node1/perReplicaState_testv2_shard1_replica_n3/data/]
+7039 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:perReplicaState_testv2 s:shard1 r:core_node2 x:perReplicaState_testv2_shard1_replica_n1] o.a.s.c.SolrCore Opening new SolrCore at [/tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001/node3/perReplicaState_testv2_shard1_replica_n1], dataDir=[/tmp/solr.client.solrj.impl.CloudSolrClientTest_E8E9E3DAA88BFADD-001/tempDir-001/node3/perReplicaState_testv2_shard1_replica_n1/data/]
+7054 INFO (qtp965371294-160) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
+7054 INFO (qtp965371294-160) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
+7057 INFO (qtp965371294-160) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.u.CommitTracker Hard AutoCommit: disabled
+7057 INFO (qtp965371294-160) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.u.CommitTracker Soft AutoCommit: disabled
+7058 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_testv2 s:shard2 r:core_node8 x:perReplicaState_testv2_shard2_replica_n7] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
+7058 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_testv2 s:shard2 r:core_node8 x:perReplicaState_testv2_shard2_replica_n7] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
+7060 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_testv2 s:shard2 r:core_node8 x:perReplicaState_testv2_shard2_replica_n7] o.a.s.u.CommitTracker Hard AutoCommit: disabled
+7060 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_testv2 s:shard2 r:core_node8 x:perReplicaState_testv2_shard2_replica_n7] o.a.s.u.CommitTracker Soft AutoCommit: disabled
+7060 INFO (qtp965371294-160) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
+7061 INFO (qtp965371294-160) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
+7063 INFO (qtp965371294-160) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
+7066 INFO (qtp965371294-160) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1746410361415794688
+7068 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_testv2 s:shard2 r:core_node8 x:perReplicaState_testv2_shard2_replica_n7] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
+7069 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_testv2 s:shard2 r:core_node8 x:perReplicaState_testv2_shard2_replica_n7] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
+7069 INFO (searcherExecutor-131-thread-1-processing-127.0.0.1:41223_solr perReplicaState_testv2_shard2_replica_n5 perReplicaState_testv2 shard2 core_node6) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.c.SolrCore Registered new searcher autowarm time: 0 ms
+7070 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_testv2 s:shard2 r:core_node8 x:perReplicaState_testv2_shard2_replica_n7] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
+7070 INFO (qtp1534462063-63) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
+7070 INFO (qtp1534462063-63) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
+7070 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_testv2 s:shard2 r:core_node8 x:perReplicaState_testv2_shard2_replica_n7] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1746410361419988992
+7072 INFO (qtp1534462063-63) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.u.CommitTracker Hard AutoCommit: disabled
+7072 INFO (qtp1534462063-63) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.u.CommitTracker Soft AutoCommit: disabled
+7076 INFO (qtp1534462063-63) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
+7076 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:perReplicaState_testv2 s:shard1 r:core_node2 x:perReplicaState_testv2_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
+7076 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:perReplicaState_testv2 s:shard1 r:core_node2 x:perReplicaState_testv2_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
+7076 INFO (searcherExecutor-133-thread-1-processing-127.0.0.1:36493_solr perReplicaState_testv2_shard2_replica_n7 perReplicaState_testv2 shard2 core_node8) [n:127.0.0.1:36493_solr c:perReplicaState_testv2 s:shard2 r:core_node8 x:perReplicaState_testv2_shard2_replica_n7] o.a.s.c.SolrCore Registered new searcher autowarm time: 0 ms
+7078 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:perReplicaState_testv2 s:shard1 r:core_node2 x:perReplicaState_testv2_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
+7078 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:perReplicaState_testv2 s:shard1 r:core_node2 x:perReplicaState_testv2_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
+7083 INFO (qtp1534462063-63) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
+7083 INFO (qtp1534462063-63) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
+7083 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:perReplicaState_testv2 s:shard1 r:core_node2 x:perReplicaState_testv2_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
+7083 INFO (qtp1534462063-63) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1746410361433620480
+7084 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:perReplicaState_testv2 s:shard1 r:core_node2 x:perReplicaState_testv2_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
+7084 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:perReplicaState_testv2 s:shard1 r:core_node2 x:perReplicaState_testv2_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
+7084 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:perReplicaState_testv2 s:shard1 r:core_node2 x:perReplicaState_testv2_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1746410361434669056
+7087 INFO (searcherExecutor-135-thread-1-processing-127.0.0.1:45559_solr perReplicaState_testv2_shard1_replica_n3 perReplicaState_testv2 shard1 core_node4) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.c.SolrCore Registered new searcher autowarm time: 0 ms
+7088 INFO (searcherExecutor-137-thread-1-processing-127.0.0.1:43371_solr perReplicaState_testv2_shard1_replica_n1 perReplicaState_testv2 shard1 core_node2) [n:127.0.0.1:43371_solr c:perReplicaState_testv2 s:shard1 r:core_node2 x:perReplicaState_testv2_shard1_replica_n1] o.a.s.c.SolrCore Registered new searcher autowarm time: 0 ms
+7089 INFO (qtp965371294-160) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.c.ZkShardTerms Successful update of terms at /collections/perReplicaState_testv2/terms/shard2 to Terms{values={core_node6=0}, version=0} for registerTerm
+7090 INFO (qtp965371294-160) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/perReplicaState_testv2/leaders/shard2
+7094 INFO (qtp1534462063-63) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.c.ZkShardTerms Successful update of terms at /collections/perReplicaState_testv2/terms/shard1 to Terms{values={core_node4=0}, version=0} for registerTerm
+7096 INFO (qtp1534462063-63) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/perReplicaState_testv2/leaders/shard1
+7097 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_testv2 s:shard2 r:core_node8 x:perReplicaState_testv2_shard2_replica_n7] o.a.s.c.ZkShardTerms Successful update of terms at /collections/perReplicaState_testv2/terms/shard2 to Terms{values={core_node6=0, core_node8=0}, version=1} for registerTerm
+7098 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr c:perReplicaState_testv2 s:shard2 r:core_node8 x:perReplicaState_testv2_shard2_replica_n7] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/perReplicaState_testv2/leaders/shard2
+7103 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:perReplicaState_testv2 s:shard1 r:core_node2 x:perReplicaState_testv2_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/perReplicaState_testv2/terms/shard1 to Terms{values={core_node2=0, core_node4=0}, version=1} for registerTerm
+7103 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr c:perReplicaState_testv2 s:shard1 r:core_node2 x:perReplicaState_testv2_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/perReplicaState_testv2/leaders/shard1
+7103 INFO (qtp965371294-160) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
+7103 INFO (qtp965371294-160) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
+7103 INFO (qtp965371294-160) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:41223/solr/perReplicaState_testv2_shard2_replica_n5/
+7104 INFO (qtp965371294-160) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.u.PeerSync PeerSync: core=perReplicaState_testv2_shard2_replica_n5 url=http://127.0.0.1:41223/solr START replicas=[http://127.0.0.1:36493/solr/perReplicaState_testv2_shard2_replica_n7/] nUpdates=100
+7105 INFO (qtp965371294-160) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.u.PeerSync PeerSync: core=perReplicaState_testv2_shard2_replica_n5 url=http://127.0.0.1:41223/solr DONE. We have no versions. sync failed.
+7106 INFO (qtp1534462063-63) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
+7106 INFO (qtp1534462063-63) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
+7106 INFO (qtp1534462063-63) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:45559/solr/perReplicaState_testv2_shard1_replica_n3/
+7107 INFO (qtp1534462063-63) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.u.PeerSync PeerSync: core=perReplicaState_testv2_shard1_replica_n3 url=http://127.0.0.1:45559/solr START replicas=[http://127.0.0.1:43371/solr/perReplicaState_testv2_shard1_replica_n1/] nUpdates=100
+7108 INFO (qtp1534462063-63) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.u.PeerSync PeerSync: core=perReplicaState_testv2_shard1_replica_n3 url=http://127.0.0.1:45559/solr DONE. We have no versions. sync failed.
+7109 INFO (qtp492762252-53) [n:127.0.0.1:43371_solr c:perReplicaState_testv2 s:shard1 r:core_node2 x:perReplicaState_testv2_shard1_replica_n1] o.a.s.c.S.Request webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
+7109 INFO (qtp428470070-64) [n:127.0.0.1:36493_solr c:perReplicaState_testv2 s:shard2 r:core_node8 x:perReplicaState_testv2_shard2_replica_n7] o.a.s.c.S.Request webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
+7112 INFO (qtp965371294-160) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
+7112 INFO (qtp965371294-160) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
+7112 INFO (qtp965371294-160) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/perReplicaState_testv2/leaders/shard2/leader after winning as /collections/perReplicaState_testv2/leader_elect/shard2/election/72058663205601292-core_node6-n_0000000000
+7113 INFO (qtp1534462063-63) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
+7113 INFO (qtp1534462063-63) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
+7113 INFO (qtp1534462063-63) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/perReplicaState_testv2/leaders/shard1/leader after winning as /collections/perReplicaState_testv2/leader_elect/shard1/election/72058663205601288-core_node4-n_0000000000
+7118 INFO (qtp965371294-160) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:41223/solr/perReplicaState_testv2_shard2_replica_n5/ shard2
+7118 INFO (qtp1534462063-63) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:45559/solr/perReplicaState_testv2_shard1_replica_n3/ shard1
+7122 INFO (qtp1534462063-63) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.c.ZkController I am the leader, no recovery necessary
+7123 INFO (qtp965371294-160) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.c.ZkController I am the leader, no recovery necessary
+7134 INFO (qtp1534462063-63) [n:127.0.0.1:45559_solr] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=perReplicaState_testv2_shard1_replica_n3&action=CREATE&numShards=2&collection=perReplicaState_testv2&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=135
+7136 INFO (qtp965371294-160) [n:127.0.0.1:41223_solr] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=perReplicaState_testv2_shard2_replica_n5&action=CREATE&numShards=2&collection=perReplicaState_testv2&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=138
+7448 INFO (recoveryExecutor-115-thread-1-processing-127.0.0.1:41223_solr perReplicaState_test_shard1_replica_n9 perReplicaState_test shard1 core_node10) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.c.RecoveryStrategy Attempting to PeerSync from [http://127.0.0.1:36493/solr/perReplicaState_test_shard1_replica_n5/] - recoveringAfterStartup=[true]
+7453 WARN (recoveryExecutor-115-thread-1-processing-127.0.0.1:41223_solr perReplicaState_test_shard1_replica_n9 perReplicaState_test shard1 core_node10) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.u.PeerSyncWithLeader no frame of reference to tell if we've missed updates
+7454 INFO (recoveryExecutor-115-thread-1-processing-127.0.0.1:41223_solr perReplicaState_test_shard1_replica_n9 perReplicaState_test shard1 core_node10) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.c.RecoveryStrategy PeerSync Recovery was not successful - trying replication.
+7454 INFO (recoveryExecutor-115-thread-1-processing-127.0.0.1:41223_solr perReplicaState_test_shard1_replica_n9 perReplicaState_test shard1 core_node10) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.c.RecoveryStrategy Starting Replication Recovery.
+7454 INFO (recoveryExecutor-115-thread-1-processing-127.0.0.1:41223_solr perReplicaState_test_shard1_replica_n9 perReplicaState_test shard1 core_node10) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.c.RecoveryStrategy Attempting to replicate from [http://127.0.0.1:36493/solr/perReplicaState_test_shard1_replica_n5/].
+7567 INFO (qtp428470070-62) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard2 r:core_node4 x:perReplicaState_test_shard2_replica_n3] o.a.s.c.S.Request webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=false&commit=true&softCommit=false&distrib.from=http://127.0.0.1:43371/solr/perReplicaState_test_shard2_replica_n1/&commit_end_point=replicas&wt=javabin&version=2&expungeDeletes=false} status=0 QTime=7
+7573 INFO (qtp492762252-53) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.c.S.Request webapp=/solr path=/update params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=false&commit=true&softCommit=false&distrib.from=http://127.0.0.1:36493/solr/perReplicaState_test_shard1_replica_n5/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false} status=0 QTime=37
+7618 INFO (qtp1534462063-63) [n:127.0.0.1:45559_solr c:perReplicaState_test s:shard1 r:core_node8 x:perReplicaState_test_shard1_replica_n7] o.a.s.c.S.Request webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=false&commit=true&softCommit=false&distrib.from=http://127.0.0.1:36493/solr/perReplicaState_test_shard1_replica_n5/&commit_end_point=replicas&wt=javabin&version=2&expungeDeletes=false} status=0 QTime=11
+7619 INFO (qtp965371294-159) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: BUFFERING replay: false
+7619 INFO (qtp965371294-159) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.c.S.Request webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=false&commit=true&softCommit=false&distrib.from=http://127.0.0.1:36493/solr/perReplicaState_test_shard1_replica_n5/&commit_end_point=replicas&wt=javabin&version=2&expungeDeletes=false} status=0 QTime=8
+7626 INFO (qtp428470070-58) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.c.S.Request webapp=/solr path=/update params={waitSearcher=true&openSearcher=false&commit=true&softCommit=false&wt=javabin&version=2} status=0 QTime=164
+7651 INFO (qtp428470070-64) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.c.S.Request webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=1
+7653 INFO (recoveryExecutor-115-thread-1-processing-127.0.0.1:41223_solr perReplicaState_test_shard1_replica_n9 perReplicaState_test shard1 core_node10) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.h.IndexFetcher Leader's generation: 1
+7654 INFO (recoveryExecutor-115-thread-1-processing-127.0.0.1:41223_solr perReplicaState_test_shard1_replica_n9 perReplicaState_test shard1 core_node10) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.h.IndexFetcher Leader's version: 0
+7654 INFO (recoveryExecutor-115-thread-1-processing-127.0.0.1:41223_solr perReplicaState_test_shard1_replica_n9 perReplicaState_test shard1 core_node10) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.h.IndexFetcher Follower's generation: 1
+7654 INFO (recoveryExecutor-115-thread-1-processing-127.0.0.1:41223_solr perReplicaState_test_shard1_replica_n9 perReplicaState_test shard1 core_node10) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.h.IndexFetcher Follower's version: 0
+7654 INFO (recoveryExecutor-115-thread-1-processing-127.0.0.1:41223_solr perReplicaState_test_shard1_replica_n9 perReplicaState_test shard1 core_node10) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.h.IndexFetcher New index in Leader. Deleting mine...
+7675 INFO (searcherExecutor-124-thread-1-processing-127.0.0.1:41223_solr perReplicaState_test_shard1_replica_n9 perReplicaState_test shard1 core_node10) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.c.SolrCore Registered new searcher autowarm time: 2 ms
+7683 INFO (recoveryExecutor-115-thread-1-processing-127.0.0.1:41223_solr perReplicaState_test_shard1_replica_n9 perReplicaState_test shard1 core_node10) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.c.RecoveryStrategy No replay needed.
+7683 INFO (recoveryExecutor-115-thread-1-processing-127.0.0.1:41223_solr perReplicaState_test_shard1_replica_n9 perReplicaState_test shard1 core_node10) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.c.RecoveryStrategy Replication Recovery was successful.
+7683 INFO (recoveryExecutor-115-thread-1-processing-127.0.0.1:41223_solr perReplicaState_test_shard1_replica_n9 perReplicaState_test shard1 core_node10) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.c.RecoveryStrategy Registering as Active after recovery.
+7688 INFO (zkCallback-40-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [4])
+7688 INFO (zkCallback-116-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [4])
+7688 INFO (zkCallback-116-thread-2) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [4])
+7688 INFO (zkCallback-43-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [4])
+7688 INFO (zkCallback-39-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [4])
+7688 INFO (zkCallback-116-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [4])
+7688 INFO (zkCallback-116-thread-4) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [4])
+7689 INFO (recoveryExecutor-115-thread-1-processing-127.0.0.1:41223_solr perReplicaState_test_shard1_replica_n9 perReplicaState_test shard1 core_node10) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.c.RecoveryStrategy Updating version bucket highest from index after successful recovery.
+7689 INFO (recoveryExecutor-115-thread-1-processing-127.0.0.1:41223_solr perReplicaState_test_shard1_replica_n9 perReplicaState_test shard1 core_node10) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1746410362069057536
+7705 INFO (recoveryExecutor-115-thread-1-processing-127.0.0.1:41223_solr perReplicaState_test_shard1_replica_n9 perReplicaState_test shard1 core_node10) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.c.RecoveryStrategy Finished recovery process, successful=[true] msTimeTaken=797.0
+7706 INFO (recoveryExecutor-115-thread-1-processing-127.0.0.1:41223_solr perReplicaState_test_shard1_replica_n9 perReplicaState_test shard1 core_node10) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.c.RecoveryStrategy Finished recovery process. recoveringAfterStartup=true msTimeTaken=798.0
+8121 INFO (zkCallback-40-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [4])
+8121 INFO (zkCallback-116-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [4])
+8127 INFO (zkCallback-116-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [4])
+8128 INFO (zkCallback-40-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [4])
+8139 INFO (qtp428470070-60) [n:127.0.0.1:36493_solr] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=perReplicaState_testv2_shard2_replica_n7&action=CREATE&numShards=2&collection=perReplicaState_testv2&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1140
+8148 INFO (qtp492762252-51) [n:127.0.0.1:43371_solr] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=perReplicaState_testv2_shard1_replica_n1&action=CREATE&numShards=2&collection=perReplicaState_testv2&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1150
+8158 INFO (qtp1534462063-57) [n:127.0.0.1:45559_solr] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
+8159 INFO (qtp1534462063-57) [n:127.0.0.1:45559_solr] o.a.s.s.HttpSolrCall [admin] webapp=null path=/collections params={wt=javabin&version=2} status=0 QTime=1240
+8161 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: perReplicaState_testv2
+8199 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.e.j.s.AbstractConnector Stopped ServerConnector@100fc9c2{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
+8202 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@577014f6{/solr,null,STOPPED}
+8202 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.e.j.s.session node0 Stopped scavenging
+8205 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.a.s.c.CoreContainer Shutting down CoreContainer instance=943665682
+8206 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:41223_solr
+8210 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.a.s.c.ZkController Publish this node as DOWN...
+8210 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.a.s.c.ZkController Publish node=127.0.0.1:41223_solr as DOWN
+8211 INFO (zkCallback-63-thread-1) [] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
+8211 INFO (zkCallback-43-thread-1) [] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
+8212 INFO (zkCallback-39-thread-1) [] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
+8212 INFO (zkCallback-40-thread-1) [] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
+8212 INFO (zkCallback-116-thread-1) [] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
+8219 INFO (zkCallback-40-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [3])
+8219 INFO (zkCallback-39-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [3])
+8219 INFO (zkCallback-116-thread-4) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [3])
+8220 INFO (zkCallback-43-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [3])
+8220 INFO (zkCallback-116-thread-2) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [3])
+8220 INFO (zkCallback-116-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [3])
+8220 INFO (zkCallback-116-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [3])
+8228 INFO (zkCallback-40-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [3])
+8228 INFO (zkCallback-43-thread-2) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [3])
+8228 INFO (zkCallback-40-thread-2) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [3])
+8228 INFO (zkCallback-40-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [3])
+8228 INFO (zkCallback-39-thread-2) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [3])
+8228 INFO (zkCallback-39-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [3])
+8228 INFO (zkCallback-116-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [3])
+8228 INFO (zkCallback-43-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [3])
+8229 INFO (zkCallback-116-thread-2) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [3])
+8229 INFO (zkCallback-116-thread-4) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [3])
+8229 INFO (zkCallback-116-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [3])
+8230 INFO (zkCallback-116-thread-5) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [3])
+8239 INFO (zkCallback-43-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [3])
+8239 INFO (zkCallback-40-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [3])
+8239 INFO (zkCallback-39-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [3])
+8239 INFO (zkCallback-116-thread-5) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [3])
+8243 INFO (zkCallback-43-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [3])
+8244 INFO (zkCallback-40-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [3])
+8244 INFO (zkCallback-43-thread-2) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [3])
+8244 INFO (zkCallback-39-thread-2) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [3])
+8245 INFO (zkCallback-116-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [3])
+8245 INFO (zkCallback-39-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [3])
+8245 INFO (zkCallback-116-thread-5) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [3])
+8247 INFO (zkCallback-40-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [3])
+8265 INFO (coreCloseExecutor-156-thread-1) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.c.SolrCore CLOSING SolrCore org.apache.solr.core.SolrCore@36fbad88 perReplicaState_test_shard1_replica_n9
+8265 INFO (coreCloseExecutor-156-thread-2) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.c.SolrCore CLOSING SolrCore org.apache.solr.core.SolrCore@148d08ac perReplicaState_testv2_shard2_replica_n5
+8266 INFO (coreCloseExecutor-156-thread-1) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.perReplicaState_test.shard1.replica_n9 tag=SolrCore@36fbad88
+8288 INFO (coreCloseExecutor-156-thread-1) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.perReplicaState_test.shard1.leader tag=SolrCore@36fbad88
+8288 INFO (coreCloseExecutor-156-thread-2) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.perReplicaState_testv2.shard2.replica_n5 tag=SolrCore@148d08ac
+8294 INFO (coreCloseExecutor-156-thread-1) [n:127.0.0.1:41223_solr c:perReplicaState_test s:shard1 r:core_node10 x:perReplicaState_test_shard1_replica_n9] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
+8310 INFO (coreCloseExecutor-156-thread-2) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.perReplicaState_testv2.shard2.leader tag=SolrCore@148d08ac
+8316 INFO (coreCloseExecutor-156-thread-2) [n:127.0.0.1:41223_solr c:perReplicaState_testv2 s:shard2 r:core_node6 x:perReplicaState_testv2_shard2_replica_n5] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
+8325 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
+8330 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
+8337 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
+8337 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
+8360 WARN (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]-SendThread(127.0.0.1:40675)) [] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10000f8ef5c000c.
+ => EndOfStreamException: Unable to read additional data from server sessionid 0x10000f8ef5c000c, likely server has closed socket
+ at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
+org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10000f8ef5c000c, likely server has closed socket
+ at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.8.0.jar:3.8.0]
+ at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.8.0.jar:3.8.0]
+ at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1282) ~[zookeeper-3.8.0.jar:3.8.0]
+8367 INFO (zkCallback-43-thread-2) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [3])
+8368 INFO (zkCallback-40-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [3])
+8367 INFO (zkCallback-39-thread-2) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [3])
+8368 INFO (zkCallback-43-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [3])
+8368 INFO (zkCallback-39-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [3])
+8368 INFO (zkCallback-40-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [3])
+8373 INFO (zkCallback-39-thread-1) [n:127.0.0.1:36493_solr c:perReplicaState_testv2 s:shard2 r:core_node8 x:perReplicaState_testv2_shard2_replica_n7] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
+8464 INFO (TEST-CloudSolrClientTest.testPerReplicaStateCollection-seed#[E8E9E3DAA88BFADD]) [] o.a.s.SolrTestCaseJ4 ###Ending testPerReplicaStateCollection
+
+Test ignored.
+
+Test ignored.
+
+Test ignored.
+
+Test ignored.
+
+Test ignored.
+
+Test ignored.
+8588 INFO (jetty-closer-159-thread-2) [] o.e.j.s.AbstractConnector Stopped ServerConnector@7d997795{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
+8590 INFO (jetty-closer-159-thread-3) [] o.e.j.s.AbstractConnector Stopped ServerConnector@7fabc003{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
+8590 INFO (jetty-closer-159-thread-2) [] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@68295695{/solr,null,STOPPED}
+8591 INFO (jetty-closer-159-thread-2) [] o.e.j.s.session node0 Stopped scavenging
+8590 INFO (jetty-closer-159-thread-1) [] o.e.j.s.AbstractConnector Stopped ServerConnector@5673084c{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
+8591 INFO (jetty-closer-159-thread-3) [] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@27cb97fb{/solr,null,STOPPED}
+8592 INFO (jetty-closer-159-thread-3) [] o.e.j.s.session node0 Stopped scavenging
+8593 INFO (jetty-closer-159-thread-1) [] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4483d8f2{/solr,null,STOPPED}
+8593 INFO (jetty-closer-159-thread-1) [] o.e.j.s.session node0 Stopped scavenging
+8598 INFO (jetty-closer-159-thread-1) [] o.a.s.c.CoreContainer Shutting down CoreContainer instance=150712800
+8598 INFO (jetty-closer-159-thread-2) [] o.a.s.c.CoreContainer Shutting down CoreContainer instance=771093021
+8598 INFO (jetty-closer-159-thread-1) [] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:36493_solr
+8598 INFO (jetty-closer-159-thread-2) [] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:45559_solr
+8599 INFO (jetty-closer-159-thread-3) [] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2024626204
+8599 INFO (jetty-closer-159-thread-3) [] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:43371_solr
+8602 INFO (jetty-closer-159-thread-1) [] o.a.s.c.ZkController Publish this node as DOWN...
+8602 INFO (jetty-closer-159-thread-2) [] o.a.s.c.ZkController Publish this node as DOWN...
+8602 INFO (jetty-closer-159-thread-3) [] o.a.s.c.ZkController Publish this node as DOWN...
+8602 INFO (jetty-closer-159-thread-2) [] o.a.s.c.ZkController Publish node=127.0.0.1:45559_solr as DOWN
+8602 INFO (jetty-closer-159-thread-1) [] o.a.s.c.ZkController Publish node=127.0.0.1:36493_solr as DOWN
+8602 INFO (zkCallback-43-thread-1) [] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (0)
+8602 INFO (zkCallback-39-thread-3) [] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (0)
+8602 INFO (zkCallback-40-thread-1) [] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (0)
+8603 INFO (jetty-closer-159-thread-3) [] o.a.s.c.ZkController Publish node=127.0.0.1:43371_solr as DOWN
+8608 INFO (zkCallback-43-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [0])
+8608 INFO (zkCallback-40-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [0])
+8609 INFO (zkCallback-39-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [0])
+8613 INFO (zkCallback-43-thread-2) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [0])
+8614 INFO (zkCallback-43-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [0])
+8614 INFO (zkCallback-39-thread-2) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [0])
+8614 INFO (zkCallback-40-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [0])
+8614 INFO (zkCallback-39-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [0])
+8614 INFO (zkCallback-40-thread-2) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [0])
+8614 INFO (zkCallback-40-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [0])
+8622 INFO (zkCallback-43-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [0])
+8622 INFO (zkCallback-40-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [0])
+8623 INFO (zkCallback-39-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [0])
+8626 INFO (zkCallback-43-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [0])
+8627 INFO (zkCallback-43-thread-2) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [0])
+8627 INFO (zkCallback-39-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [0])
+8627 INFO (zkCallback-39-thread-2) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [0])
+8627 INFO (zkCallback-40-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [0])
+8627 INFO (zkCallback-40-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [0])
+8628 INFO (zkCallback-43-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [0])
+8629 INFO (zkCallback-40-thread-2) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [0])
+8629 INFO (zkCallback-43-thread-4) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [0])
+8630 INFO (zkCallback-40-thread-4) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [0])
+8632 INFO (zkCallback-40-thread-5) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [0])
+8633 INFO (zkCallback-39-thread-4) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [0])
+8634 INFO (zkCallback-39-thread-5) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [0])
+8648 INFO (coreCloseExecutor-164-thread-1) [n:127.0.0.1:45559_solr c:perReplicaState_test s:shard1 r:core_node8 x:perReplicaState_test_shard1_replica_n7] o.a.s.c.SolrCore CLOSING SolrCore org.apache.solr.core.SolrCore@1126e6ec perReplicaState_test_shard1_replica_n7
+8648 INFO (coreCloseExecutor-164-thread-1) [n:127.0.0.1:45559_solr c:perReplicaState_test s:shard1 r:core_node8 x:perReplicaState_test_shard1_replica_n7] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.perReplicaState_test.shard1.replica_n7 tag=SolrCore@1126e6ec
+8650 INFO (zkCallback-43-thread-4) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [0])
+8651 INFO (zkCallback-40-thread-4) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [0])
+8652 INFO (zkCallback-39-thread-2) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [0])
+8657 INFO (zkCallback-43-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [0])
+8657 INFO (zkCallback-43-thread-4) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [0])
+8657 INFO (zkCallback-40-thread-2) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [0])
+8658 INFO (zkCallback-39-thread-2) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [0])
+8659 INFO (zkCallback-40-thread-4) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [0])
+8659 INFO (zkCallback-40-thread-5) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [0])
+8659 INFO (zkCallback-39-thread-5) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_test/state.json] for collection [perReplicaState_test] has occurred - updating... (live nodes size: [0])
+8660 INFO (coreCloseExecutor-164-thread-2) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.c.SolrCore CLOSING SolrCore org.apache.solr.core.SolrCore@4ba68364 perReplicaState_testv2_shard1_replica_n3
+8661 INFO (coreCloseExecutor-164-thread-1) [n:127.0.0.1:45559_solr c:perReplicaState_test s:shard1 r:core_node8 x:perReplicaState_test_shard1_replica_n7] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.perReplicaState_test.shard1.leader tag=SolrCore@1126e6ec
+8661 INFO (coreCloseExecutor-164-thread-2) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.perReplicaState_testv2.shard1.replica_n3 tag=SolrCore@4ba68364
+8662 INFO (zkCallback-43-thread-2) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [0])
+8662 INFO (zkCallback-43-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [0])
+8664 INFO (coreCloseExecutor-164-thread-1) [n:127.0.0.1:45559_solr c:perReplicaState_test s:shard1 r:core_node8 x:perReplicaState_test_shard1_replica_n7] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
+8666 INFO (zkCallback-39-thread-4) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [0])
+8666 INFO (zkCallback-39-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [0])
+8666 INFO (zkCallback-40-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [0])
+8666 INFO (zkCallback-40-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [0])
+8683 INFO (coreCloseExecutor-164-thread-2) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.perReplicaState_testv2.shard1.leader tag=SolrCore@4ba68364
+8688 INFO (coreCloseExecutor-164-thread-2) [n:127.0.0.1:45559_solr c:perReplicaState_testv2 s:shard1 r:core_node4 x:perReplicaState_testv2_shard1_replica_n3] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
+8697 INFO (zkCallback-43-thread-4) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [0])
+8698 INFO (zkCallback-40-thread-5) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [0])
+8699 INFO (zkCallback-39-thread-5) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [0])
+8700 INFO (jetty-closer-159-thread-2) [] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
+8702 INFO (zkCallback-43-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testPerReplicaStateCollection/state.json] for collection [testPerReplicaStateCollection] has occurred - updating... (live nodes size: [0])
+8704 INFO (jetty-closer-159-thread-2) [] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
+8705 INFO (zkCallback-39-thread-2) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testPerReplicaStateCollection/state.json] for collection [testPerReplicaStateCollection] has occurred - updating... (live nodes size: [0])
+8714 INFO (zkCallback-43-thread-1) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [0])
+8714 INFO (zkCallback-43-thread-4) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [0])
+8714 INFO (zkCallback-39-thread-4) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [0])
+8715 INFO (zkCallback-40-thread-4) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [0])
+8715 INFO (zkCallback-40-thread-3) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [0])
+8716 INFO (zkCallback-39-thread-2) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/perReplicaState_testv2/state.json] for collection [perReplicaState_testv2] has occurred - updating... (live nodes size: [0])
+8717 INFO (coreCloseExecutor-167-thread-1) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.c.SolrCore CLOSING SolrCore org.apache.solr.core.SolrCore@43b3d3d6 testPerReplicaStateCollection_shard2_replica_n1
+8717 INFO (coreCloseExecutor-167-thread-1) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.testPerReplicaStateCollection.shard2.replica_n1 tag=SolrCore@43b3d3d6
+8724 INFO (zkCallback-43-thread-4) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testPerReplicaStateCollection/state.json] for collection [testPerReplicaStateCollection] has occurred - updating... (live nodes size: [0])
+8725 INFO (zkCallback-39-thread-5) [] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testPerReplicaStateCollection/state.json] for collection [testPerReplicaStateCollection] has occurred - updating... (live nodes size: [0])
+8725 INFO (coreCloseExecutor-167-thread-2) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.c.SolrCore CLOSING SolrCore org.apache.solr.core.SolrCore@1e6a82e8 perReplicaState_test_shard1_replica_n5
+8727 INFO (coreCloseExecutor-167-thread-1) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.testPerReplicaStateCollection.shard2.leader tag=SolrCore@43b3d3d6
+8727 INFO (coreCloseExecutor-167-thread-2) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.perReplicaState_test.shard1.replica_n5 tag=SolrCore@1e6a82e8
+8729 INFO (coreCloseExecutor-167-thread-3) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard2 r:core_node4 x:perReplicaState_test_shard2_replica_n3] o.a.s.c.SolrCore CLOSING SolrCore org.apache.solr.core.SolrCore@21fad5cc perReplicaState_test_shard2_replica_n3
+8731 INFO (jetty-closer-159-thread-2) [] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
+8731 INFO (jetty-closer-159-thread-2) [] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
+8739 INFO (coreCloseExecutor-167-thread-1) [n:127.0.0.1:36493_solr c:testPerReplicaStateCollection s:shard2 r:core_node3 x:testPerReplicaStateCollection_shard2_replica_n1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
+8749 INFO (closeThreadPool-169-thread-2) [] o.a.s.c.Overseer Overseer (id=72058663205601288-127.0.0.1:45559_solr-n_0000000000) closing
+8749 INFO (coreCloseExecutor-167-thread-3) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard2 r:core_node4 x:perReplicaState_test_shard2_replica_n3] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.perReplicaState_test.shard2.replica_n3 tag=SolrCore@21fad5cc
+8749 INFO (OverseerStateUpdate-72058663205601288-127.0.0.1:45559_solr-n_0000000000) [n:127.0.0.1:45559_solr] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:45559_solr
+8754 INFO (closeThreadPool-169-thread-1) [] o.a.s.c.Overseer Overseer (id=72058663205601288-127.0.0.1:45559_solr-n_0000000000) closing
+8832 INFO (coreCloseExecutor-170-thread-3) [n:127.0.0.1:43371_solr c:perReplicaState_testv2 s:shard1 r:core_node2 x:perReplicaState_testv2_shard1_replica_n1] o.a.s.c.SolrCore CLOSING SolrCore org.apache.solr.core.SolrCore@7ff13d9c perReplicaState_testv2_shard1_replica_n1
+8838 INFO (coreCloseExecutor-170-thread-3) [n:127.0.0.1:43371_solr c:perReplicaState_testv2 s:shard1 r:core_node2 x:perReplicaState_testv2_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.perReplicaState_testv2.shard1.replica_n1 tag=SolrCore@7ff13d9c
+8838 INFO (coreCloseExecutor-170-thread-2) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.c.SolrCore CLOSING SolrCore org.apache.solr.core.SolrCore@12f29f40 perReplicaState_test_shard2_replica_n1
+8832 INFO (coreCloseExecutor-170-thread-1) [n:127.0.0.1:43371_solr c:testPerReplicaStateCollection s:shard1 r:core_node4 x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.c.SolrCore CLOSING SolrCore org.apache.solr.core.SolrCore@61117296 testPerReplicaStateCollection_shard1_replica_n2
+8843 WARN (jetty-launcher-8-thread-1-SendThread(127.0.0.1:40675)) [] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10000f8ef5c0008.
+ => EndOfStreamException: Unable to read additional data from server sessionid 0x10000f8ef5c0008, likely server has closed socket
+ at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
+org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10000f8ef5c0008, likely server has closed socket
+ at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.8.0.jar:3.8.0]
+ at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.8.0.jar:3.8.0]
+ at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1282) ~[zookeeper-3.8.0.jar:3.8.0]
+8844 INFO (coreCloseExecutor-167-thread-2) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.perReplicaState_test.shard1.leader tag=SolrCore@1e6a82e8
+8845 INFO (coreCloseExecutor-167-thread-3) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard2 r:core_node4 x:perReplicaState_test_shard2_replica_n3] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.perReplicaState_test.shard2.leader tag=SolrCore@21fad5cc
+8849 INFO (coreCloseExecutor-167-thread-2) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard1 r:core_node6 x:perReplicaState_test_shard1_replica_n5] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
+8854 INFO (coreCloseExecutor-170-thread-3) [n:127.0.0.1:43371_solr c:perReplicaState_testv2 s:shard1 r:core_node2 x:perReplicaState_testv2_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.perReplicaState_testv2.shard1.leader tag=SolrCore@7ff13d9c
+8854 INFO (coreCloseExecutor-170-thread-1) [n:127.0.0.1:43371_solr c:testPerReplicaStateCollection s:shard1 r:core_node4 x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.testPerReplicaStateCollection.shard1.replica_n2 tag=SolrCore@61117296
+8857 INFO (coreCloseExecutor-167-thread-3) [n:127.0.0.1:36493_solr c:perReplicaState_test s:shard2 r:core_node4 x:perReplicaState_test_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
+8859 INFO (coreCloseExecutor-170-thread-3) [n:127.0.0.1:43371_solr c:perReplicaState_testv2 s:shard1 r:core_node2 x:perReplicaState_testv2_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
+8864 INFO (jetty-closer-159-thread-1) [] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
+8873 INFO (coreCloseExecutor-170-thread-1) [n:127.0.0.1:43371_solr c:testPerReplicaStateCollection s:shard1 r:core_node4 x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.testPerReplicaStateCollection.shard1.leader tag=SolrCore@61117296
+8873 INFO (coreCloseExecutor-170-thread-2) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.perReplicaState_test.shard2.replica_n1 tag=SolrCore@12f29f40
+8874 INFO (jetty-closer-159-thread-1) [] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
+8876 INFO (coreCloseExecutor-170-thread-1) [n:127.0.0.1:43371_solr c:testPerReplicaStateCollection s:shard1 r:core_node4 x:testPerReplicaStateCollection_shard1_replica_n2] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
+8887 INFO (jetty-closer-159-thread-1) [] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
+8887 INFO (jetty-closer-159-thread-1) [] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
+8890 INFO (coreCloseExecutor-170-thread-2) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.perReplicaState_test.shard2.leader tag=SolrCore@12f29f40
+8894 INFO (coreCloseExecutor-170-thread-2) [n:127.0.0.1:43371_solr c:perReplicaState_test s:shard2 r:core_node2 x:perReplicaState_test_shard2_replica_n1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
+8899 INFO (jetty-closer-159-thread-3) [] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
+8902 INFO (jetty-closer-159-thread-3) [] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
+8904 INFO (jetty-closer-159-thread-3) [] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
+8904 INFO (jetty-closer-159-thread-3) [] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
+8945 INFO (jetty-closer-159-thread-2) [] o.a.s.c.Overseer Overseer (id=72058663205601288-127.0.0.1:45559_solr-n_0000000000) closing
+10873 WARN (zkCallback-39-thread-1) [n:127.0.0.1:36493_solr c:perReplicaState_testv2 s:shard2 r:core_node8 x:perReplicaState_testv2_shard2_replica_n7] o.a.s.c.SyncStrategy Closed, skipping sync up.
+10874 INFO (zkCallback-39-thread-1) [n:127.0.0.1:36493_solr c:perReplicaState_testv2 s:shard2 r:core_node8 x:perReplicaState_testv2_shard2_replica_n7] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
+10874 INFO (zkCallback-39-thread-1) [n:127.0.0.1:36493_solr c:perReplicaState_testv2 s:shard2 r:core_node8 x:perReplicaState_testv2_shard2_replica_n7] o.a.s.c.SolrCore CLOSING SolrCore org.apache.solr.core.SolrCore@3f3c27e perReplicaState_testv2_shard2_replica_n7
+10874 INFO (zkCallback-39-thread-1) [n:127.0.0.1:36493_solr c:perReplicaState_testv2 s:shard2 r:core_node8 x:perReplicaState_testv2_shard2_replica_n7] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.perReplicaState_testv2.shard2.replica_n7 tag=SolrCore@3f3c27e
+10880 INFO (zkCallback-39-thread-1) [n:127.0.0.1:36493_solr c:perReplicaState_testv2 s:shard2 r:core_node8 x:perReplicaState_testv2_shard2_replica_n7] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.perReplicaState_testv2.shard2.leader tag=SolrCore@3f3c27e
+10883 INFO (zkCallback-39-thread-1) [n:127.0.0.1:36493_solr c:perReplicaState_testv2 s:shard2 r:core_node8 x:perReplicaState_testv2_shard2_replica_n7] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
+10890 INFO (zkCallback-39-thread-1) [n:127.0.0.1:36493_solr c:perReplicaState_testv2 s:shard2 r:core_node8 x:perReplicaState_testv2_shard2_replica_n7] o.a.s.c.ShardLeaderElectionContextBase No version found for ephemeral leader parent node, won't remove previous leader registration.
+11000 INFO (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
+11209 WARN (ZkTestServer Run Thread) [] o.a.s.c.ZkTestServer Watch limit violations:
+Maximum concurrent create/delete watches above limit:
+
+ 9 /solr/collections/perReplicaState_test/terms/shard1
+ 5 /solr/clusterprops.json
+ 5 /solr/collections/perReplicaState_testv2/terms/shard1
+ 5 /solr/aliases.json
+ 5 /solr/collections/perReplicaState_testv2/terms/shard2
+ 5 /solr/collections/perReplicaState_test/terms/shard2
+ 4 /solr/packages.json
+ 4 /solr/security.json
+ 4 /solr/configs/conf
+ 4 /solr/collections/perReplicaState_test/collectionprops.json
+ 2 /solr/collections/testPerReplicaStateCollection/terms/shard1
+ 2 /solr/collections/testPerReplicaStateCollection/terms/shard2
+
+Maximum concurrent data watches above limit:
+
+ 65 /solr/collections/perReplicaState_test/state.json
+ 53 /solr/collections/perReplicaState_testv2/state.json
+ 18 /solr/collections/testPerReplicaStateCollection/state.json
+
+Maximum concurrent children watches above limit:
+
+ 57 /solr/collections/perReplicaState_test/state.json
+ 39 /solr/collections/perReplicaState_testv2/state.json
+ 24 /solr/live_nodes
+ 18 /solr/collections
+ 13 /solr/overseer/collection-queue-work
+ 12 /solr/collections/testPerReplicaStateCollection/state.json
+
+11232 INFO (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.u.ErrorLogMuter Closing ErrorLogMuter-regex-1 after mutting 0 log messages
+11232 INFO (SUITE-CloudSolrClientTest-seed#[E8E9E3DAA88BFADD]-worker) [] o.a.s.u.ErrorLogMuter Creating ErrorLogMuter-regex-2 for ERROR logs matching regex: ignore_exception
+
+Process finished with exit code 0
+