You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2017/08/24 20:30:49 UTC
[JENKINS] Lucene-Solr-7.0-Windows (32bit/jdk1.8.0_144) - Build #
108 - Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.0-Windows/108/
Java: 32bit/jdk1.8.0_144 -server -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.cloud.CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster
Error Message:
Document mismatch on target after sync expected:<2000> but was:<1200>
Stack Trace:
java.lang.AssertionError: Document mismatch on target after sync expected:<2000> but was:<1200>
at __randomizedtesting.SeedInfo.seed([3D9ABE974DE0570F:E9DFF5CEAAB6E4F4]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.apache.solr.cloud.CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster(CdcrBootstrapTest.java:309)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 11184 lines...]
[junit4] Suite: org.apache.solr.cloud.CdcrBootstrapTest
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7.0-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTest_3D9ABE974DE0570F-001\init-core-data-001
[junit4] 2> 257465 INFO (SUITE-CdcrBootstrapTest-seed#[3D9ABE974DE0570F]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 257466 INFO (SUITE-CdcrBootstrapTest-seed#[3D9ABE974DE0570F]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 257466 INFO (TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[3D9ABE974DE0570F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBootstrapWithContinousIndexingOnSourceCluster
[junit4] 2> 257467 INFO (TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[3D9ABE974DE0570F]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in C:\Users\jenkins\workspace\Lucene-Solr-7.0-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTest_3D9ABE974DE0570F-001\cdcr-target-001
[junit4] 2> 257467 INFO (TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[3D9ABE974DE0570F]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 257468 INFO (Thread-997) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 257468 INFO (Thread-997) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 257472 ERROR (Thread-997) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 257567 INFO (TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[3D9ABE974DE0570F]) [ ] o.a.s.c.ZkTestServer start zk server on port:55775
[junit4] 2> 257583 INFO (jetty-launcher-300-thread-1) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 257584 INFO (jetty-launcher-300-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1454d21{/solr,null,AVAILABLE}
[junit4] 2> 257588 INFO (jetty-launcher-300-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1158022{HTTP/1.1,[http/1.1]}{127.0.0.1:55779}
[junit4] 2> 257588 INFO (jetty-launcher-300-thread-1) [ ] o.e.j.s.Server Started @260516ms
[junit4] 2> 257588 INFO (jetty-launcher-300-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=55779}
[junit4] 2> 257588 ERROR (jetty-launcher-300-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 257588 INFO (jetty-launcher-300-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 257588 INFO (jetty-launcher-300-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 257590 INFO (jetty-launcher-300-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 257590 INFO (jetty-launcher-300-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-24T19:24:17.416Z
[junit4] 2> 257595 INFO (jetty-launcher-300-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 257610 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15e15b3c8100001, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 257617 INFO (jetty-launcher-300-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55775/solr
[junit4] 2> 257634 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15e15b3c8100002, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 257720 INFO (jetty-launcher-300-thread-1) [n:127.0.0.1:55779_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 257720 INFO (jetty-launcher-300-thread-1) [n:127.0.0.1:55779_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:55779_solr
[junit4] 2> 257721 INFO (jetty-launcher-300-thread-1) [n:127.0.0.1:55779_solr ] o.a.s.c.Overseer Overseer (id=98540103748550659-127.0.0.1:55779_solr-n_0000000000) starting
[junit4] 2> 257727 INFO (jetty-launcher-300-thread-1) [n:127.0.0.1:55779_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55779_solr
[junit4] 2> 257728 INFO (OverseerStateUpdate-98540103748550659-127.0.0.1:55779_solr-n_0000000000) [n:127.0.0.1:55779_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 257939 INFO (jetty-launcher-300-thread-1) [n:127.0.0.1:55779_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55779.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10e17e
[junit4] 2> 257943 INFO (jetty-launcher-300-thread-1) [n:127.0.0.1:55779_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55779.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10e17e
[junit4] 2> 257944 INFO (jetty-launcher-300-thread-1) [n:127.0.0.1:55779_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55779.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10e17e
[junit4] 2> 257945 INFO (jetty-launcher-300-thread-1) [n:127.0.0.1:55779_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.0-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTest_3D9ABE974DE0570F-001\cdcr-target-001\node1\.
[junit4] 1> Target zkHost = 127.0.0.1:55775/solr
[junit4] 2> 257971 INFO (TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[3D9ABE974DE0570F]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in C:\Users\jenkins\workspace\Lucene-Solr-7.0-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTest_3D9ABE974DE0570F-001\cdcr-source-001
[junit4] 2> 257971 INFO (TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[3D9ABE974DE0570F]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 257972 INFO (Thread-1007) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 257972 INFO (Thread-1007) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 257975 ERROR (Thread-1007) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 258073 INFO (TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[3D9ABE974DE0570F]) [ ] o.a.s.c.ZkTestServer start zk server on port:55799
[junit4] 2> 258088 INFO (jetty-launcher-312-thread-1) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 258089 INFO (jetty-launcher-312-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@986778{/solr,null,AVAILABLE}
[junit4] 2> 258091 INFO (jetty-launcher-312-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@85f03e{HTTP/1.1,[http/1.1]}{127.0.0.1:55803}
[junit4] 2> 258091 INFO (jetty-launcher-312-thread-1) [ ] o.e.j.s.Server Started @261019ms
[junit4] 2> 258091 INFO (jetty-launcher-312-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=55803}
[junit4] 2> 258091 ERROR (jetty-launcher-312-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 258091 INFO (jetty-launcher-312-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 258093 INFO (jetty-launcher-312-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 258093 INFO (jetty-launcher-312-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 258093 INFO (jetty-launcher-312-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-24T19:24:17.919Z
[junit4] 2> 258099 INFO (jetty-launcher-312-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 258111 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15e15b3ca090001, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 258115 INFO (jetty-launcher-312-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55799/solr
[junit4] 2> 258118 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15e15b3ca090002, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 258206 INFO (jetty-launcher-312-thread-1) [n:127.0.0.1:55803_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 258207 INFO (jetty-launcher-312-thread-1) [n:127.0.0.1:55803_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:55803_solr
[junit4] 2> 258208 INFO (jetty-launcher-312-thread-1) [n:127.0.0.1:55803_solr ] o.a.s.c.Overseer Overseer (id=98540103781646339-127.0.0.1:55803_solr-n_0000000000) starting
[junit4] 2> 258213 INFO (jetty-launcher-312-thread-1) [n:127.0.0.1:55803_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55803_solr
[junit4] 2> 258214 INFO (OverseerStateUpdate-98540103781646339-127.0.0.1:55803_solr-n_0000000000) [n:127.0.0.1:55803_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 258396 INFO (jetty-launcher-312-thread-1) [n:127.0.0.1:55803_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55803.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10e17e
[junit4] 2> 258679 INFO (jetty-launcher-312-thread-1) [n:127.0.0.1:55803_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55803.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10e17e
[junit4] 2> 258679 INFO (jetty-launcher-312-thread-1) [n:127.0.0.1:55803_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55803.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10e17e
[junit4] 2> 258680 INFO (jetty-launcher-312-thread-1) [n:127.0.0.1:55803_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.0-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTest_3D9ABE974DE0570F-001\cdcr-source-001\node1\.
[junit4] 2> 258735 INFO (TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[3D9ABE974DE0570F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 258736 INFO (TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[3D9ABE974DE0570F]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55799/solr ready
[junit4] 2> 258738 INFO (qtp26336562-2702) [n:127.0.0.1:55803_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=cdcr-source&name=cdcr-source&nrtReplicas=1&action=CREATE&numShards=1&property.solr.directoryFactory=solr.StandardDirectoryFactory&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 258741 INFO (OverseerThreadFactory-1282-thread-1-processing-n:127.0.0.1:55803_solr) [n:127.0.0.1:55803_solr ] o.a.s.c.CreateCollectionCmd Create collection cdcr-source
[junit4] 2> 258846 INFO (OverseerStateUpdate-98540103781646339-127.0.0.1:55803_solr-n_0000000000) [n:127.0.0.1:55803_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"cdcr-source",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"cdcr-source_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:55803/solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 259053 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=cdcr-source&newCollection=true&collection=cdcr-source&version=2&replicaType=NRT&coreNodeName=core_node1&name=cdcr-source_shard1_replica_n1&action=CREATE&numShards=1&shard=shard1&property.solr.directoryFactory=solr.StandardDirectoryFactory&wt=javabin
[junit4] 2> 259054 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 259165 INFO (zkCallback-319-thread-1-processing-n:127.0.0.1:55803_solr) [n:127.0.0.1:55803_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/cdcr-source/state.json] for collection [cdcr-source] has occurred - updating... (live nodes size: [1])
[junit4] 2> 259167 INFO (zkCallback-319-thread-2-processing-n:127.0.0.1:55803_solr) [n:127.0.0.1:55803_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/cdcr-source/state.json] for collection [cdcr-source] has occurred - updating... (live nodes size: [1])
[junit4] 2> 260078 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 260083 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 260085 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.s.IndexSchema [cdcr-source_shard1_replica_n1] Schema name=minimal
[junit4] 2> 260088 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 260093 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at /configs/cdcr-source/managed-schema
[junit4] 2> 260096 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in ZooKeeper, renamed the non-managed schema /configs/cdcr-source/schema.xml to /configs/cdcr-source/schema.xml.bak
[junit4] 2> 260096 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'cdcr-source_shard1_replica_n1' using configuration from collection cdcr-source, trusted=true
[junit4] 2> 260096 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55803.solr.core.cdcr-source.shard1.replica_n1' (registry 'solr.core.cdcr-source.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10e17e
[junit4] 2> 260096 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 260096 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [[cdcr-source_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.0-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTest_3D9ABE974DE0570F-001\cdcr-source-001\node1\cdcr-source_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.0-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTest_3D9ABE974DE0570F-001\cdcr-source-001\node1\.\cdcr-source_shard1_replica_n1\data\]
[junit4] 2> 260256 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog
[junit4] 2> 260256 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 260258 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 260258 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 260263 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1362f08[cdcr-source_shard1_replica_n1] main]
[junit4] 2> 260265 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/cdcr-source
[junit4] 2> 260266 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/cdcr-source
[junit4] 2> 260266 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/cdcr-source/managed-schema
[junit4] 2> 260266 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 260267 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 260276 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrBufferStateManager Created znode /collections/cdcr-source/cdcr/state/buffer
[junit4] 2> 260283 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrProcessStateManager Created znode /collections/cdcr-source/cdcr/state/process
[junit4] 2> 260315 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1576641662960009216
[junit4] 2> 260315 INFO (searcherExecutor-1285-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_replica_n1] Registered new searcher Searcher@1362f08[cdcr-source_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 260323 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 260323 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 260323 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:55803/solr/cdcr-source_shard1_replica_n1/
[junit4] 2> 260323 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 260323 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:55803/solr/cdcr-source_shard1_replica_n1/ has no replicas
[junit4] 2> 260323 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 260328 INFO (zkCallback-319-thread-2-processing-n:127.0.0.1:55803_solr) [n:127.0.0.1:55803_solr ] o.a.s.h.CdcrLeaderStateManager Received new leader state @ cdcr-source:shard1
[junit4] 2> 260329 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:55803/solr/cdcr-source_shard1_replica_n1/ shard1
[junit4] 2> 260435 INFO (zkCallback-319-thread-2-processing-n:127.0.0.1:55803_solr) [n:127.0.0.1:55803_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/cdcr-source/state.json] for collection [cdcr-source] has occurred - updating... (live nodes size: [1])
[junit4] 2> 260435 INFO (zkCallback-319-thread-1-processing-n:127.0.0.1:55803_solr) [n:127.0.0.1:55803_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/cdcr-source/state.json] for collection [cdcr-source] has occurred - updating... (live nodes size: [1])
[junit4] 2> 260441 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 260443 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=cdcr-source&newCollection=true&collection=cdcr-source&version=2&replicaType=NRT&coreNodeName=core_node1&name=cdcr-source_shard1_replica_n1&action=CREATE&numShards=1&shard=shard1&property.solr.directoryFactory=solr.StandardDirectoryFactory&wt=javabin} status=0 QTime=1389
[junit4] 2> 260447 INFO (qtp26336562-2702) [n:127.0.0.1:55803_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 260546 INFO (zkCallback-319-thread-1-processing-n:127.0.0.1:55803_solr) [n:127.0.0.1:55803_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/cdcr-source/state.json] for collection [cdcr-source] has occurred - updating... (live nodes size: [1])
[junit4] 2> 260546 INFO (zkCallback-319-thread-2-processing-n:127.0.0.1:55803_solr) [n:127.0.0.1:55803_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/cdcr-source/state.json] for collection [cdcr-source] has occurred - updating... (live nodes size: [1])
[junit4] 2> 260743 INFO (OverseerCollectionConfigSetProcessor-98540103781646339-127.0.0.1:55803_solr-n_0000000000) [n:127.0.0.1:55803_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 261448 INFO (qtp26336562-2702) [n:127.0.0.1:55803_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=cdcr-source&name=cdcr-source&nrtReplicas=1&action=CREATE&numShards=1&property.solr.directoryFactory=solr.StandardDirectoryFactory&wt=javabin&version=2} status=0 QTime=2709
[junit4] 1> Adding 10 docs with commit=true, numDocs=100
[junit4] 2> 261475 INFO (qtp26336562-2705) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-source:4&wt=javabin&version=2} status=0 QTime=22
[junit4] 2> 261476 INFO (qtp26336562-2706) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1576641664177405952,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 261476 INFO (qtp26336562-2706) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3b6bc commitCommandVersion:1576641664177405952
[junit4] 2> 261500 INFO (qtp26336562-2706) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@139a629[cdcr-source_shard1_replica_n1] main]
[junit4] 2> 261501 INFO (qtp26336562-2706) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 261501 INFO (searcherExecutor-1285-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_replica_n1] Registered new searcher Searcher@139a629[cdcr-source_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C100)))}
[junit4] 2> 261502 INFO (qtp26336562-2706) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-source:4&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} status=0 QTime=25
[junit4] 1> Adding 10 docs with commit=true, numDocs=200
[junit4] 2> 261508 INFO (qtp26336562-2707) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-source:4&wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 261509 INFO (qtp26336562-2700) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1576641664212008960,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 261510 INFO (qtp26336562-2700) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3b6bc commitCommandVersion:1576641664212008960
[junit4] 2> 261523 INFO (qtp26336562-2700) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@34bc7d[cdcr-source_shard1_replica_n1] main]
[junit4] 2> 261523 INFO (qtp26336562-2700) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 261524 INFO (searcherExecutor-1285-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_replica_n1] Registered new searcher Searcher@34bc7d[cdcr-source_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C100) Uninverting(_1(7.0.0):C100)))}
[junit4] 2> 261524 INFO (qtp26336562-2700) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-source:4&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} status=0 QTime=15
[junit4] 1> Adding 10 docs with commit=true, numDocs=300
[junit4] 2> 261530 INFO (qtp26336562-2704) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-source:4&wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 261530 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1576641664234029056,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 261531 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3b6bc commitCommandVersion:1576641664234029056
[junit4] 2> 261547 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@194059a[cdcr-source_shard1_replica_n1] main]
[junit4] 2> 261547 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 261548 INFO (searcherExecutor-1285-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_replica_n1] Registered new searcher Searcher@194059a[cdcr-source_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C100) Uninverting(_1(7.0.0):C100) Uninverting(_2(7.0.0):C100)))}
[junit4] 2> 261548 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-source:4&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} status=0 QTime=18
[junit4] 1> Adding 10 docs with commit=true, numDocs=400
[junit4] 2> 261553 INFO (qtp26336562-2702) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-source:4&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 261554 INFO (qtp26336562-2705) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1576641664259194880,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 261554 INFO (qtp26336562-2705) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3b6bc commitCommandVersion:1576641664259194880
[junit4] 2> 261572 INFO (qtp26336562-2705) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1678afd[cdcr-source_shard1_replica_n1] main]
[junit4] 2> 261573 INFO (qtp26336562-2705) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 261573 INFO (searcherExecutor-1285-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_replica_n1] Registered new searcher Searcher@1678afd[cdcr-source_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C100) Uninverting(_1(7.0.0):C100) Uninverting(_2(7.0.0):C100) Uninverting(_3(7.0.0):C100)))}
[junit4] 2> 261574 INFO (qtp26336562-2705) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-source:4&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} status=0 QTime=20
[junit4] 1> Adding 10 docs with commit=true, numDocs=500
[junit4] 2> 261580 INFO (qtp26336562-2706) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-source:4&wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 261580 INFO (qtp26336562-2707) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1576641664286457856,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 261580 INFO (qtp26336562-2707) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3b6bc commitCommandVersion:1576641664286457856
[junit4] 2> 261599 INFO (qtp26336562-2707) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1854088[cdcr-source_shard1_replica_n1] main]
[junit4] 2> 261599 INFO (qtp26336562-2707) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 261600 INFO (searcherExecutor-1285-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_replica_n1] Registered new searcher Searcher@1854088[cdcr-source_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C100) Uninverting(_1(7.0.0):C100) Uninverting(_2(7.0.0):C100) Uninverting(_3(7.0.0):C100) Uninverting(_4(7.0.0):C100)))}
[junit4] 2> 261600 INFO (qtp26336562-2707) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-source:4&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} status=0 QTime=19
[junit4] 1> Adding 10 docs with commit=true, numDocs=600
[junit4] 2> 261604 INFO (qtp26336562-2700) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-source:4&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 261605 INFO (qtp26336562-2704) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1576641664312672256,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 261605 INFO (qtp26336562-2704) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3b6bc commitCommandVersion:1576641664312672256
[junit4] 2> 261625 INFO (qtp26336562-2704) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@15d6de0[cdcr-source_shard1_replica_n1] main]
[junit4] 2> 261626 INFO (qtp26336562-2704) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 261626 INFO (searcherExecutor-1285-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_replica_n1] Registered new searcher Searcher@15d6de0[cdcr-source_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C100) Uninverting(_1(7.0.0):C100) Uninverting(_2(7.0.0):C100) Uninverting(_3(7.0.0):C100) Uninverting(_4(7.0.0):C100) Uninverting(_5(7.0.0):C100)))}
[junit4] 2> 261626 INFO (qtp26336562-2704) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-source:4&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} status=0 QTime=21
[junit4] 1> Adding 10 docs with commit=true, numDocs=700
[junit4] 2> 261631 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-source:4&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 261632 INFO (qtp26336562-2702) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1576641664340983808,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 261632 INFO (qtp26336562-2702) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3b6bc commitCommandVersion:1576641664340983808
[junit4] 2> 261653 INFO (qtp26336562-2702) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@de52b3[cdcr-source_shard1_replica_n1] main]
[junit4] 2> 261653 INFO (qtp26336562-2702) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 261654 INFO (searcherExecutor-1285-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_replica_n1] Registered new searcher Searcher@de52b3[cdcr-source_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C100) Uninverting(_1(7.0.0):C100) Uninverting(_2(7.0.0):C100) Uninverting(_3(7.0.0):C100) Uninverting(_4(7.0.0):C100) Uninverting(_5(7.0.0):C100) Uninverting(_6(7.0.0):C100)))}
[junit4] 2> 261654 INFO (qtp26336562-2702) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-source:4&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} status=0 QTime=22
[junit4] 1> Adding 10 docs with commit=true, numDocs=800
[junit4] 2> 261658 INFO (qtp26336562-2705) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-source:4&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 261659 INFO (qtp26336562-2706) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1576641664369295360,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 261659 INFO (qtp26336562-2706) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3b6bc commitCommandVersion:1576641664369295360
[junit4] 2> 261683 INFO (qtp26336562-2706) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@f5d7c4[cdcr-source_shard1_replica_n1] main]
[junit4] 2> 261683 INFO (qtp26336562-2706) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 261684 INFO (searcherExecutor-1285-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_replica_n1] Registered new searcher Searcher@f5d7c4[cdcr-source_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C100) Uninverting(_1(7.0.0):C100) Uninverting(_2(7.0.0):C100) Uninverting(_3(7.0.0):C100) Uninverting(_4(7.0.0):C100) Uninverting(_5(7.0.0):C100) Uninverting(_6(7.0.0):C100) Uninverting(_7(7.0.0):C100)))}
[junit4] 2> 261684 INFO (qtp26336562-2706) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-source:4&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} status=0 QTime=25
[junit4] 1> Adding 10 docs with commit=true, numDocs=900
[junit4] 2> 261685 INFO (qtp26336562-2707) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-source:4&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 261690 INFO (qtp26336562-2700) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1576641664401801216,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 261690 INFO (qtp26336562-2700) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3b6bc commitCommandVersion:1576641664401801216
[junit4] 2> 261716 INFO (qtp26336562-2700) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@25127b[cdcr-source_shard1_replica_n1] main]
[junit4] 2> 261717 INFO (qtp26336562-2700) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 261717 INFO (searcherExecutor-1285-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_replica_n1] Registered new searcher Searcher@25127b[cdcr-source_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C100) Uninverting(_1(7.0.0):C100) Uninverting(_2(7.0.0):C100) Uninverting(_3(7.0.0):C100) Uninverting(_4(7.0.0):C100) Uninverting(_5(7.0.0):C100) Uninverting(_6(7.0.0):C100) Uninverting(_7(7.0.0):C100) Uninverting(_8(7.0.0):C100)))}
[junit4] 2> 261717 INFO (qtp26336562-2700) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-source:4&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} status=0 QTime=27
[junit4] 1> Adding 10 docs with commit=true, numDocs=1000
[junit4] 2> 261721 INFO (qtp26336562-2704) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-source:4&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 261722 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1576641664435355648,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 261722 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3b6bc commitCommandVersion:1576641664435355648
[junit4] 2> 261750 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1102bc4[cdcr-source_shard1_replica_n1] main]
[junit4] 2> 261750 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 261751 INFO (searcherExecutor-1285-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_replica_n1] Registered new searcher Searcher@1102bc4[cdcr-source_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C100) Uninverting(_1(7.0.0):C100) Uninverting(_2(7.0.0):C100) Uninverting(_3(7.0.0):C100) Uninverting(_4(7.0.0):C100) Uninverting(_5(7.0.0):C100) Uninverting(_6(7.0.0):C100) Uninverting(_7(7.0.0):C100) Uninverting(_8(7.0.0):C100) Uninverting(_9(7.0.0):C100)))}
[junit4] 2> 261751 INFO (qtp26336562-2703) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-source:4&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} status=0 QTime=29
[junit4] 2> 261753 INFO (qtp26336562-2702) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=cdcr-source:4&wt=javabin&version=2} hits=1000 status=0 QTime=0
[junit4] 2> 261764 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15e15b3c8100006, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 261767 INFO (TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[3D9ABE974DE0570F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 261768 INFO (TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[3D9ABE974DE0570F]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55775/solr ready
[junit4] 2> 261769 INFO (qtp9541216-2659) [n:127.0.0.1:55779_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=cdcr-target&name=cdcr-target&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 261773 INFO (OverseerThreadFactory-1274-thread-1-processing-n:127.0.0.1:55779_solr) [n:127.0.0.1:55779_solr ] o.a.s.c.CreateCollectionCmd Create collection cdcr-target
[junit4] 2> 261884 INFO (OverseerStateUpdate-98540103748550659-127.0.0.1:55779_solr-n_0000000000) [n:127.0.0.1:55779_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"cdcr-target",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"cdcr-target_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:55779/solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 262086 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node1&collection.configName=cdcr-target&newCollection=true&name=cdcr-target_shard1_replica_n1&action=CREATE&numShards=1&collection=cdcr-target&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 262087 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 262195 INFO (zkCallback-307-thread-1-processing-n:127.0.0.1:55779_solr) [n:127.0.0.1:55779_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/cdcr-target/state.json] for collection [cdcr-target] has occurred - updating... (live nodes size: [1])
[junit4] 2> 262196 INFO (zkCallback-307-thread-2-processing-n:127.0.0.1:55779_solr) [n:127.0.0.1:55779_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/cdcr-target/state.json] for collection [cdcr-target] has occurred - updating... (live nodes size: [1])
[junit4] 2> 263112 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 263115 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 263119 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.s.IndexSchema [cdcr-target_shard1_replica_n1] Schema name=minimal
[junit4] 2> 263124 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 263125 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at /configs/cdcr-target/managed-schema
[junit4] 2> 263131 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in ZooKeeper, renamed the non-managed schema /configs/cdcr-target/schema.xml to /configs/cdcr-target/schema.xml.bak
[junit4] 2> 263131 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'cdcr-target_shard1_replica_n1' using configuration from collection cdcr-target, trusted=true
[junit4] 2> 263131 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55779.solr.core.cdcr-target.shard1.replica_n1' (registry 'solr.core.cdcr-target.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10e17e
[junit4] 2> 263131 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 263131 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.SolrCore [[cdcr-target_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.0-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTest_3D9ABE974DE0570F-001\cdcr-target-001\node1\cdcr-target_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.0-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTest_3D9ABE974DE0570F-001\cdcr-target-001\node1\.\cdcr-target_shard1_replica_n1\data\]
[junit4] 2> 263233 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog
[junit4] 2> 263233 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 263234 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 263234 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 263235 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@205a7e[cdcr-target_shard1_replica_n1] main]
[junit4] 2> 263236 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/cdcr-target
[junit4] 2> 263237 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/cdcr-target
[junit4] 2> 263237 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/cdcr-target/managed-schema
[junit4] 2> 263237 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 263238 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 263241 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.h.CdcrBufferStateManager Created znode /collections/cdcr-target/cdcr/state/buffer
[junit4] 2> 263242 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.h.CdcrProcessStateManager Created znode /collections/cdcr-target/cdcr/state/process
[junit4] 2> 263245 INFO (searcherExecutor-1291-thread-1-processing-n:127.0.0.1:55779_solr x:cdcr-target_shard1_replica_n1 s:shard1 c:cdcr-target r:core_node1) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-target_shard1_replica_n1] Registered new searcher Searcher@205a7e[cdcr-target_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 263245 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1576641666032336896
[junit4] 2> 263251 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 263251 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 263251 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:55779/solr/cdcr-target_shard1_replica_n1/
[junit4] 2> 263251 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 263251 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:55779/solr/cdcr-target_shard1_replica_n1/ has no replicas
[junit4] 2> 263251 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 263253 INFO (zkCallback-307-thread-2-processing-n:127.0.0.1:55779_solr) [n:127.0.0.1:55779_solr ] o.a.s.h.CdcrLeaderStateManager Received new leader state @ cdcr-target:shard1
[junit4] 2> 263255 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:55779/solr/cdcr-target_shard1_replica_n1/ shard1
[junit4] 2> 263358 INFO (zkCallback-307-thread-2-processing-n:127.0.0.1:55779_solr) [n:127.0.0.1:55779_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/cdcr-target/state.json] for collection [cdcr-target] has occurred - updating... (live nodes size: [1])
[junit4] 2> 263358 INFO (zkCallback-307-thread-1-processing-n:127.0.0.1:55779_solr) [n:127.0.0.1:55779_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/cdcr-target/state.json] for collection [cdcr-target] has occurred - updating... (live nodes size: [1])
[junit4] 2> 263406 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 263407 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node1&collection.configName=cdcr-target&newCollection=true&name=cdcr-target_shard1_replica_n1&action=CREATE&numShards=1&collection=cdcr-target&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1321
[junit4] 2> 263412 INFO (qtp9541216-2659) [n:127.0.0.1:55779_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 263514 INFO (zkCallback-307-thread-1-processing-n:127.0.0.1:55779_solr) [n:127.0.0.1:55779_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/cdcr-target/state.json] for collection [cdcr-target] has occurred - updating... (live nodes size: [1])
[junit4] 2> 263514 INFO (zkCallback-307-thread-2-processing-n:127.0.0.1:55779_solr) [n:127.0.0.1:55779_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/cdcr-target/state.json] for collection [cdcr-target] has occurred - updating... (live nodes size: [1])
[junit4] 2> 263776 INFO (OverseerCollectionConfigSetProcessor-98540103748550659-127.0.0.1:55779_solr-n_0000000000) [n:127.0.0.1:55779_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 264414 INFO (qtp9541216-2659) [n:127.0.0.1:55779_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=cdcr-target&name=cdcr-target&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=2644
[junit4] 2> 265421 INFO (zkCallback-307-thread-2-processing-n:127.0.0.1:55779_solr) [n:127.0.0.1:55779_solr ] o.a.s.h.CdcrProcessStateManager The CDCR process state has changed: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/cdcr-target/cdcr/state/process @ cdcr-target:shard1
[junit4] 2> 265422 INFO (qtp9541216-2663) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.S.Request [cdcr-target_shard1_replica_n1] webapp=/solr path=/cdcr params={qt=/cdcr&_stateVer_=cdcr-target:4&action=start&wt=javabin&version=2} status=0 QTime=6
[junit4] 2> 265422 INFO (zkCallback-307-thread-2-processing-n:127.0.0.1:55779_solr) [n:127.0.0.1:55779_solr ] o.a.s.h.CdcrProcessStateManager Received new CDCR process state from watcher: STARTED @ cdcr-target:shard1
[junit4] 2> 265433 INFO (qtp26336562-2702) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 265436 INFO (qtp26336562-2702) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55775/solr ready
[junit4] 2> 265451 INFO (qtp9541216-2661) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.h.CdcrRequestHandler Found maxVersionFromRecent 0 maxVersionFromIndex 0
[junit4] 2> 265451 INFO (qtp9541216-2661) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.S.Request [cdcr-target_shard1_replica_n1] webapp=/solr path=/cdcr params={action=SHARDCHECKPOINT&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 265452 INFO (qtp9541216-2665) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.S.Request [cdcr-target_shard1_replica_n1] webapp=/solr path=/cdcr params={_stateVer_=cdcr-target:4&action=COLLECTIONCHECKPOINT&wt=javabin&version=2} status=0 QTime=14
[junit4] 2> 265453 INFO (qtp26336562-2702) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrReplicatorManager Create new update log reader for target cdcr-target with checkpoint -1 @ cdcr-source:shard1
[junit4] 2> 265453 INFO (qtp26336562-2702) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrReplicatorManager Attempting to bootstrap target collection: cdcr-target, shard: shard1
[junit4] 2> 265460 INFO (qtp26336562-2702) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrReplicatorManager Submitting bootstrap task to executor
[junit4] 2> 265461 INFO (zkCallback-319-thread-2-processing-n:127.0.0.1:55803_solr) [n:127.0.0.1:55803_solr ] o.a.s.h.CdcrProcessStateManager The CDCR process state has changed: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/cdcr-source/cdcr/state/process @ cdcr-source:shard1
[junit4] 2> 265462 INFO (qtp26336562-2702) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/cdcr params={qt=/cdcr&_stateVer_=cdcr-source:4&action=start&wt=javabin&version=2} status=0 QTime=36
[junit4] 1> Adding 10 docs with commit=true, numDocs=1100
[junit4] 2> 265463 INFO (zkCallback-319-thread-2-processing-n:127.0.0.1:55803_solr) [n:127.0.0.1:55803_solr ] o.a.s.h.CdcrProcessStateManager Received new CDCR process state from watcher: STARTED @ cdcr-source:shard1
[junit4] 2> 265463 INFO (cdcr-bootstrap-status-331-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrReplicatorManager Attempting to bootstrap target collection: cdcr-target shard: shard1 leader: http://127.0.0.1:55779/solr/cdcr-target_shard1_replica_n1/
[junit4] 2> 265467 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.S.Request [cdcr-target_shard1_replica_n1] webapp=/solr path=/cdcr params={qt=/cdcr&masterUrl=http://127.0.0.1:55803/solr/cdcr-source_shard1_replica_n1/&action=BOOTSTRAP&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 265473 INFO (qtp26336562-2706) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-source:4&wt=javabin&version=2} status=0 QTime=7
[junit4] 2> 265474 INFO (qtp26336562-2707) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1576641668369612800,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 265474 INFO (qtp26336562-2707) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3b6bc commitCommandVersion:1576641668369612800
[junit4] 2> 265481 INFO (qtp9541216-2663) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.S.Request [cdcr-target_shard1_replica_n1] webapp=/solr path=/cdcr params={qt=/cdcr&action=BOOTSTRAP_STATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 265481 WARN (cdcr-bootstrap-status-331-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrReplicatorManager Bootstrap process was not found on target collection: cdcr-target shard: shard1, leader: http://127.0.0.1:55779/solr/cdcr-target_shard1_replica_n1/
[junit4] 2> 265481 INFO (cdcr-bootstrap-status-331-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrReplicatorManager Attempting to bootstrap target collection: cdcr-target shard: shard1 leader: http://127.0.0.1:55779/solr/cdcr-target_shard1_replica_n1/
[junit4] 2> 265483 INFO (qtp9541216-2663) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.S.Request [cdcr-target_shard1_replica_n1] webapp=/solr path=/cdcr params={qt=/cdcr&masterUrl=http://127.0.0.1:55803/solr/cdcr-source_shard1_replica_n1/&action=BOOTSTRAP&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 265485 INFO (qtp9541216-2663) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.S.Request [cdcr-target_shard1_replica_n1] webapp=/solr path=/cdcr params={qt=/cdcr&action=BOOTSTRAP_STATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 265485 WARN (cdcr-bootstrap-status-331-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrReplicatorManager Bootstrap process was not found on target collection: cdcr-target shard: shard1, leader: http://127.0.0.1:55779/solr/cdcr-target_shard1_replica_n1/
[junit4] 2> 265485 INFO (cdcr-bootstrap-status-331-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrReplicatorManager Attempting to bootstrap target collection: cdcr-target shard: shard1 leader: http://127.0.0.1:55779/solr/cdcr-target_shard1_replica_n1/
[junit4] 2> 265486 INFO (qtp9541216-2663) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.S.Request [cdcr-target_shard1_replica_n1] webapp=/solr path=/cdcr params={qt=/cdcr&masterUrl=http://127.0.0.1:55803/solr/cdcr-source_shard1_replica_n1/&action=BOOTSTRAP&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 265487 INFO (qtp9541216-2665) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.S.Request [cdcr-target_shard1_replica_n1] webapp=/solr path=/cdcr params={qt=/cdcr&action=BOOTSTRAP_STATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 265487 WARN (cdcr-bootstrap-status-331-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrReplicatorManager Bootstrap process was not found on target collection: cdcr-target shard: shard1, leader: http://127.0.0.1:55779/solr/cdcr-target_shard1_replica_n1/
[junit4] 2> 265488 INFO (cdcr-bootstrap-status-331-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrReplicatorManager Attempting to bootstrap target collection: cdcr-target shard: shard1 leader: http://127.0.0.1:55779/solr/cdcr-target_shard1_replica_n1/
[junit4] 2> 265488 INFO (qtp9541216-2665) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.S.Request [cdcr-target_shard1_replica_n1] webapp=/solr path=/cdcr params={qt=/cdcr&masterUrl=http://127.0.0.1:55803/solr/cdcr-source_shard1_replica_n1/&action=BOOTSTRAP&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 265489 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.S.Request [cdcr-target_shard1_replica_n1] webapp=/solr path=/cdcr params={qt=/cdcr&action=BOOTSTRAP_STATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 265489 WARN (cdcr-bootstrap-status-331-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrReplicatorManager Bootstrap process was not found on target collection: cdcr-target shard: shard1, leader: http://127.0.0.1:55779/solr/cdcr-target_shard1_replica_n1/
[junit4] 2> 265490 INFO (cdcr-bootstrap-status-331-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrReplicatorManager Attempting to bootstrap target collection: cdcr-target shard: shard1 leader: http://127.0.0.1:55779/solr/cdcr-target_shard1_replica_n1/
[junit4] 2> 265490 INFO (qtp9541216-2662) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.S.Request [cdcr-target_shard1_replica_n1] webapp=/solr path=/cdcr params={qt=/cdcr&masterUrl=http://127.0.0.1:55803/solr/cdcr-source_shard1_replica_n1/&action=BOOTSTRAP&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 265491 INFO (qtp9541216-2666) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.S.Request [cdcr-target_shard1_replica_n1] webapp=/solr path=/cdcr params={qt=/cdcr&action=BOOTSTRAP_STATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 265491 WARN (cdcr-bootstrap-status-331-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrReplicatorManager Bootstrap process was not found on target collection: cdcr-target shard: shard1, leader: http://127.0.0.1:55779/solr/cdcr-target_shard1_replica_n1/
[junit4] 2> 265492 INFO (cdcr-bootstrap-status-331-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrReplicatorManager Attempting to bootstrap target collection: cdcr-target shard: shard1 leader: http://127.0.0.1:55779/solr/cdcr-target_shard1_replica_n1/
[junit4] 2> 265492 INFO (qtp9541216-2661) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.S.Request [cdcr-target_shard1_replica_n1] webapp=/solr path=/cdcr params={qt=/cdcr&masterUrl=http://127.0.0.1:55803/solr/cdcr-source_shard1_replica_n1/&action=BOOTSTRAP&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 265494 INFO (qtp9541216-2661) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.S.Request [cdcr-target_shard1_replica_n1] webapp=/solr path=/cdcr params={qt=/cdcr&action=BOOTSTRAP_STATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 265494 WARN (cdcr-bootstrap-status-331-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrReplicatorManager Bootstrap process was not found on target collection: cdcr-target shard: shard1, leader: http://127.0.0.1:55779/solr/cdcr-target_shard1_replica_n1/
[junit4] 2> 265495 INFO (cdcr-bootstrap-status-331-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrReplicatorManager Attempting to bootstrap target collection: cdcr-target shard: shard1 leader: http://127.0.0.1:55779/solr/cdcr-target_shard1_replica_n1/
[junit4] 2> 265495 INFO (qtp9541216-2659) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.S.Request [cdcr-target_shard1_replica_n1] webapp=/solr path=/cdcr params={qt=/cdcr&masterUrl=http://127.0.0.1:55803/solr/cdcr-source_shard1_replica_n1/&action=BOOTSTRAP&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 265496 INFO (qtp9541216-2659) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.S.Request [cdcr-target_shard1_replica_n1] webapp=/solr path=/cdcr params={qt=/cdcr&action=BOOTSTRAP_STATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 265496 WARN (cdcr-bootstrap-status-331-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrReplicatorManager Bootstrap process was not found on target collection: cdcr-target shard: shard1, leader: http://127.0.0.1:55779/solr/cdcr-target_shard1_replica_n1/
[junit4] 2> 265496 INFO (cdcr-bootstrap-status-331-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrReplicatorManager Attempting to bootstrap target collection: cdcr-target shard: shard1 leader: http://127.0.0.1:55779/solr/cdcr-target_shard1_replica_n1/
[junit4] 2> 265497 INFO (qtp9541216-2659) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.S.Request [cdcr-target_shard1_replica_n1] webapp=/solr path=/cdcr params={qt=/cdcr&masterUrl=http://127.0.0.1:55803/solr/cdcr-source_shard1_replica_n1/&action=BOOTSTRAP&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 265497 INFO (qtp9541216-2659) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.S.Request [cdcr-target_shard1_replica_n1] webapp=/solr path=/cdcr params={qt=/cdcr&action=BOOTSTRAP_STATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 265498 WARN (cdcr-bootstrap-status-331-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrReplicatorManager Bootstrap process was not found on target collection: cdcr-target shard: shard1, leader: http://127.0.0.1:55779/solr/cdcr-target_shard1_replica_n1/
[junit4] 2> 265498 INFO (cdcr-bootstrap-status-331-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrReplicatorManager Attempting to bootstrap target collection: cdcr-target shard: shard1 leader: http://127.0.0.1:55779/solr/cdcr-target_shard1_replica_n1/
[junit4] 2> 265498 INFO (qtp9541216-2659) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.S.Request [cdcr-target_shard1_replica_n1] webapp=/solr path=/cdcr params={qt=/cdcr&masterUrl=http://127.0.0.1:55803/solr/cdcr-source_shard1_replica_n1/&action=BOOTSTRAP&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 265498 INFO (qtp26336562-2707) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1e24cd5[cdcr-source_shard1_replica_n1] main]
[junit4] 2> 265499 INFO (qtp26336562-2707) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 265499 INFO (qtp9541216-2663) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.S.Request [cdcr-target_shard1_replica_n1] webapp=/solr path=/cdcr params={qt=/cdcr&action=BOOTSTRAP_STATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 265500 WARN (cdcr-bootstrap-status-331-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrReplicatorManager Bootstrap process was not found on target collection: cdcr-target shard: shard1, leader: http://127.0.0.1:55779/solr/cdcr-target_shard1_replica_n1/
[junit4] 2> 265500 INFO (cdcr-bootstrap-status-331-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrReplicatorManager Attempting to bootstrap target collection: cdcr-target shard: shard1 leader: http://127.0.0.1:55779/solr/cdcr-target_shard1_replica_n1/
[junit4] 2> 265500 INFO (searcherExecutor-1285-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_replica_n1] Registered new searcher Searcher@1e24cd5[cdcr-source_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_a(7.0.0):C1000) Uninverting(_b(7.0.0):C100)))}
[junit4] 2> 265501 INFO (qtp9541216-2661) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.S.Request [cdcr-target_shard1_replica_n1] webapp=/solr path=/cdcr params={qt=/cdcr&masterUrl=http://127.0.0.1:55803/solr/cdcr-source_shard1_replica_n1/&action=BOOTSTRAP&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 265501 INFO (qtp9541216-2665) [n:127.0.0.1:55779_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.S.Request [cdcr-target_shard1_replica_n1] webapp=/solr path=/cdcr params={qt=/cdcr&action=BOOTSTRAP_STATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 265502 WARN (cdcr-bootstrap-status-331-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrReplicatorManager Bootstrap process was not found on target collection: cdcr-target shard: shard1, leader: http://127.0.0.1:55779/solr/cdcr-target_shard1_replica_n1/
[junit4] 2> 265502 INFO (cdcr-bootstrap-status-331-thread-1-processing-n:127.0.0.1:55803_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node1) [n:127.0.0.1:55803_solr c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica_n1] o.a.s.h
[...truncated too long message...]
a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 408079 INFO (TEST-CdcrBootstrapTest.testConvertClusterToCdcrAndBootstrap-seed#[3D9ABE974DE0570F]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:56585 56585
[junit4] 2> 408080 INFO (Thread-1061) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:56585 56585
[junit4] 2> 408082 WARN (Thread-1061) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2> 2 /solr/configs/cdcr-source
[junit4] 2> 2 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 10 /solr/collections/cdcr-source/state.json
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2> 2 /solr/collections/cdcr-source/cdcr/state/process
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/collections
[junit4] 2> 2 /solr/overseer/queue
[junit4] 2> 2 /solr/live_nodes
[junit4] 2> 2 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 408085 INFO (jetty-closer-370-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@18afede{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 408086 INFO (jetty-closer-370-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=15886179
[junit4] 2> 408086 INFO (jetty-closer-370-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 408086 INFO (jetty-closer-370-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@966f1a: rootName = solr_56562, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1516ed8
[junit4] 2> 408086 INFO (jetty-closer-370-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 408086 INFO (jetty-closer-370-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@9744a6: rootName = solr_56562, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1a40468
[junit4] 2> 408093 INFO (jetty-closer-370-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 408093 INFO (jetty-closer-370-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@10268f8: rootName = solr_56562, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@b5874c
[junit4] 2> 408095 INFO (jetty-closer-370-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 408097 INFO (coreCloseExecutor-1425-thread-1) [n:127.0.0.1:56562_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-target_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@70280d
[junit4] 2> 408097 INFO (coreCloseExecutor-1425-thread-1) [n:127.0.0.1:56562_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.cdcr-target.shard1.replica_n1, tag=7350285
[junit4] 2> 408097 INFO (coreCloseExecutor-1425-thread-1) [n:127.0.0.1:56562_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@14e44df: rootName = solr_56562, domain = solr.core.cdcr-target.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.cdcr-target.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@123841
[junit4] 2> 408099 INFO (zkCallback-376-thread-1-processing-n:127.0.0.1:56562_solr) [n:127.0.0.1:56562_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/cdcr-target/state.json] for collection [cdcr-target] has occurred - updating... (live nodes size: [1])
[junit4] 2> 408099 INFO (zkCallback-376-thread-2-processing-n:127.0.0.1:56562_solr) [n:127.0.0.1:56562_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/cdcr-target/state.json] for collection [cdcr-target] has occurred - updating... (live nodes size: [1])
[junit4] 2> 408107 INFO (coreCloseExecutor-1425-thread-1) [n:127.0.0.1:56562_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.cdcr-target.shard1.leader, tag=7350285
[junit4] 2> 408107 INFO (coreCloseExecutor-1425-thread-1) [n:127.0.0.1:56562_solr c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica_n1] o.a.s.h.CdcrRequestHandler Solr core is being closed - shutting down CDCR handler @ cdcr-target:shard1
[junit4] 2> 408110 INFO (jetty-closer-370-thread-1) [ ] o.a.s.c.Overseer Overseer (id=98540112887545859-127.0.0.1:56562_solr-n_0000000000) closing
[junit4] 2> 408110 INFO (OverseerStateUpdate-98540112887545859-127.0.0.1:56562_solr-n_0000000000) [n:127.0.0.1:56562_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:56562_solr
[junit4] 2> 408113 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15e15b5e8ca0003, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 408114 WARN (zkCallback-376-thread-2-processing-n:127.0.0.1:56562_solr) [n:127.0.0.1:56562_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 408115 INFO (jetty-closer-370-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@708f17{/solr,null,UNAVAILABLE}
[junit4] 2> 408117 ERROR (TEST-CdcrBootstrapTest.testConvertClusterToCdcrAndBootstrap-seed#[3D9ABE974DE0570F]) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 408118 INFO (TEST-CdcrBootstrapTest.testConvertClusterToCdcrAndBootstrap-seed#[3D9ABE974DE0570F]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:56558 56558
[junit4] 2> 408119 INFO (Thread-1051) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:56558 56558
[junit4] 2> 408120 WARN (Thread-1051) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2> 2 /solr/configs/cdcr-target/managed-schema
[junit4] 2> 2 /solr/collections/cdcr-target/leaders/shard1/leader
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/collections/cdcr-target/state.json
[junit4] 2> 3 /solr/collections/cdcr-target/cdcr/state/process
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2> 2 /solr/collections/cdcr-target/cdcr/state/buffer
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/live_nodes
[junit4] 2> 3 /solr/collections
[junit4] 2>
[junit4] 2> 408121 INFO (TEST-CdcrBootstrapTest.testConvertClusterToCdcrAndBootstrap-seed#[3D9ABE974DE0570F]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testConvertClusterToCdcrAndBootstrap
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-7.0-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTest_3D9ABE974DE0570F-001
[junit4] 2> Aug 24, 2017 7:26:47 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 6 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarity(queryNorm=false): {}, locale=pt, timezone=GMT0
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 (32-bit)/cpus=3,threads=1,free=68646696,total=271843328
[junit4] 2> NOTE: All tests run in this JVM: [TestPrepRecovery, TestReplicationHandler, TestFieldTypeResource, SmileWriterTest, ConnectionReuseTest, TestCursorMarkWithoutUniqueKey, TestSegmentSorting, LargeFieldTest, PrimUtilsTest, ShardRoutingTest, ReturnFieldsTest, TestPKIAuthenticationPlugin, TestPHPSerializedResponseWriter, TestConfigSetProperties, BlockCacheTest, CursorPagingTest, TestDocBasedVersionConstraints, HighlighterConfigTest, PropertiesRequestHandlerTest, DistributedQueryComponentCustomSortTest, SpatialFilterTest, TestBinaryField, RankQueryTest, TestClusterStateMutator, TestScoreJoinQPScore, TestFieldCache, IndexSchemaTest, TestSolrDeletionPolicy2, HdfsBasicDistributedZkTest, BinaryUpdateRequestHandlerTest, RuleEngineTest, CdcrBootstrapTest]
[junit4] Completed [108/729 (1!)] on J0 in 150.92s, 3 tests, 1 failure <<< FAILURES!
[...truncated 41503 lines...]
[JENKINS-EA] Lucene-Solr-7.0-Windows (32bit/jdk-9-ea+181) - Build #
109 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.0-Windows/109/
Java: 32bit/jdk-9-ea+181 -server -XX:+UseG1GC --illegal-access=deny
1 tests failed.
FAILED: org.apache.solr.cloud.CleanupOldIndexTest.test
Error Message:
Stack Trace:
java.util.concurrent.TimeoutException
at __randomizedtesting.SeedInfo.seed([2582C888A414E4A6:ADD6F7520AE8895E]:0)
at org.apache.solr.common.cloud.ZkStateReader.waitForState(ZkStateReader.java:1267)
at org.apache.solr.client.solrj.impl.CloudSolrClient.waitForState(CloudSolrClient.java:438)
at org.apache.solr.cloud.CleanupOldIndexTest.test(CleanupOldIndexTest.java:114)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 12139 lines...]
[junit4] Suite: org.apache.solr.cloud.CleanupOldIndexTest
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7.0-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CleanupOldIndexTest_2582C888A414E4A6-001\init-core-data-001
[junit4] 2> 1570435 WARN (SUITE-CleanupOldIndexTest-seed#[2582C888A414E4A6]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 1570435 INFO (SUITE-CleanupOldIndexTest-seed#[2582C888A414E4A6]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1570436 INFO (SUITE-CleanupOldIndexTest-seed#[2582C888A414E4A6]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) 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)
[junit4] 2> 1570437 INFO (SUITE-CleanupOldIndexTest-seed#[2582C888A414E4A6]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in C:\Users\jenkins\workspace\Lucene-Solr-7.0-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CleanupOldIndexTest_2582C888A414E4A6-001\tempDir-001
[junit4] 2> 1570438 INFO (SUITE-CleanupOldIndexTest-seed#[2582C888A414E4A6]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1570438 INFO (Thread-3645) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1570438 INFO (Thread-3645) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1570443 ERROR (Thread-3645) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 1570538 INFO (SUITE-CleanupOldIndexTest-seed#[2582C888A414E4A6]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:57133
[junit4] 2> 1570548 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15e1824f7490000, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 1570550 INFO (jetty-launcher-5131-thread-2) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1570551 INFO (jetty-launcher-5131-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@89b005{/solr,null,AVAILABLE}
[junit4] 2> 1570554 INFO (jetty-launcher-5131-thread-1) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1570554 INFO (jetty-launcher-5131-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@f7cd5f{SSL,[ssl, http/1.1]}{127.0.0.1:57137}
[junit4] 2> 1570555 INFO (jetty-launcher-5131-thread-2) [ ] o.e.j.s.Server Started @1572064ms
[junit4] 2> 1570555 INFO (jetty-launcher-5131-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=57137}
[junit4] 2> 1570557 ERROR (jetty-launcher-5131-thread-2) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1570557 INFO (jetty-launcher-5131-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1a4d826{/solr,null,AVAILABLE}
[junit4] 2> 1570557 INFO (jetty-launcher-5131-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 1570557 INFO (jetty-launcher-5131-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1570557 INFO (jetty-launcher-5131-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 1570557 INFO (jetty-launcher-5131-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-25T06:47:09.501070200Z
[junit4] 2> 1570558 INFO (jetty-launcher-5131-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1e4d072{SSL,[ssl, http/1.1]}{127.0.0.1:57140}
[junit4] 2> 1570558 INFO (jetty-launcher-5131-thread-1) [ ] o.e.j.s.Server Started @1572067ms
[junit4] 2> 1570558 INFO (jetty-launcher-5131-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=57140}
[junit4] 2> 1570558 ERROR (jetty-launcher-5131-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1570558 INFO (jetty-launcher-5131-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 1570558 INFO (jetty-launcher-5131-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1570558 INFO (jetty-launcher-5131-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 1570559 INFO (jetty-launcher-5131-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-25T06:47:09.502196300Z
[junit4] 2> 1570567 INFO (jetty-launcher-5131-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1570568 INFO (jetty-launcher-5131-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1570582 INFO (jetty-launcher-5131-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57133/solr
[junit4] 2> 1570582 INFO (jetty-launcher-5131-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57133/solr
[junit4] 2> 1570587 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15e1824f7490003, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 1570587 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15e1824f7490004, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 1570765 INFO (jetty-launcher-5131-thread-2) [ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1570766 INFO (jetty-launcher-5131-thread-2) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:57137_solr
[junit4] 2> 1570768 INFO (jetty-launcher-5131-thread-2) [ ] o.a.s.c.Overseer Overseer (id=98542788895375366-127.0.0.1:57137_solr-n_0000000000) starting
[junit4] 2> 1570772 INFO (jetty-launcher-5131-thread-1) [ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1570774 INFO (jetty-launcher-5131-thread-1) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57140_solr
[junit4] 2> 1570777 INFO (zkCallback-5143-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1570778 INFO (zkCallback-5142-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1570783 INFO (jetty-launcher-5131-thread-2) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57137_solr
[junit4] 2> 1570789 INFO (zkCallback-5142-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1570789 INFO (zkCallback-5143-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1570910 INFO (jetty-launcher-5131-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57140.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1b9b034
[junit4] 2> 1570920 INFO (jetty-launcher-5131-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57140.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1b9b034
[junit4] 2> 1570920 INFO (jetty-launcher-5131-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57140.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1b9b034
[junit4] 2> 1570922 INFO (jetty-launcher-5131-thread-1) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.0-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CleanupOldIndexTest_2582C888A414E4A6-001\tempDir-001\node1\.
[junit4] 2> 1571021 INFO (jetty-launcher-5131-thread-2) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57137.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1b9b034
[junit4] 2> 1571030 INFO (jetty-launcher-5131-thread-2) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57137.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1b9b034
[junit4] 2> 1571030 INFO (jetty-launcher-5131-thread-2) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57137.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1b9b034
[junit4] 2> 1571032 INFO (jetty-launcher-5131-thread-2) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.0-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CleanupOldIndexTest_2582C888A414E4A6-001\tempDir-001\node2\.
[junit4] 2> 1571064 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15e1824f7490007, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 1571072 INFO (SUITE-CleanupOldIndexTest-seed#[2582C888A414E4A6]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1571079 INFO (SUITE-CleanupOldIndexTest-seed#[2582C888A414E4A6]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57133/solr ready
[junit4] 2> 1571122 INFO (TEST-CleanupOldIndexTest.test-seed#[2582C888A414E4A6]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 1571212 INFO (qtp24537389-20866) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params async=cb7bf01e-9967-46f9-9ea2-e2f453bc9f7c&replicationFactor=2&collection.configName=conf1&name=oldindextest&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1571213 INFO (qtp24537389-20866) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=cb7bf01e-9967-46f9-9ea2-e2f453bc9f7c&replicationFactor=2&collection.configName=conf1&name=oldindextest&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 1571216 INFO (qtp24537389-20865) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=cb7bf01e-9967-46f9-9ea2-e2f453bc9f7c&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1571217 INFO (qtp24537389-20865) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=cb7bf01e-9967-46f9-9ea2-e2f453bc9f7c&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1571218 INFO (OverseerThreadFactory-6387-thread-1) [ ] o.a.s.c.CreateCollectionCmd Create collection oldindextest
[junit4] 2> 1571329 INFO (OverseerStateUpdate-98542788895375366-127.0.0.1:57137_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"oldindextest",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"oldindextest_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:57137/solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 1571332 INFO (OverseerStateUpdate-98542788895375366-127.0.0.1:57137_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"oldindextest",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"oldindextest_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:57140/solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 1571544 INFO (qtp24537389-20864) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&collection=oldindextest&version=2&replicaType=NRT&async=cb7bf01e-9967-46f9-9ea2-e2f453bc9f7c1358656230070963&coreNodeName=core_node2&name=oldindextest_shard1_replica_n2&action=CREATE&numShards=1&shard=shard1&wt=javabin} status=0 QTime=2
[junit4] 2> 1571544 INFO (parallelCoreAdminExecutor-6388-thread-1) [ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&collection=oldindextest&version=2&replicaType=NRT&async=cb7bf01e-9967-46f9-9ea2-e2f453bc9f7c1358656230070963&coreNodeName=core_node2&name=oldindextest_shard1_replica_n2&action=CREATE&numShards=1&shard=shard1&wt=javabin
[junit4] 2> 1571548 INFO (parallelCoreAdminExecutor-6388-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1571588 INFO (qtp13274547-20860) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&collection=oldindextest&version=2&replicaType=NRT&async=cb7bf01e-9967-46f9-9ea2-e2f453bc9f7c1358656228304562&coreNodeName=core_node1&name=oldindextest_shard1_replica_n1&action=CREATE&numShards=1&shard=shard1&wt=javabin} status=0 QTime=0
[junit4] 2> 1571588 INFO (parallelCoreAdminExecutor-6390-thread-1) [ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&collection=oldindextest&version=2&replicaType=NRT&async=cb7bf01e-9967-46f9-9ea2-e2f453bc9f7c1358656228304562&coreNodeName=core_node1&name=oldindextest_shard1_replica_n1&action=CREATE&numShards=1&shard=shard1&wt=javabin
[junit4] 2> 1571590 INFO (parallelCoreAdminExecutor-6390-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1571591 INFO (qtp24537389-20878) [ ] o.a.s.h.a.CoreAdminOperation Checking request status for : cb7bf01e-9967-46f9-9ea2-e2f453bc9f7c1358656230070963
[junit4] 2> 1571591 INFO (qtp24537389-20878) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=cb7bf01e-9967-46f9-9ea2-e2f453bc9f7c1358656230070963&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1571702 INFO (zkCallback-5142-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/oldindextest/state.json] for collection [oldindextest] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1571702 INFO (zkCallback-5143-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/oldindextest/state.json] for collection [oldindextest] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1571703 INFO (zkCallback-5142-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/oldindextest/state.json] for collection [oldindextest] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1571703 INFO (zkCallback-5143-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/oldindextest/state.json] for collection [oldindextest] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1572224 INFO (qtp24537389-20879) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=cb7bf01e-9967-46f9-9ea2-e2f453bc9f7c&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1572226 INFO (qtp24537389-20879) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=cb7bf01e-9967-46f9-9ea2-e2f453bc9f7c&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 1572575 INFO (parallelCoreAdminExecutor-6388-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1572608 INFO (qtp24537389-20877) [ ] o.a.s.h.a.CoreAdminOperation Checking request status for : cb7bf01e-9967-46f9-9ea2-e2f453bc9f7c1358656230070963
[junit4] 2> 1572608 INFO (qtp24537389-20877) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=cb7bf01e-9967-46f9-9ea2-e2f453bc9f7c1358656230070963&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1572649 INFO (parallelCoreAdminExecutor-6388-thread-1) [ ] o.a.s.s.IndexSchema [oldindextest_shard1_replica_n2] Schema name=cloud-dynamic
[junit4] 2> 1572652 INFO (parallelCoreAdminExecutor-6390-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1572683 INFO (parallelCoreAdminExecutor-6390-thread-1) [ ] o.a.s.s.IndexSchema [oldindextest_shard1_replica_n1] Schema name=cloud-dynamic
[junit4] 2> 1572699 INFO (parallelCoreAdminExecutor-6388-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 1572699 INFO (parallelCoreAdminExecutor-6388-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'oldindextest_shard1_replica_n2' using configuration from collection oldindextest, trusted=true
[junit4] 2> 1572702 INFO (parallelCoreAdminExecutor-6388-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57140.solr.core.oldindextest.shard1.replica_n2' (registry 'solr.core.oldindextest.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1b9b034
[junit4] 2> 1572702 INFO (parallelCoreAdminExecutor-6388-thread-1) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1572702 INFO (parallelCoreAdminExecutor-6388-thread-1) [ ] o.a.s.c.SolrCore [[oldindextest_shard1_replica_n2] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.0-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CleanupOldIndexTest_2582C888A414E4A6-001\tempDir-001\node1\oldindextest_shard1_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.0-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CleanupOldIndexTest_2582C888A414E4A6-001\tempDir-001\node1\.\oldindextest_shard1_replica_n2\data\]
[junit4] 2> 1572765 INFO (parallelCoreAdminExecutor-6390-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 1572765 INFO (parallelCoreAdminExecutor-6390-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'oldindextest_shard1_replica_n1' using configuration from collection oldindextest, trusted=true
[junit4] 2> 1572766 INFO (parallelCoreAdminExecutor-6390-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57137.solr.core.oldindextest.shard1.replica_n1' (registry 'solr.core.oldindextest.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1b9b034
[junit4] 2> 1572766 INFO (parallelCoreAdminExecutor-6390-thread-1) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1572767 INFO (parallelCoreAdminExecutor-6390-thread-1) [ ] o.a.s.c.SolrCore [[oldindextest_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.0-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CleanupOldIndexTest_2582C888A414E4A6-001\tempDir-001\node2\oldindextest_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.0-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CleanupOldIndexTest_2582C888A414E4A6-001\tempDir-001\node2\.\oldindextest_shard1_replica_n1\data\]
[junit4] 2> 1572950 INFO (parallelCoreAdminExecutor-6388-thread-1) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1572950 INFO (parallelCoreAdminExecutor-6388-thread-1) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1572953 INFO (parallelCoreAdminExecutor-6388-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1572954 INFO (parallelCoreAdminExecutor-6388-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1572960 INFO (parallelCoreAdminExecutor-6388-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1f6979d[oldindextest_shard1_replica_n2] main]
[junit4] 2> 1572962 INFO (parallelCoreAdminExecutor-6388-thread-1) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1572962 INFO (parallelCoreAdminExecutor-6388-thread-1) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1572964 INFO (parallelCoreAdminExecutor-6388-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1572965 INFO (parallelCoreAdminExecutor-6388-thread-1) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1576684624972611584
[junit4] 2> 1572965 INFO (parallelCoreAdminExecutor-6390-thread-1) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1572965 INFO (parallelCoreAdminExecutor-6390-thread-1) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1572968 INFO (parallelCoreAdminExecutor-6390-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1572968 INFO (parallelCoreAdminExecutor-6390-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1572973 INFO (parallelCoreAdminExecutor-6390-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@24988e[oldindextest_shard1_replica_n1] main]
[junit4] 2> 1572975 INFO (parallelCoreAdminExecutor-6390-thread-1) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1572976 INFO (parallelCoreAdminExecutor-6390-thread-1) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1572978 INFO (parallelCoreAdminExecutor-6390-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1572978 INFO (parallelCoreAdminExecutor-6390-thread-1) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1576684624986243072
[junit4] 2> 1572978 INFO (parallelCoreAdminExecutor-6388-thread-1) [ ] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=2 found=1 timeoutin=9999ms
[junit4] 2> 1572985 INFO (searcherExecutor-6393-thread-1) [ ] o.a.s.c.SolrCore [oldindextest_shard1_replica_n1] Registered new searcher Searcher@24988e[oldindextest_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1572986 INFO (searcherExecutor-6392-thread-1) [ ] o.a.s.c.SolrCore [oldindextest_shard1_replica_n2] Registered new searcher Searcher@1f6979d[oldindextest_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1573108 INFO (zkCallback-5143-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/oldindextest/state.json] for collection [oldindextest] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1573108 INFO (zkCallback-5143-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/oldindextest/state.json] for collection [oldindextest] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1573108 INFO (zkCallback-5142-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/oldindextest/state.json] for collection [oldindextest] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1573110 INFO (zkCallback-5142-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/oldindextest/state.json] for collection [oldindextest] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1573231 INFO (qtp24537389-20877) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=cb7bf01e-9967-46f9-9ea2-e2f453bc9f7c&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1573233 INFO (qtp24537389-20877) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=cb7bf01e-9967-46f9-9ea2-e2f453bc9f7c&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 1573482 INFO (parallelCoreAdminExecutor-6388-thread-1) [ ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1573482 INFO (parallelCoreAdminExecutor-6388-thread-1) [ ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1573482 INFO (parallelCoreAdminExecutor-6388-thread-1) [ ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/
[junit4] 2> 1573482 INFO (parallelCoreAdminExecutor-6388-thread-1) [ ] o.a.s.u.PeerSync PeerSync: core=oldindextest_shard1_replica_n2 url=https://127.0.0.1:57140/solr START replicas=[https://127.0.0.1:57137/solr/oldindextest_shard1_replica_n1/] nUpdates=100
[junit4] 2> 1573482 INFO (parallelCoreAdminExecutor-6388-thread-1) [ ] o.a.s.u.PeerSync PeerSync: core=oldindextest_shard1_replica_n2 url=https://127.0.0.1:57140/solr DONE. We have no versions. sync failed.
[junit4] 2> 1573494 INFO (qtp13274547-20857) [ ] o.a.s.c.S.Request [oldindextest_shard1_replica_n1] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1573498 INFO (parallelCoreAdminExecutor-6388-thread-1) [ ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 1573498 INFO (parallelCoreAdminExecutor-6388-thread-1) [ ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 1573498 INFO (parallelCoreAdminExecutor-6388-thread-1) [ ] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 1573540 INFO (parallelCoreAdminExecutor-6388-thread-1) [ ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/ shard1
[junit4] 2> 1573622 INFO (qtp24537389-20868) [ ] o.a.s.h.a.CoreAdminOperation Checking request status for : cb7bf01e-9967-46f9-9ea2-e2f453bc9f7c1358656230070963
[junit4] 2> 1573622 INFO (qtp24537389-20868) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=cb7bf01e-9967-46f9-9ea2-e2f453bc9f7c1358656230070963&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1573649 INFO (zkCallback-5142-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/oldindextest/state.json] for collection [oldindextest] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1573650 INFO (zkCallback-5142-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/oldindextest/state.json] for collection [oldindextest] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1573650 INFO (zkCallback-5143-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/oldindextest/state.json] for collection [oldindextest] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1573650 INFO (zkCallback-5143-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/oldindextest/state.json] for collection [oldindextest] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1573694 INFO (parallelCoreAdminExecutor-6388-thread-1) [ ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1573807 INFO (zkCallback-5143-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/oldindextest/state.json] for collection [oldindextest] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1573807 INFO (zkCallback-5142-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/oldindextest/state.json] for collection [oldindextest] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1573807 INFO (zkCallback-5142-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/oldindextest/state.json] for collection [oldindextest] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1573807 INFO (zkCallback-5143-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/oldindextest/state.json] for collection [oldindextest] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1574107 INFO (zkCallback-5143-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/oldindextest/state.json] for collection [oldindextest] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1574108 INFO (zkCallback-5143-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/oldindextest/state.json] for collection [oldindextest] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1574108 INFO (zkCallback-5142-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/oldindextest/state.json] for collection [oldindextest] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1574108 INFO (zkCallback-5142-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/oldindextest/state.json] for collection [oldindextest] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1574235 INFO (qtp24537389-20868) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=cb7bf01e-9967-46f9-9ea2-e2f453bc9f7c&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1574237 INFO (qtp24537389-20868) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=cb7bf01e-9967-46f9-9ea2-e2f453bc9f7c&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 1574627 INFO (qtp24537389-20866) [ ] o.a.s.h.a.CoreAdminOperation Checking request status for : cb7bf01e-9967-46f9-9ea2-e2f453bc9f7c1358656230070963
[junit4] 2> 1574627 INFO (qtp24537389-20866) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=cb7bf01e-9967-46f9-9ea2-e2f453bc9f7c1358656230070963&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1574635 INFO (qtp13274547-20857) [ ] o.a.s.h.a.CoreAdminOperation Checking request status for : cb7bf01e-9967-46f9-9ea2-e2f453bc9f7c1358656228304562
[junit4] 2> 1574635 INFO (qtp13274547-20857) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=cb7bf01e-9967-46f9-9ea2-e2f453bc9f7c1358656228304562&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1574646 INFO (OverseerThreadFactory-6387-thread-1) [ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1575227 INFO (OverseerCollectionConfigSetProcessor-98542788895375366-127.0.0.1:57137_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
[junit4] 2> 1575247 INFO (qtp24537389-20866) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=cb7bf01e-9967-46f9-9ea2-e2f453bc9f7c&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1575259 INFO (qtp24537389-20866) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=cb7bf01e-9967-46f9-9ea2-e2f453bc9f7c&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=12
[junit4] 2> 1575262 INFO (qtp24537389-20864) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :deletestatus with params requestid=cb7bf01e-9967-46f9-9ea2-e2f453bc9f7c&action=DELETESTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1575264 INFO (qtp24537389-20864) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=cb7bf01e-9967-46f9-9ea2-e2f453bc9f7c&action=DELETESTATUS&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 1575348 INFO (qtp13274547-20857) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-0 (1576684627433619456)]} 0 20
[junit4] 2> 1575349 INFO (qtp24537389-20878) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-0 (1576684627433619456)]} 0 37
[junit4] 2> 1575358 INFO (qtp13274547-20862) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-1 (1576684627475562496)]} 0 2
[junit4] 2> 1575359 INFO (qtp24537389-20879) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-1 (1576684627475562496)]} 0 7
[junit4] 2> 1575367 INFO (qtp13274547-20861) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-2 (1576684627486048256)]} 0 2
[junit4] 2> 1575370 INFO (qtp24537389-20871) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-2 (1576684627486048256)]} 0 8
[junit4] 2> 1575377 INFO (qtp13274547-20860) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-3 (1576684627497582592)]} 0 1
[junit4] 2> 1575378 INFO (qtp24537389-20877) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-3 (1576684627497582592)]} 0 9
[junit4] 2> 1575390 INFO (qtp13274547-20859) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-4 (1576684627510165504)]} 0 1
[junit4] 2> 1575391 INFO (qtp24537389-20867) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-4 (1576684627510165504)]} 0 6
[junit4] 2> 1575394 INFO (qtp13274547-20876) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-5 (1576684627518554112)]} 0 3
[junit4] 2> 1575394 INFO (qtp24537389-20868) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-5 (1576684627518554112)]} 0 6
[junit4] 2> 1575408 INFO (qtp13274547-20876) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-0 (-1576684627532185600)]} 0 0
[junit4] 2> 1575408 INFO (qtp24537389-20865) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-0 (-1576684627532185600)]} 0 3
[junit4] 2> 1575427 INFO (qtp13274547-20857) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-6 (1576684627539525632)]} 0 7
[junit4] 2> 1575433 INFO (qtp24537389-20866) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-6 (1576684627539525632)]} 0 20
[junit4] 2> 1575439 INFO (qtp13274547-20862) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-1 (-1576684627562594304)]} 0 0
[junit4] 2> 1575441 INFO (qtp24537389-20864) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-1 (-1576684627562594304)]} 0 5
[junit4] 2> 1575454 INFO (qtp13274547-20861) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-7 (1576684627573080064)]} 0 1
[junit4] 2> 1575455 INFO (qtp24537389-20878) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-7 (1576684627573080064)]} 0 10
[junit4] 2> 1575464 INFO (qtp13274547-20860) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-8 (1576684627589857280)]} 0 1
[junit4] 2> 1575464 INFO (qtp24537389-20879) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-8 (1576684627589857280)]} 0 7
[junit4] 2> 1575477 INFO (qtp13274547-20860) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-2 (-1576684627593003008)]} 0 4
[junit4] 2> 1575488 INFO (qtp24537389-20871) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-2 (-1576684627593003008)]} 0 16
[junit4] 2> 1575499 INFO (qtp13274547-20855) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-9 (1576684627624460288)]} 0 1
[junit4] 2> 1575499 INFO (qtp24537389-20877) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-9 (1576684627624460288)]} 0 6
[junit4] 2> 1575511 INFO (qtp13274547-20876) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-10 (1576684627637043200)]} 0 1
[junit4] 2> 1575513 INFO (qtp24537389-20867) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-10 (1576684627637043200)]} 0 8
[junit4] 2> 1575525 INFO (qtp13274547-20857) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-4 (-1576684627650674688)]} 0 0
[junit4] 2> 1575526 INFO (qtp24537389-20868) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-4 (-1576684627650674688)]} 0 6
[junit4] 2> 1575535 INFO (qtp13274547-20862) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-11 (1576684627662209024)]} 0 0
[junit4] 2> 1575535 INFO (qtp24537389-20865) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-11 (1576684627662209024)]} 0 4
[junit4] 2> 1575548 INFO (qtp13274547-20862) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-12 (1576684627670597632)]} 0 8
[junit4] 2> 1575549 INFO (qtp24537389-20865) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-12 (1576684627670597632)]} 0 11
[junit4] 2> 1575556 INFO (qtp13274547-20859) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-13 (1576684627685277696)]} 0 1
[junit4] 2> 1575557 INFO (qtp24537389-20864) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-13 (1576684627685277696)]} 0 5
[junit4] 2> 1575574 INFO (qtp13274547-20859) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-5 (-1576684627706249216)]} 0 0
[junit4] 2> 1575575 INFO (qtp24537389-20864) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-5 (-1576684627706249216)]} 0 3
[junit4] 2> 1575582 INFO (qtp13274547-20859) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-14 (1576684627713589248)]} 0 1
[junit4] 2> 1575589 INFO (qtp24537389-20879) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-14 (1576684627713589248)]} 0 11
[junit4] 2> 1575603 INFO (qtp13274547-20876) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-6 (-1576684627735609344)]} 0 0
[junit4] 2> 1575612 INFO (qtp24537389-20871) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-6 (-1576684627735609344)]} 0 12
[junit4] 2> 1575623 INFO (qtp13274547-20857) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-15 (1576684627752386560)]} 0 1
[junit4] 2> 1575624 INFO (qtp24537389-20877) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-15 (1576684627752386560)]} 0 4
[junit4] 2> 1575628 INFO (qtp13274547-20861) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-8 (-1576684627762872320)]} 0 0
[junit4] 2> 1575634 INFO (qtp24537389-20867) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-8 (-1576684627762872320)]} 0 8
[junit4] 2> 1575646 INFO (qtp13274547-20862) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-16 (1576684627780698112)]} 0 1
[junit4] 2> 1575647 INFO (qtp24537389-20868) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-16 (1576684627780698112)]} 0 4
[junit4] 2> 1575657 INFO (qtp13274547-20875) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-9 (-1576684627793281024)]} 0 0
[junit4] 2> 1575662 INFO (qtp24537389-20866) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-9 (-1576684627793281024)]} 0 7
[junit4] 2> 1575678 INFO (qtp13274547-20855) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-17 (1576684627814252544)]} 0 0
[junit4] 2> 1575686 INFO (qtp24537389-20865) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-17 (1576684627814252544)]} 0 11
[junit4] 2> 1575721 INFO (qtp13274547-20859) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-18 (1576684627847806976)]} 0 16
[junit4] 2> 1575727 INFO (qtp24537389-20878) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-18 (1576684627847806976)]} 0 20
[junit4] 2> 1575743 INFO (qtp13274547-20876) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-19 (1576684627878215680)]} 0 0
[junit4] 2> 1575750 INFO (qtp24537389-20864) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-19 (1576684627878215680)]} 0 10
[junit4] 2> 1575755 INFO (qtp13274547-20876) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-20 (1576684627894992896)]} 0 0
[junit4] 2> 1575756 INFO (qtp24537389-20879) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-20 (1576684627894992896)]} 0 4
[junit4] 2> 1575764 INFO (qtp13274547-20876) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-10 (-1576684627903381504)]} 0 0
[junit4] 2> 1575765 INFO (qtp24537389-20879) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-10 (-1576684627903381504)]} 0 5
[junit4] 2> 1575778 INFO (qtp13274547-20862) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-21 (1576684627919110144)]} 0 0
[junit4] 2> 1575779 INFO (qtp24537389-20871) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-21 (1576684627919110144)]} 0 5
[junit4] 2> 1575786 INFO (qtp13274547-20862) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-22 (1576684627927498752)]} 0 0
[junit4] 2> 1575787 INFO (qtp24537389-20871) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-22 (1576684627927498752)]} 0 4
[junit4] 2> 1575792 INFO (qtp13274547-20862) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-12 (-1576684627932741632)]} 0 0
[junit4] 2> 1575793 INFO (qtp24537389-20867) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-12 (-1576684627932741632)]} 0 3
[junit4] 2> 1575797 INFO (qtp13274547-20862) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-23 (1576684627940081664)]} 0 1
[junit4] 2> 1575797 INFO (qtp24537389-20868) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-23 (1576684627940081664)]} 0 4
[junit4] 2> 1575809 INFO (qtp13274547-20857) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-14 (-1576684627947421696)]} 0 0
[junit4] 2> 1575809 INFO (qtp24537389-20866) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-14 (-1576684627947421696)]} 0 7
[junit4] 2> 1575815 INFO (qtp13274547-20861) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-24 (1576684627956858880)]} 0 1
[junit4] 2> 1575815 INFO (qtp24537389-20865) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-24 (1576684627956858880)]} 0 4
[junit4] 2> 1575820 INFO (qtp13274547-20861) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-16 (-1576684627964198912)]} 0 0
[junit4] 2> 1575821 INFO (qtp24537389-20878) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-16 (-1576684627964198912)]} 0 3
[junit4] 2> 1575828 INFO (qtp13274547-20876) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-25 (1576684627970490368)]} 0 1
[junit4] 2> 1575829 INFO (qtp24537389-20864) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-25 (1576684627970490368)]} 0 5
[junit4] 2> 1575834 INFO (qtp13274547-20875) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-17 (-1576684627977830400)]} 0 0
[junit4] 2> 1575835 INFO (qtp24537389-20879) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-17 (-1576684627977830400)]} 0 4
[junit4] 2> 1575844 INFO (qtp13274547-20875) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-26 (1576684627985170432)]} 0 2
[junit4] 2> 1575844 INFO (qtp24537389-20879) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-26 (1576684627985170432)]} 0 6
[junit4] 2> 1575862 INFO (qtp13274547-20859) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-27 (1576684627994607616)]} 0 11
[junit4] 2> 1575863 INFO (qtp24537389-20871) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-27 (1576684627994607616)]} 0 16
[junit4] 2> 1575873 INFO (qtp13274547-20862) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-18 (-1576684628016627712)]} 0 1
[junit4] 2> 1575875 INFO (qtp24537389-20867) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-18 (-1576684628016627712)]} 0 6
[junit4] 2> 1575881 INFO (qtp13274547-20862) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-28 (1576684628028162048)]} 0 0
[junit4] 2> 1575886 INFO (qtp24537389-20868) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-28 (1576684628028162048)]} 0 7
[junit4] 2> 1575903 INFO (qtp13274547-20876) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-20 (-1576684628048084992)]} 0 0
[junit4] 2> 1575904 INFO (qtp24537389-20866) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-20 (-1576684628048084992)]} 0 3
[junit4] 2> 1575917 INFO (qtp13274547-20855) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-29 (1576684628064862208)]} 0 1
[junit4] 2> 1575917 INFO (qtp24537389-20865) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-29 (1576684628064862208)]} 0 11
[junit4] 2> 1575925 INFO (qtp13274547-20875) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-30 (1576684628073250816)]} 0 0
[junit4] 2> 1575928 INFO (qtp24537389-20878) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-30 (1576684628073250816)]} 0 6
[junit4] 2> 1575934 INFO (qtp13274547-20857) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-31 (1576684628082688000)]} 0 0
[junit4] 2> 1575936 INFO (qtp24537389-20864) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-31 (1576684628082688000)]} 0 5
[junit4] 2> 1575945 INFO (qtp13274547-20859) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-21 (-1576684628092125184)]} 0 3
[junit4] 2> 1575946 INFO (qtp24537389-20877) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-21 (-1576684628092125184)]} 0 6
[junit4] 2> 1575952 INFO (qtp13274547-20860) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-32 (1576684628101562368)]} 0 1
[junit4] 2> 1575953 INFO (qtp24537389-20879) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-32 (1576684628101562368)]} 0 4
[junit4] 2> 1575969 INFO (qtp13274547-20862) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-33 (1576684628110999552)]} 0 2
[junit4] 2> 1575969 INFO (qtp24537389-20871) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-33 (1576684628110999552)]} 0 11
[junit4] 2> 1575974 INFO (qtp13274547-20876) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-23 (-1576684628125679616)]} 0 1
[junit4] 2> 1575975 INFO (qtp24537389-20867) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-23 (-1576684628125679616)]} 0 4
[junit4] 2> 1575983 INFO (qtp13274547-20855) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-34 (1576684628134068224)]} 0 0
[junit4] 2> 1575983 INFO (qtp24537389-20868) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-34 (1576684628134068224)]} 0 3
[junit4] 2> 1575990 INFO (qtp13274547-20875) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-24 (-1576684628139311104)]} 0 2
[junit4] 2> 1575994 INFO (qtp24537389-20866) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-24 (-1576684628139311104)]} 0 7
[junit4] 2> 1576005 INFO (qtp13274547-20857) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-35 (1576684628155039744)]} 0 2
[junit4] 2> 1576006 INFO (qtp24537389-20865) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-35 (1576684628155039744)]} 0 10
[junit4] 2> 1576021 INFO (qtp13274547-20860) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-36 (1576684628173914112)]} 0 1
[junit4] 2> 1576022 INFO (qtp24537389-20878) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-36 (1576684628173914112)]} 0 4
[junit4] 2> 1576028 INFO (qtp13274547-20860) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-37 (1576684628181254144)]} 0 0
[junit4] 2> 1576029 INFO (qtp24537389-20878) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-37 (1576684628181254144)]} 0 3
[junit4] 2> 1576039 INFO (qtp13274547-20876) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-27 (-1576684628189642752)]} 0 2
[junit4] 2> 1576041 INFO (qtp24537389-20877) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-27 (-1576684628189642752)]} 0 6
[junit4] 2> 1576048 INFO (qtp13274547-20855) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-38 (1576684628200128512)]} 0 1
[junit4] 2> 1576050 INFO (qtp24537389-20879) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-38 (1576684628200128512)]} 0 5
[junit4] 2> 1576055 INFO (qtp13274547-20875) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-31 (-1576684628210614272)]} 0 0
[junit4] 2> 1576056 INFO (qtp24537389-20871) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-31 (-1576684628210614272)]} 0 3
[junit4] 2> 1576061 INFO (qtp13274547-20875) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-39 (1576684628215857152)]} 0 0
[junit4] 2> 1576062 INFO (qtp24537389-20871) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-39 (1576684628215857152)]} 0 4
[junit4] 2> 1576086 INFO (qtp13274547-20857) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-40 (1576684628230537216)]} 0 2
[junit4] 2> 1576088 INFO (qtp24537389-20867) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-40 (1576684628230537216)]} 0 16
[junit4] 2> 1576115 INFO (qtp13274547-20862) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-32 (-1576684628252557312)]} 0 18
[junit4] 2> 1576115 INFO (qtp24537389-20868) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-32 (-1576684628252557312)]} 0 21
[junit4] 2> 1576119 INFO (qtp13274547-20859) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-41 (1576684628277723136)]} 0 0
[junit4] 2> 1576119 INFO (qtp24537389-20866) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-41 (1576684628277723136)]} 0 3
[junit4] 2> 1576134 INFO (qtp13274547-20859) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-34 (-1576684628279820288)]} 0 0
[junit4] 2> 1576137 INFO (qtp24537389-20866) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-34 (-1576684628279820288)]} 0 11
[junit4] 2> 1576174 INFO (qtp13274547-20861) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-42 (1576684628306034688)]} 0 27
[junit4] 2> 1576174 INFO (qtp24537389-20864) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-42 (1576684628306034688)]} 0 30
[junit4] 2> 1576182 INFO (qtp13274547-20875) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-36 (-1576684628343783424)]} 0 1
[junit4] 2> 1576182 INFO (qtp24537389-20878) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-36 (-1576684628343783424)]} 0 3
[junit4] 2> 1576182 INFO (qtp13274547-20860) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-43 (1576684628345880576)]} 0 1
[junit4] 2> 1576182 INFO (qtp24537389-20877) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-43 (1576684628345880576)]} 0 4
[junit4] 2> 1576182 INFO (qtp13274547-20876) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-44 (1576684628345880577)]} 0 1
[junit4] 2> 1576182 INFO (qtp24537389-20879) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-44 (1576684628345880577)]} 0 4
[junit4] 2> 1576210 INFO (qtp13274547-20876) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-45 (1576684628371046400)]} 0 2
[junit4] 2> 1576211 INFO (qtp24537389-20879) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-45 (1576684628371046400)]} 0 6
[junit4] 2> 1576213 INFO (qtp13274547-20859) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-37 (-1576684628378386432)]} 0 0
[junit4] 2> 1576213 INFO (qtp24537389-20871) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-37 (-1576684628378386432)]} 0 3
[junit4] 2> 1576223 INFO (qtp13274547-20859) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-46 (1576684628385726464)]} 0 0
[junit4] 2> 1576225 INFO (qtp24537389-20871) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-46 (1576684628385726464)]} 0 4
[junit4] 2> 1576230 INFO (qtp13274547-20861) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-47 (1576684628393066496)]} 0 1
[junit4] 2> 1576231 INFO (qtp24537389-20868) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-47 (1576684628393066496)]} 0 4
[junit4] 2> 1576237 INFO (qtp13274547-20862) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-48 (1576684628400406528)]} 0 0
[junit4] 2> 1576238 INFO (qtp24537389-20865) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-48 (1576684628400406528)]} 0 4
[junit4] 2> 1576243 INFO (qtp13274547-20875) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-38 (-1576684628406697984)]} 0 0
[junit4] 2> 1576243 INFO (qtp24537389-20866) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-38 (-1576684628406697984)]} 0 3
[junit4] 2> 1576248 INFO (qtp13274547-20860) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-49 (1576684628412989440)]} 0 0
[junit4] 2> 1576249 INFO (qtp24537389-20864) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-49 (1576684628412989440)]} 0 4
[junit4] 2> 1576255 INFO (qtp13274547-20855) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-41 (-1576684628419280896)]} 0 0
[junit4] 2> 1576258 INFO (qtp24537389-20878) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-41 (-1576684628419280896)]} 0 5
[junit4] 2> 1576274 INFO (qtp13274547-20876) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-50 (1576684628433960960)]} 0 0
[junit4] 2> 1576275 INFO (qtp24537389-20877) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-50 (1576684628433960960)]} 0 9
[junit4] 2> 1576282 INFO (qtp13274547-20857) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-51 (1576684628445495296)]} 0 2
[junit4] 2> 1576284 INFO (qtp24537389-20879) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-51 (1576684628445495296)]} 0 5
[junit4] 2> 1576287 INFO (qtp13274547-20857) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-44 (-1576684628453883904)]} 0 0
[junit4] 2> 1576288 INFO (qtp24537389-20867) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-44 (-1576684628453883904)]} 0 3
[junit4] 2> 1576300 INFO (qtp13274547-20862) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-52 (1576684628459126784)]} 0 1
[junit4] 2> 1576304 INFO (qtp24537389-20871) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-52 (1576684628459126784)]} 0 13
[junit4] 2> 1576311 INFO (qtp13274547-20875) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-53 (1576684628479049728)]} 0 0
[junit4] 2> 1576312 INFO (qtp24537389-20868) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-53 (1576684628479049728)]} 0 3
[junit4] 2> 1576321 INFO (qtp13274547-20875) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-45 (-1576684628489535488)]} 0 0
[junit4] 2> 1576322 INFO (qtp24537389-20865) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-45 (-1576684628489535488)]} 0 3
[junit4] 2> 1576333 INFO (qtp13274547-20875) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-54 (1576684628492681216)]} 0 6
[junit4] 2> 1576334 INFO (qtp24537389-20866) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-54 (1576684628492681216)]} 0 10
[junit4] 2> 1576366 INFO (qtp13274547-20876) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-46 (-1576684628526235648)]} 0 10
[junit4] 2> 1576367 INFO (qtp24537389-20864) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-46 (-1576684628526235648)]} 0 13
[junit4] 2> 1576384 INFO (qtp13274547-20861) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-55 (1576684628541964288)]} 0 1
[junit4] 2> 1576384 INFO (qtp24537389-20878) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-55 (1576684628541964288)]} 0 15
[junit4] 2> 1576390 INFO (qtp13274547-20861) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-47 (-1576684628560838656)]} 0 0
[junit4] 2> 1576394 INFO (qtp24537389-20877) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-47 (-1576684628560838656)]} 0 7
[junit4] 2> 1576402 INFO (qtp13274547-20861) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-56 (1576684628573421568)]} 0 0
[junit4] 2> 1576403 INFO (qtp24537389-20879) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-56 (1576684628573421568)]} 0 3
[junit4] 2> 1576408 INFO (qtp13274547-20855) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-57 (1576684628580761600)]} 0 0
[junit4] 2> 1576409 INFO (qtp24537389-20867) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-57 (1576684628580761600)]} 0 3
[junit4] 2> 1576411 INFO (qtp13274547-20859) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-48 (-1576684628586004480)]} 0 0
[junit4] 2> 1576415 INFO (qtp24537389-20871) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-48 (-1576684628586004480)]} 0 3
[junit4] 2> 1576420 INFO (qtp13274547-20859) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-58 (1576684628593344512)]} 0 0
[junit4] 2> 1576421 INFO (qtp24537389-20868) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-58 (1576684628593344512)]} 0 3
[junit4] 2> 1576430 INFO (qtp13274547-20859) [ ] o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:57140/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-50 (-1576684628603830272)]} 0 0
[junit4] 2> 1576432 INFO
[...truncated too long message...]
HttpSolrCall.call(HttpSolrCall.java:507)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:378)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:322)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:202)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2>
[junit4] 2> 1675634 INFO (qtp13274547-20855) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={nodeName=127.0.0.1:57140_solr&onlyIfLeaderActive=true&core=oldindextest_shard1_replica_n1&coreNodeName=core_node2&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2} status=400 QTime=93095
[junit4] 2> 1675635 ERROR (SUITE-CleanupOldIndexTest-seed#[2582C888A414E4A6]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 1675635 INFO (SUITE-CleanupOldIndexTest-seed#[2582C888A414E4A6]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:57133 57133
[junit4] 2> 1675636 INFO (Thread-3645) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:57133 57133
[junit4] 2> 1675637 WARN (Thread-3645) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/aliases.json
[junit4] 2> 3 /solr/security.json
[junit4] 2> 3 /solr/configs/conf1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 26 /solr/collections/oldindextest/state.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2> 4 /solr/clusterprops.json
[junit4] 2> 2 /solr/overseer_elect/election/98542788895375366-127.0.0.1:57137_solr-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/collections
[junit4] 2> 3 /solr/live_nodes
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-7.0-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CleanupOldIndexTest_2582C888A414E4A6-001
[junit4] 2> Aug 25, 2017 6:48:54 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 4 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {rnd_b=PostingsFormat(name=LuceneFixedGap), a_t=PostingsFormat(name=LuceneVarGapFixedInterval), id=PostingsFormat(name=LuceneFixedGap)}, docValues:{}, maxPointsInLeafNode=686, maxMBSortInHeap=5.414215189891824, sim=RandomSimilarity(queryNorm=false): {}, locale=hr, timezone=Asia/Muscat
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 9 (32-bit)/cpus=3,threads=1,free=324004696,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [FastVectorHighlighterTest, SimplePostToolTest, TestReplicaProperties, TestDistributedSearch, ShufflingReplicaListTransformerTest, TestClusterStateMutator, WrapperMergePolicyFactoryTest, TestCSVResponseWriter, SolrTestCaseJ4Test, PathHierarchyTokenizerFactoryTest, ZkStateWriterTest, TestComplexPhraseLeadingWildcard, TestSolrQueryResponse, TestFieldResource, RankQueryTest, GraphQueryTest, TestStressLiveNodes, ChaosMonkeyNothingIsSafeTest, DeleteInactiveReplicaTest, TestExtendedDismaxParser, CollectionsAPISolrJTest, TestFieldCacheVsDocValues, TestRecoveryHdfs, MultiThreadedOCPTest, TestSystemCollAutoCreate, TestStressCloudBlindAtomicUpdates, TestSubQueryTransformerDistrib, TestSolrCoreParser, AddBlockUpdateTest, TestPseudoReturnFields, CdcrBootstrapTest, RuleEngineTest, LeaderFailureAfterFreshStartTest, LeaderInitiatedRecoveryOnShardRestartTest, InfoHandlerTest, FieldAnalysisRequestHandlerTest, ChaosMonkeySafeLeaderWithPullReplicasTest, TestLFUCache, DistributedFacetPivotSmallTest, DistributedFacetExistsSmallTest, RecoveryAfterSoftCommitTest, DistributedVersionInfoTest, BlobRepositoryCloudTest, TestMultiValuedNumericRangeQuery, TestDistributedStatsComponentCardinality, TestCopyFieldCollectionResource, TestHdfsCloudBackupRestore, CdcrUpdateLogTest, UniqFieldsUpdateProcessorFactoryTest, TestLegacyFieldCache, ConfigSetsAPITest, ZkControllerTest, DistributedQueryComponentCustomSortTest, TestCloudDeleteByQuery, ClusterStateTest, SolrIndexSplitterTest, TestPointFields, TestReversedWildcardFilterFactory, TestJmxIntegration, SoftAutoCommitTest, SolrInfoBeanTest, DistributedFacetPivotLongTailTest, TestLegacyNumericUtils, TestSQLHandler, DistributedSuggestComponentTest, AnalyticsQueryTest, TestIndexingPerformance, TestAddFieldRealTimeGet, TestDistribIDF, TestSolrDeletionPolicy2, TestPrepRecovery, CdcrVersionReplicationTest, TestSizeLimitedDistributedMap, SolrGraphiteReporterTest, TestOmitPositions, TestPolicyCloud, MoveReplicaHDFSUlogDirTest, TestCorePropertiesReload, TestPKIAuthenticationPlugin, TestMiniSolrCloudCluster, RequestHandlersTest, TestReqParamsAPI, TestHdfsUpdateLog, ConnectionReuseTest, TestUpdate, HdfsLockFactoryTest, TestUtils, IndexBasedSpellCheckerTest, SuggesterTest, TestSolrConfigHandlerCloud, LeaderElectionTest, TestDFRSimilarityFactory, TestCustomDocTransformer, TestSlowCompositeReaderWrapper, RecoveryZkTest, TestConfigSetProperties, HdfsRecoveryZkTest, ShardRoutingTest, SolrXmlInZkTest, TestInPlaceUpdatesStandalone, TestGroupingSearch, TestRandomRequestDistribution, TestDeleteCollectionOnDownNodes, SolrCoreTest, TestSubQueryTransformer, TestMacros, TestTrackingShardHandlerFactory, OverseerTaskQueueTest, CdcrReplicationHandlerTest, CollectionReloadTest, TestIntervalFaceting, TestNoOpRegenerator, TestDynamicLoading, TestTrieFacet, CoreSorterTest, ClassificationUpdateProcessorFactoryTest, HighlighterConfigTest, PKIAuthenticationIntegrationTest, MissingSegmentRecoveryTest, TestRawResponseWriter, TestCloudManagedSchema, TestNRTOpen, BlockCacheTest, TestSmileRequest, BlockJoinFacetRandomTest, TestPhraseSuggestions, RAMDirectoryFactoryTest, TestConfigSetsAPI, TestCursorMarkWithoutUniqueKey, TestNestedDocsSort, TestDocBasedVersionConstraints, AnalysisAfterCoreReloadTest, TestManagedResourceStorage, TestSchemaVersionResource, TestXmlQParserPlugin, TestMiniSolrCloudClusterSSL, TestEmbeddedSolrServerSchemaAPI, TestBulkSchemaAPI, TestDynamicFieldResource, TestNamedUpdateProcessors, EnumFieldTest, WordBreakSolrSpellCheckerTest, TestSolrCoreProperties, DistributedSpellCheckComponentTest, SuggestComponentContextFilterQueryTest, TestLegacyNumericRangeQueryBuilder, TestSolrCloudSnapshots, AtomicUpdateProcessorFactoryTest, TestHighlightDedupGrouping, TestJsonFacets, UUIDUpdateProcessorFallbackTest, DateRangeFieldTest, TestHalfAndHalfDocValues, TestStressReorder, TestManagedSynonymFilterFactory, QueryResultKeyTest, ExplicitHLLTest, HighlighterMaxOffsetTest, DeleteShardTest, TestShardHandlerFactory, DisMaxRequestHandlerTest, TestOrdValues, SegmentsInfoRequestHandlerTest, SliceStateTest, TestRandomDVFaceting, BigEndianAscendingWordSerializerTest, TestRTimerTree, ClassificationUpdateProcessorTest, CleanupOldIndexTest]
[junit4] Completed [379/729 (1!)] on J0 in 105.73s, 1 test, 1 error <<< FAILURES!
[...truncated 37673 lines...]