Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Windows/122/ Java: 64bit/jdk1.8.0_144 -XX:+UseCompressedOops -XX:+UseParallelGC
1 tests failed. FAILED: org.apache.solr.cloud.CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster Error Message: Document mismatch on target after sync expected:<2000> but was:<1012> Stack Trace: java.lang.AssertionError: Document mismatch on target after sync expected:<2000> but was:<1012> at __randomizedtesting.SeedInfo.seed([13579F6828A085BD:C712D431CFF63646]: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 12402 lines...] [junit4] Suite: org.apache.solr.cloud.CdcrBootstrapTest [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTest_13579F6828A085BD-001\init-core-data-001 [junit4] 2> 1800226 WARN (SUITE-CdcrBootstrapTest-seed#[13579F6828A085BD]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=13 numCloses=13 [junit4] 2> 1800227 INFO (SUITE-CdcrBootstrapTest-seed#[13579F6828A085BD]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 1800230 INFO (SUITE-CdcrBootstrapTest-seed#[13579F6828A085BD]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) [junit4] 2> 1800230 INFO (SUITE-CdcrBootstrapTest-seed#[13579F6828A085BD]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 1800233 INFO (TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[13579F6828A085BD]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBootstrapWithSourceCluster [junit4] 2> 1800234 INFO (TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[13579F6828A085BD]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTest_13579F6828A085BD-001\cdcr-target-001 [junit4] 2> 1800234 INFO (TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[13579F6828A085BD]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1800234 INFO (Thread-3189) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1800234 INFO (Thread-3189) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1800241 ERROR (Thread-3189) [ ] 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> 1800334 INFO (TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[13579F6828A085BD]) [ ] o.a.s.c.ZkTestServer start zk server on port:55512 [junit4] 2> 1800354 INFO (jetty-launcher-2346-thread-1) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 1800355 INFO (jetty-launcher-2346-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@256a4434{/solr,null,AVAILABLE} [junit4] 2> 1800365 INFO (jetty-launcher-2346-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3f521c98{HTTP/1.1,[http/1.1]}{127.0.0.1:55517} [junit4] 2> 1800365 INFO (jetty-launcher-2346-thread-1) [ ] o.e.j.s.Server Started @1808126ms [junit4] 2> 1800365 INFO (jetty-launcher-2346-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=55517} [junit4] 2> 1800365 ERROR (jetty-launcher-2346-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1800365 INFO (jetty-launcher-2346-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.1.0 [junit4] 2> 1800365 INFO (jetty-launcher-2346-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1800365 INFO (jetty-launcher-2346-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 1800365 INFO (jetty-launcher-2346-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-18T10:51:26.928Z [junit4] 2> 1800378 INFO (jetty-launcher-2346-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1800393 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 0x15df4f81ad30001, 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> 1800401 INFO (jetty-launcher-2346-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55512/solr [junit4] 2> 1800535 INFO (jetty-launcher-2346-thread-1) [n:127.0.0.1:55517_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1800536 INFO (jetty-launcher-2346-thread-1) [n:127.0.0.1:55517_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:55517_solr [junit4] 2> 1800537 INFO (jetty-launcher-2346-thread-1) [n:127.0.0.1:55517_solr ] o.a.s.c.Overseer Overseer (id=98504113311121411-127.0.0.1:55517_solr-n_0000000000) starting [junit4] 2> 1800546 INFO (jetty-launcher-2346-thread-1) [n:127.0.0.1:55517_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55517_solr [junit4] 2> 1800553 INFO (OverseerStateUpdate-98504113311121411-127.0.0.1:55517_solr-n_0000000000) [n:127.0.0.1:55517_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1800758 INFO (jetty-launcher-2346-thread-1) [n:127.0.0.1:55517_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55517.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@642e892a [junit4] 2> 1800764 INFO (jetty-launcher-2346-thread-1) [n:127.0.0.1:55517_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55517.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@642e892a [junit4] 2> 1800764 INFO (jetty-launcher-2346-thread-1) [n:127.0.0.1:55517_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55517.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@642e892a [junit4] 2> 1800774 INFO (jetty-launcher-2346-thread-1) [n:127.0.0.1:55517_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTest_13579F6828A085BD-001\cdcr-target-001\node1\. [junit4] 1> Target zkHost = 127.0.0.1:55512/solr [junit4] 2> 1800808 INFO (TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[13579F6828A085BD]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTest_13579F6828A085BD-001\cdcr-source-001 [junit4] 2> 1800808 INFO (TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[13579F6828A085BD]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1800808 INFO (Thread-3199) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1800808 INFO (Thread-3199) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1800812 ERROR (Thread-3199) [ ] 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> 1800908 INFO (TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[13579F6828A085BD]) [ ] o.a.s.c.ZkTestServer start zk server on port:55537 [junit4] 2> 1800921 INFO (jetty-launcher-2358-thread-1) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 1800921 INFO (jetty-launcher-2358-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4cba95ea{/solr,null,AVAILABLE} [junit4] 2> 1800922 INFO (jetty-launcher-2358-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2d098bf8{HTTP/1.1,[http/1.1]}{127.0.0.1:55542} [junit4] 2> 1800922 INFO (jetty-launcher-2358-thread-1) [ ] o.e.j.s.Server Started @1808685ms [junit4] 2> 1800922 INFO (jetty-launcher-2358-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=55542} [junit4] 2> 1800922 ERROR (jetty-launcher-2358-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1800922 INFO (jetty-launcher-2358-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.1.0 [junit4] 2> 1800922 INFO (jetty-launcher-2358-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1800922 INFO (jetty-launcher-2358-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 1800922 INFO (jetty-launcher-2358-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-18T10:51:27.485Z [junit4] 2> 1800928 INFO (jetty-launcher-2358-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1800938 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 0x15df4f81d0f0001, 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> 1800941 INFO (jetty-launcher-2358-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55537/solr [junit4] 2> 1800945 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 0x15df4f81d0f0002, 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> 1801075 INFO (jetty-launcher-2358-thread-1) [n:127.0.0.1:55542_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1801076 INFO (jetty-launcher-2358-thread-1) [n:127.0.0.1:55542_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:55542_solr [junit4] 2> 1801077 INFO (jetty-launcher-2358-thread-1) [n:127.0.0.1:55542_solr ] o.a.s.c.Overseer Overseer (id=98504113348608003-127.0.0.1:55542_solr-n_0000000000) starting [junit4] 2> 1801085 INFO (jetty-launcher-2358-thread-1) [n:127.0.0.1:55542_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55542_solr [junit4] 2> 1801135 INFO (zkCallback-2365-thread-1-processing-n:127.0.0.1:55542_solr) [n:127.0.0.1:55542_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1801230 INFO (jetty-launcher-2358-thread-1) [n:127.0.0.1:55542_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55542.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@642e892a [junit4] 2> 1801236 INFO (jetty-launcher-2358-thread-1) [n:127.0.0.1:55542_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55542.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@642e892a [junit4] 2> 1801236 INFO (jetty-launcher-2358-thread-1) [n:127.0.0.1:55542_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55542.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@642e892a [junit4] 2> 1801238 INFO (jetty-launcher-2358-thread-1) [n:127.0.0.1:55542_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTest_13579F6828A085BD-001\cdcr-source-001\node1\. [junit4] 2> 1801268 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 0x15df4f81d0f0004, 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> 1801287 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 0x15df4f81d0f0006, 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> 1801293 INFO (TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[13579F6828A085BD]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1801294 INFO (TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[13579F6828A085BD]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55537/solr ready [junit4] 2> 1801303 INFO (qtp1110221996-15840) [n:127.0.0.1:55542_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> 1801306 INFO (OverseerThreadFactory-6277-thread-1-processing-n:127.0.0.1:55542_solr) [n:127.0.0.1:55542_solr ] o.a.s.c.CreateCollectionCmd Create collection cdcr-source [junit4] 2> 1801415 INFO (OverseerStateUpdate-98504113348608003-127.0.0.1:55542_solr-n_0000000000) [n:127.0.0.1:55542_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:55542/solr", [junit4] 2> "type":"NRT"} [junit4] 2> 1801619 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_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_node2&name=cdcr-source_shard1_replica_n1&action=CREATE&numShards=1&shard=shard1&property.solr.directoryFactory=solr.StandardDirectoryFactory&wt=javabin [junit4] 2> 1801620 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1801728 INFO (zkCallback-2365-thread-1-processing-n:127.0.0.1:55542_solr) [n:127.0.0.1:55542_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> 1801728 INFO (zkCallback-2365-thread-2-processing-n:127.0.0.1:55542_solr) [n:127.0.0.1:55542_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> 1802660 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0 [junit4] 2> 1802677 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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> 1802681 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.s.IndexSchema [cdcr-source_shard1_replica_n1] Schema name=minimal [junit4] 2> 1802686 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1802688 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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> 1802691 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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> 1802691 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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> 1802693 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55542.solr.core.cdcr-source.shard1.replica_n1' (registry 'solr.core.cdcr-source.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@642e892a [junit4] 2> 1802693 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1802693 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTest_13579F6828A085BD-001\cdcr-source-001\node1\cdcr-source_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTest_13579F6828A085BD-001\cdcr-source-001\node1\.\cdcr-source_shard1_replica_n1\data\] [junit4] 2> 1802935 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog [junit4] 2> 1802935 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1802937 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1802937 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1802946 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3d577251[cdcr-source_shard1_replica_n1] main] [junit4] 2> 1802947 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/cdcr-source [junit4] 2> 1802951 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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> 1802951 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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> 1802961 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest [junit4] 2> 1802962 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1802974 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrBufferStateManager Created znode /collections/cdcr-source/cdcr/state/buffer [junit4] 2> 1802985 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrProcessStateManager Created znode /collections/cdcr-source/cdcr/state/process [junit4] 2> 1803058 INFO (searcherExecutor-6279-thread-1-processing-n:127.0.0.1:55542_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node2) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_replica_n1] Registered new searcher Searcher@3d577251[cdcr-source_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1803058 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1576065815932829696 [junit4] 2> 1803072 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1803072 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1803072 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:55542/solr/cdcr-source_shard1_replica_n1/ [junit4] 2> 1803072 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1803072 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:55542/solr/cdcr-source_shard1_replica_n1/ has no replicas [junit4] 2> 1803072 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 1803076 INFO (zkCallback-2365-thread-2-processing-n:127.0.0.1:55542_solr) [n:127.0.0.1:55542_solr ] o.a.s.h.CdcrLeaderStateManager Received new leader state @ cdcr-source:shard1 [junit4] 2> 1803083 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:55542/solr/cdcr-source_shard1_replica_n1/ shard1 [junit4] 2> 1803189 INFO (zkCallback-2365-thread-1-processing-n:127.0.0.1:55542_solr) [n:127.0.0.1:55542_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> 1803189 INFO (zkCallback-2365-thread-2-processing-n:127.0.0.1:55542_solr) [n:127.0.0.1:55542_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> 1803237 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1803238 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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_node2&name=cdcr-source_shard1_replica_n1&action=CREATE&numShards=1&shard=shard1&property.solr.directoryFactory=solr.StandardDirectoryFactory&wt=javabin} status=0 QTime=1619 [junit4] 2> 1803244 INFO (qtp1110221996-15840) [n:127.0.0.1:55542_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> 1803308 INFO (OverseerCollectionConfigSetProcessor-98504113348608003-127.0.0.1:55542_solr-n_0000000000) [n:127.0.0.1:55542_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> 1803346 INFO (zkCallback-2365-thread-2-processing-n:127.0.0.1:55542_solr) [n:127.0.0.1:55542_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> 1803346 INFO (zkCallback-2365-thread-1-processing-n:127.0.0.1:55542_solr) [n:127.0.0.1:55542_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> 1804246 INFO (qtp1110221996-15840) [n:127.0.0.1:55542_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=2943 [junit4] 1> Adding 10 docs with commit=true, numDocs=100 [junit4] 2> 1804266 INFO (qtp1110221996-15843) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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=14 [junit4] 2> 1804267 INFO (qtp1110221996-15844) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1576065817200558080,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1804267 INFO (qtp1110221996-15844) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7a49f73e commitCommandVersion:1576065817200558080 [junit4] 2> 1804312 INFO (qtp1110221996-15844) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@106e69e4[cdcr-source_shard1_replica_n1] main] [junit4] 2> 1804313 INFO (qtp1110221996-15844) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1804314 INFO (searcherExecutor-6279-thread-1-processing-n:127.0.0.1:55542_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node2) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_replica_n1] Registered new searcher Searcher@106e69e4[cdcr-source_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):C100)))} [junit4] 2> 1804315 INFO (qtp1110221996-15844) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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=46 [junit4] 1> Adding 10 docs with commit=true, numDocs=200 [junit4] 2> 1804329 INFO (qtp1110221996-15845) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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=13 [junit4] 2> 1804331 INFO (qtp1110221996-15838) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1576065817267666944,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1804331 INFO (qtp1110221996-15838) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7a49f73e commitCommandVersion:1576065817267666944 [junit4] 2> 1804443 INFO (qtp1110221996-15838) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@39b3b290[cdcr-source_shard1_replica_n1] main] [junit4] 2> 1804444 INFO (searcherExecutor-6279-thread-1-processing-n:127.0.0.1:55542_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node2) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_replica_n1] Registered new searcher Searcher@39b3b290[cdcr-source_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):C100) Uninverting(_1(7.1.0):C100)))} [junit4] 2> 1804445 INFO (qtp1110221996-15838) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1804445 INFO (qtp1110221996-15838) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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=115 [junit4] 1> Adding 10 docs with commit=true, numDocs=300 [junit4] 2> 1804472 INFO (qtp1110221996-15838) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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=24 [junit4] 2> 1804479 INFO (qtp1110221996-15842) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1576065817422856192,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1804479 INFO (qtp1110221996-15842) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7a49f73e commitCommandVersion:1576065817422856192 [junit4] 2> 1804765 INFO (qtp1110221996-15842) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@48fb5c5e[cdcr-source_shard1_replica_n1] main] [junit4] 2> 1804770 INFO (searcherExecutor-6279-thread-1-processing-n:127.0.0.1:55542_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node2) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_replica_n1] Registered new searcher Searcher@48fb5c5e[cdcr-source_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):C100) Uninverting(_1(7.1.0):C100) Uninverting(_2(7.1.0):C100)))} [junit4] 2> 1804770 INFO (qtp1110221996-15842) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1804771 INFO (qtp1110221996-15842) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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=298 [junit4] 1> Adding 10 docs with commit=true, numDocs=400 [junit4] 2> 1804788 INFO (qtp1110221996-15842) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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=8 [junit4] 2> 1804789 INFO (qtp1110221996-15842) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1576065817747914752,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1804789 INFO (qtp1110221996-15842) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7a49f73e commitCommandVersion:1576065817747914752 [junit4] 2> 1804832 INFO (qtp1110221996-15842) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3baf72a3[cdcr-source_shard1_replica_n1] main] [junit4] 2> 1804834 INFO (searcherExecutor-6279-thread-1-processing-n:127.0.0.1:55542_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node2) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_replica_n1] Registered new searcher Searcher@3baf72a3[cdcr-source_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):C100) Uninverting(_1(7.1.0):C100) Uninverting(_2(7.1.0):C100) Uninverting(_3(7.1.0):C100)))} [junit4] 2> 1804834 INFO (qtp1110221996-15842) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1804834 INFO (qtp1110221996-15842) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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=45 [junit4] 1> Adding 10 docs with commit=true, numDocs=500 [junit4] 2> 1804841 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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> 1804842 INFO (qtp1110221996-15840) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1576065817803489280,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1804843 INFO (qtp1110221996-15840) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7a49f73e commitCommandVersion:1576065817803489280 [junit4] 2> 1804897 INFO (qtp1110221996-15840) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@772656a6[cdcr-source_shard1_replica_n1] main] [junit4] 2> 1804899 INFO (searcherExecutor-6279-thread-1-processing-n:127.0.0.1:55542_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node2) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_replica_n1] Registered new searcher Searcher@772656a6[cdcr-source_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):C100) Uninverting(_1(7.1.0):C100) Uninverting(_2(7.1.0):C100) Uninverting(_3(7.1.0):C100) Uninverting(_4(7.1.0):C100)))} [junit4] 2> 1804900 INFO (qtp1110221996-15840) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1804900 INFO (qtp1110221996-15840) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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=57 [junit4] 1> Adding 10 docs with commit=true, numDocs=600 [junit4] 2> 1804906 INFO (qtp1110221996-15840) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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> 1804907 INFO (qtp1110221996-15843) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1576065817871646720,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1804907 INFO (qtp1110221996-15843) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7a49f73e commitCommandVersion:1576065817871646720 [junit4] 2> 1804970 INFO (qtp1110221996-15843) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@13b0ccce[cdcr-source_shard1_replica_n1] main] [junit4] 2> 1804972 INFO (searcherExecutor-6279-thread-1-processing-n:127.0.0.1:55542_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node2) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_replica_n1] Registered new searcher Searcher@13b0ccce[cdcr-source_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):C100) Uninverting(_1(7.1.0):C100) Uninverting(_2(7.1.0):C100) Uninverting(_3(7.1.0):C100) Uninverting(_4(7.1.0):C100) Uninverting(_5(7.1.0):C100)))} [junit4] 2> 1804973 INFO (qtp1110221996-15843) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1804973 INFO (qtp1110221996-15843) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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=66 [junit4] 1> Adding 10 docs with commit=true, numDocs=700 [junit4] 2> 1804981 INFO (qtp1110221996-15843) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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> 1804982 INFO (qtp1110221996-15844) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1576065817950289920,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1804982 INFO (qtp1110221996-15844) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7a49f73e commitCommandVersion:1576065817950289920 [junit4] 2> 1805120 INFO (qtp1110221996-15844) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6e23cac4[cdcr-source_shard1_replica_n1] main] [junit4] 2> 1805120 INFO (qtp1110221996-15844) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1805127 INFO (searcherExecutor-6279-thread-1-processing-n:127.0.0.1:55542_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node2) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_replica_n1] Registered new searcher Searcher@6e23cac4[cdcr-source_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):C100) Uninverting(_1(7.1.0):C100) Uninverting(_2(7.1.0):C100) Uninverting(_3(7.1.0):C100) Uninverting(_4(7.1.0):C100) Uninverting(_5(7.1.0):C100) Uninverting(_6(7.1.0):C100)))} [junit4] 2> 1805130 INFO (qtp1110221996-15844) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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=148 [junit4] 1> Adding 10 docs with commit=true, numDocs=800 [junit4] 2> 1805136 INFO (qtp1110221996-15845) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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=6 [junit4] 2> 1805139 INFO (qtp1110221996-15838) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1576065818114916352,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1805139 INFO (qtp1110221996-15838) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7a49f73e commitCommandVersion:1576065818114916352 [junit4] 2> 1805293 INFO (qtp1110221996-15838) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1cece31c[cdcr-source_shard1_replica_n1] main] [junit4] 2> 1805293 INFO (qtp1110221996-15838) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1805300 INFO (searcherExecutor-6279-thread-1-processing-n:127.0.0.1:55542_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node2) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_replica_n1] Registered new searcher Searcher@1cece31c[cdcr-source_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):C100) Uninverting(_1(7.1.0):C100) Uninverting(_2(7.1.0):C100) Uninverting(_3(7.1.0):C100) Uninverting(_4(7.1.0):C100) Uninverting(_5(7.1.0):C100) Uninverting(_6(7.1.0):C100) Uninverting(_7(7.1.0):C100)))} [junit4] 2> 1805300 INFO (qtp1110221996-15838) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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=162 [junit4] 1> Adding 10 docs with commit=true, numDocs=900 [junit4] 2> 1805310 INFO (qtp1110221996-15842) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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> 1805310 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1576065818294222848,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1805310 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7a49f73e commitCommandVersion:1576065818294222848 [junit4] 2> 1805371 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@181a51f5[cdcr-source_shard1_replica_n1] main] [junit4] 2> 1805371 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1805372 INFO (searcherExecutor-6279-thread-1-processing-n:127.0.0.1:55542_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node2) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_replica_n1] Registered new searcher Searcher@181a51f5[cdcr-source_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):C100) Uninverting(_1(7.1.0):C100) Uninverting(_2(7.1.0):C100) Uninverting(_3(7.1.0):C100) Uninverting(_4(7.1.0):C100) Uninverting(_5(7.1.0):C100) Uninverting(_6(7.1.0):C100) Uninverting(_7(7.1.0):C100) Uninverting(_8(7.1.0):C100)))} [junit4] 2> 1805373 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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=62 [junit4] 1> Adding 10 docs with commit=true, numDocs=1000 [junit4] 2> 1805383 INFO (qtp1110221996-15840) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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=8 [junit4] 2> 1805383 INFO (qtp1110221996-15843) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1576065818370768896,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1805384 INFO (qtp1110221996-15843) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7a49f73e commitCommandVersion:1576065818370768896 [junit4] 2> 1805581 INFO (qtp1110221996-15843) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@165162f6[cdcr-source_shard1_replica_n1] main] [junit4] 2> 1805582 INFO (qtp1110221996-15843) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1805583 INFO (searcherExecutor-6279-thread-1-processing-n:127.0.0.1:55542_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node2) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_replica_n1] Registered new searcher Searcher@165162f6[cdcr-source_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):C100) Uninverting(_1(7.1.0):C100) Uninverting(_2(7.1.0):C100) Uninverting(_3(7.1.0):C100) Uninverting(_4(7.1.0):C100) Uninverting(_5(7.1.0):C100) Uninverting(_6(7.1.0):C100) Uninverting(_7(7.1.0):C100) Uninverting(_8(7.1.0):C100) Uninverting(_9(7.1.0):C100)))} [junit4] 2> 1805584 INFO (qtp1110221996-15843) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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=200 [junit4] 2> 1805588 INFO (qtp1110221996-15844) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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> 1805618 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 0x15df4f81ad30006, 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> 1805627 INFO (TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[13579F6828A085BD]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1805627 INFO (TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[13579F6828A085BD]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55512/solr ready [junit4] 2> 1805633 INFO (qtp1191266856-15797) [n:127.0.0.1:55517_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> 1805645 INFO (OverseerThreadFactory-6268-thread-1-processing-n:127.0.0.1:55517_solr) [n:127.0.0.1:55517_solr ] o.a.s.c.CreateCollectionCmd Create collection cdcr-target [junit4] 2> 1805767 INFO (OverseerStateUpdate-98504113311121411-127.0.0.1:55517_solr-n_0000000000) [n:127.0.0.1:55517_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:55517/solr", [junit4] 2> "type":"NRT"} [junit4] 2> 1805987 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&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> 1805988 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1806102 INFO (zkCallback-2353-thread-1-processing-n:127.0.0.1:55517_solr) [n:127.0.0.1:55517_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> 1806103 INFO (zkCallback-2353-thread-2-processing-n:127.0.0.1:55517_solr) [n:127.0.0.1:55517_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> 1807062 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0 [junit4] 2> 1807068 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 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> 1807073 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.s.IndexSchema [cdcr-target_shard1_replica_n1] Schema name=minimal [junit4] 2> 1807079 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1807080 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 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> 1807086 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 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> 1807087 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 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> 1807087 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55517.solr.core.cdcr-target.shard1.replica_n1' (registry 'solr.core.cdcr-target.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@642e892a [junit4] 2> 1807087 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1807088 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 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.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTest_13579F6828A085BD-001\cdcr-target-001\node1\cdcr-target_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTest_13579F6828A085BD-001\cdcr-target-001\node1\.\cdcr-target_shard1_replica_n1\data\] [junit4] 2> 1807230 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog [junit4] 2> 1807230 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1807232 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1807232 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1807233 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@54d4afbd[cdcr-target_shard1_replica_n1] main] [junit4] 2> 1807234 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/cdcr-target [junit4] 2> 1807234 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 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> 1807234 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 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> 1807235 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest [junit4] 2> 1807236 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1807239 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.h.CdcrBufferStateManager Created znode /collections/cdcr-target/cdcr/state/buffer [junit4] 2> 1807246 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.h.CdcrProcessStateManager Created znode /collections/cdcr-target/cdcr/state/process [junit4] 2> 1807246 INFO (searcherExecutor-6285-thread-1-processing-n:127.0.0.1:55517_solr x:cdcr-target_shard1_replica_n1 s:shard1 c:cdcr-target r:core_node2) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-target_shard1_replica_n1] Registered new searcher Searcher@54d4afbd[cdcr-target_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1807246 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1576065820324265984 [junit4] 2> 1807260 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1807260 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1807260 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:55517/solr/cdcr-target_shard1_replica_n1/ [junit4] 2> 1807260 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1807260 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:55517/solr/cdcr-target_shard1_replica_n1/ has no replicas [junit4] 2> 1807260 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 1807264 INFO (zkCallback-2353-thread-2-processing-n:127.0.0.1:55517_solr) [n:127.0.0.1:55517_solr ] o.a.s.h.CdcrLeaderStateManager Received new leader state @ cdcr-target:shard1 [junit4] 2> 1807274 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:55517/solr/cdcr-target_shard1_replica_n1/ shard1 [junit4] 2> 1807380 INFO (zkCallback-2353-thread-1-processing-n:127.0.0.1:55517_solr) [n:127.0.0.1:55517_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> 1807380 INFO (zkCallback-2353-thread-2-processing-n:127.0.0.1:55517_solr) [n:127.0.0.1:55517_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> 1807427 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1807427 INFO (qtp1191266856-15798) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&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=1444 [junit4] 2> 1807434 INFO (qtp1191266856-15797) [n:127.0.0.1:55517_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> 1807532 INFO (zkCallback-2353-thread-1-processing-n:127.0.0.1:55517_solr) [n:127.0.0.1:55517_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> 1807532 INFO (zkCallback-2353-thread-2-processing-n:127.0.0.1:55517_solr) [n:127.0.0.1:55517_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> 1807648 INFO (OverseerCollectionConfigSetProcessor-98504113311121411-127.0.0.1:55517_solr-n_0000000000) [n:127.0.0.1:55517_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> 1808435 INFO (qtp1191266856-15797) [n:127.0.0.1:55517_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=2801 [junit4] 2> 1808443 INFO (qtp1191266856-15801) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 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> 1808443 INFO (zkCallback-2353-thread-1-processing-n:127.0.0.1:55517_solr) [n:127.0.0.1:55517_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> 1808444 INFO (zkCallback-2353-thread-1-processing-n:127.0.0.1:55517_solr) [n:127.0.0.1:55517_solr ] o.a.s.h.CdcrProcessStateManager Received new CDCR process state from watcher: STARTED @ cdcr-target:shard1 [junit4] 2> 1808446 INFO (qtp1110221996-15844) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1808451 INFO (qtp1110221996-15844) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55512/solr ready [junit4] 2> 1808467 INFO (qtp1191266856-15804) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.h.CdcrRequestHandler Found maxVersionFromRecent 0 maxVersionFromIndex 0 [junit4] 2> 1808467 INFO (qtp1191266856-15804) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 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=3 [junit4] 2> 1808468 INFO (qtp1191266856-15803) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 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=15 [junit4] 2> 1808470 INFO (qtp1110221996-15844) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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> 1808470 INFO (qtp1110221996-15844) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrReplicatorManager Attempting to bootstrap target collection: cdcr-target, shard: shard1 [junit4] 2> 1808475 INFO (qtp1110221996-15844) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrReplicatorManager Submitting bootstrap task to executor [junit4] 2> 1808477 INFO (zkCallback-2365-thread-1-processing-n:127.0.0.1:55542_solr) [n:127.0.0.1:55542_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> 1808477 INFO (qtp1110221996-15844) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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=33 [junit4] 2> 1808477 INFO (zkCallback-2365-thread-1-processing-n:127.0.0.1:55542_solr) [n:127.0.0.1:55542_solr ] o.a.s.h.CdcrProcessStateManager Received new CDCR process state from watcher: STARTED @ cdcr-source:shard1 [junit4] 2> 1808477 INFO (cdcr-bootstrap-status-2377-thread-1-processing-n:127.0.0.1:55542_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node2) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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:55517/solr/cdcr-target_shard1_replica_n1/ [junit4] 2> 1808483 INFO (qtp1110221996-15838) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 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=queues&wt=javabin&version=2} status=0 QTime=4 [junit4] 1> Cdcr queue response: {responseHeader={status=0,QTime=4},queues={127.0.0.1:55512/solr={cdcr-target={queueSize=1010,lastTimestamp=}}},tlogTotalSize=47659,tlogTotalCount=10,updateLogSynchronizer=stopped} [junit4] 2> 1808483 INFO (qtp1191266856-15800) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 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:55542/solr/cdcr-source_shard1_replica_n1/&action=BOOTSTRAP&wt=javabin&version=2} status=0 QTime=2 [junit4] 2> 1808483 INFO (qtp1191266856-15797) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1576065821621354496,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1808483 INFO (qtp1191266856-15797) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 1808483 INFO (qtp1191266856-15797) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1808483 INFO (qtp1191266856-15797) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.c.S.Request [cdcr-target_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-target:4&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1808483 INFO (qtp1191266856-15797) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.c.S.Request [cdcr-target_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=cdcr-target:4&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 1808490 INFO (qtp1191266856-15800) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 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> 1808490 INFO (cdcr-bootstrap-status-2377-thread-1-processing-n:127.0.0.1:55542_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r:core_node2) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrReplicatorManager CDCR bootstrap running for 1 seconds, sleeping for 2000 ms [junit4] 2> 1808493 INFO (recoveryExecutor-2351-thread-1-processing-n:127.0.0.1:55517_solr x:cdcr-target_shard1_replica_n1 s:shard1 c:cdcr-target r:core_node2) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null} [junit4] 2> 1808494 INFO (qtp1110221996-15842) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1576065821632888832,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1808494 INFO (qtp1110221996-15842) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7a49f73e commitCommandVersion:1576065821632888832 [junit4] 2> 1808536 INFO (qtp1110221996-15842) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@bc77573[cdcr-source_shard1_replica_n1] realtime] [junit4] 2> 1808537 INFO (qtp1110221996-15842) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1808537 INFO (qtp1110221996-15842) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={waitSearcher=true&openSearcher=false&commit=true&softCommit=false&commit_end_point=true&wt=javabin&version=2} status=0 QTime=41 [junit4] 2> 1808539 INFO (qtp1110221996-15843) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 1808540 INFO (recoveryExecutor-2351-thread-1-processing-n:127.0.0.1:55517_solr x:cdcr-target_shard1_replica_n1 s:shard1 c:cdcr-target r:core_node2) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.h.IndexFetcher Master's generation: 12 [junit4] 2> 1808540 INFO (recoveryExecutor-2351-thread-1-processing-n:127.0.0.1:55517_solr x:cdcr-target_shard1_replica_n1 s:shard1 c:cdcr-target r:core_node2) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.h.IndexFetcher Master's version: 1503053495057 [junit4] 2> 1808540 INFO (recoveryExecutor-2351-thread-1-processing-n:127.0.0.1:55517_solr x:cdcr-target_shard1_replica_n1 s:shard1 c:cdcr-target r:core_node2) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 1808540 INFO (recoveryExecutor-2351-thread-1-processing-n:127.0.0.1:55517_solr x:cdcr-target_shard1_replica_n1 s:shard1 c:cdcr-target r:core_node2) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.h.IndexFetcher Slave's version: 0 [junit4] 2> 1808540 INFO (recoveryExecutor-2351-thread-1-processing-n:127.0.0.1:55517_solr x:cdcr-target_shard1_replica_n1 s:shard1 c:cdcr-target r:core_node2) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 1808551 INFO (qtp1110221996-15845) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.h.ReplicationHandler Adding tlog files to list: [{size=4657, name=tlog.0000000000000000000.1576065817183780864}, {size=4778, name=tlog.0000000000000000001.1576065817250889728}, {size=4778, name=tlog.0000000000000000002.1576065817389301760}, {size=4778, name=tlog.0000000000000000003.1576065817738477568}, {size=4778, name=tlog.0000000000000000004.1576065817797197824}, {size=4778, name=tlog.0000000000000000005.1576065817865355264}, {size=4778, name=tlog.0000000000000000006.1576065817942949888}, {size=4778, name=tlog.0000000000000000007.1576065818106527744}, {size=4778, name=tlog.0000000000000000008.1576065818285834240}, {size=4778, name=tlog.0000000000000000009.1576065818361331712}] [junit4] 2> 1808551 INFO (qtp1110221996-15845) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/replication params={generation=12&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=11 [junit4] 2> 1808552 INFO (recoveryExecutor-2351-thread-1-processing-n:127.0.0.1:55517_solr x:cdcr-target_shard1_replica_n1 s:shard1 c:cdcr-target r:core_node2) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.h.IndexFetcher Number of files in latest index in master: 23 [junit4] 2> 1808552 INFO (recoveryExecutor-2351-thread-1-processing-n:127.0.0.1:55517_solr x:cdcr-target_shard1_replica_n1 s:shard1 c:cdcr-target r:core_node2) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.h.IndexFetcher Number of tlog files in master: 10 [junit4] 2> 1808557 INFO (recoveryExecutor-2351-thread-1-processing-n:127.0.0.1:55517_solr x:cdcr-target_shard1_replica_n1 s:shard1 c:cdcr-target r:core_node2) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 1808557 INFO (recoveryExecutor-2351-thread-1-processing-n:127.0.0.1:55517_solr x:cdcr-target_shard1_replica_n1 s:shard1 c:cdcr-target r:core_node2) [n:127.0.0.1:55517_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@29558b40 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@240b5577) [junit4] 2> 1808557 INFO (qtp1110221996-15844) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/replication params={generation=12&qt=/replication&file=_a_Memory_0.ram&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1808560 INFO (qtp1110221996-15838) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/replication params={generation=12&qt=/replication&file=_a_Memory_0.mdvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1808567 INFO (qtp1110221996-15842) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/replication params={generation=12&qt=/replication&file=_a.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1808568 INFO (qtp1110221996-15840) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/replication params={generation=12&qt=/replication&file=_a_LuceneFixedGap_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1808569 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/replication params={generation=12&qt=/replication&file=_a_Lucene70_0.dvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1808572 INFO (qtp1110221996-15843) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/replication params={generation=12&qt=/replication&file=_a.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1808574 INFO (qtp1110221996-15845) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/replication params={generation=12&qt=/replication&file=_a.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1808576 INFO (qtp1110221996-15844) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/replication params={generation=12&qt=/replication&file=_a_LuceneFixedGap_0.pos&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1808579 INFO (qtp1110221996-15838) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/replication params={generation=12&qt=/replication&file=_a_LuceneFixedGap_0.tii&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1808580 INFO (qtp1110221996-15842) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/replication params={generation=12&qt=/replication&file=_a_Memory_0.mdvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1808582 INFO (qtp1110221996-15840) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/replication params={generation=12&qt=/replication&file=_a_LuceneFixedGap_0.tib&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1808584 INFO (qtp1110221996-15841) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/replication params={generation=12&qt=/replication&file=_a_TestBloomFilteredLucenePostings_0.blm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1808586 INFO (qtp1110221996-15843) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/replication params={generation=12&qt=/replication&file=_a_Direct_0.dvdm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1808611 INFO (qtp1110221996-15845) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/replication params={generation=12&qt=/replication&file=_a.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1808613 INFO (qtp1110221996-15844) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/replication params={generation=12&qt=/replication&file=_a.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1808614 INFO (qtp1110221996-15838) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/replication params={generation=12&qt=/replication&file=_a_TestBloomFilteredLucenePostings_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1808614 INFO (qtp1110221996-15842) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/replication params={generation=12&qt=/replication&file=_a_TestBloomFilteredLucenePostings_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1808619 INFO (qtp1110221996-15840) [n:127.0.0.1:55542_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] we [...truncated too long message...] get/state.json] for collection [cdcr-target] has occurred - updating... (live nodes size: [1]) [junit4] 2> 1959992 INFO (zkCallback-2422-thread-2-processing-n:127.0.0.1:56073_solr) [n:127.0.0.1:56073_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> 1960001 INFO (coreCloseExecutor-6419-thread-1) [n:127.0.0.1:56073_solr c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.cdcr-target.shard1.leader, tag=1258602057 [junit4] 2> 1960001 INFO (coreCloseExecutor-6419-thread-1) [n:127.0.0.1:56073_solr c:cdcr-target s:shard1 r:core_node2 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> 1960002 INFO (jetty-closer-2416-thread-1) [ ] o.a.s.c.Overseer Overseer (id=98504122817052675-127.0.0.1:56073_solr-n_0000000000) closing [junit4] 2> 1960002 INFO (OverseerStateUpdate-98504122817052675-127.0.0.1:56073_solr-n_0000000000) [n:127.0.0.1:56073_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:56073_solr [junit4] 2> 1960004 WARN (zkCallback-2422-thread-2-processing-n:127.0.0.1:56073_solr) [n:127.0.0.1:56073_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 1960005 INFO (jetty-closer-2416-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7e89cd90{/solr,null,UNAVAILABLE} [junit4] 2> 1960006 ERROR (TEST-CdcrBootstrapTest.testConvertClusterToCdcrAndBootstrap-seed#[13579F6828A085BD]) [ ] 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> 1960007 INFO (TEST-CdcrBootstrapTest.testConvertClusterToCdcrAndBootstrap-seed#[13579F6828A085BD]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:56069 56069 [junit4] 2> 1960012 INFO (Thread-3240) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:56069 56069 [junit4] 2> 1960020 WARN (Thread-3240) [ ] 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> 1960020 INFO (TEST-CdcrBootstrapTest.testConvertClusterToCdcrAndBootstrap-seed#[13579F6828A085BD]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testConvertClusterToCdcrAndBootstrap [junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTest_13579F6828A085BD-001 [junit4] 2> Aug 18, 2017 10:54:06 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): {_version_=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), xyz=PostingsFormat(name=Memory), id=PostingsFormat(name=LuceneFixedGap)}, docValues:{_version_=DocValuesFormat(name=Memory), xyz=DocValuesFormat(name=Lucene70), id=DocValuesFormat(name=Direct)}, maxPointsInLeafNode=89, maxMBSortInHeap=7.375214089397142, sim=RandomSimilarity(queryNorm=false): {}, locale=fr-FR, timezone=America/Ojinaga [junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=3,threads=1,free=256278064,total=532152320 [junit4] 2> NOTE: All tests run in this JVM: [TestBulkSchemaAPI, DistributedExpandComponentTest, StressHdfsTest, TestPivotHelperCode, TestSimpleTextCodec, TriLevelCompositeIdRoutingTest, TestLegacyTerms, TestDistribIDF, ResponseLogComponentTest, DocExpirationUpdateProcessorFactoryTest, JvmMetricsTest, TestLFUCache, LeaderFailoverAfterPartitionTest, FieldAnalysisRequestHandlerTest, TestFieldTypeCollectionResource, SliceStateTest, TestJmxIntegration, DistanceFunctionTest, SolrInfoBeanTest, ConfigureRecoveryStrategyTest, TestCloudSchemaless, RecoveryZkTest, TestGroupingSearch, TestPullReplica, SparseHLLTest, TestUtils, CheckHdfsIndexTest, MetricsHandlerTest, TestHdfsUpdateLog, CloudMLTQParserTest, IndexSchemaTest, BlockCacheTest, NumericFieldsTest, TestEmbeddedSolrServerSchemaAPI, SecurityConfHandlerTest, ZkCLITest, TestCollectionsAPIViaSolrCloudCluster, ScriptEngineTest, TestTestInjection, TestNamedUpdateProcessors, TestSha256AuthenticationProvider, SpellCheckCollatorWithCollapseTest, DistribDocExpirationUpdateProcessorTest, V2ApiIntegrationTest, TestMissingGroups, TestCustomStream, TestScoreJoinQPScore, TestBinaryField, HttpPartitionTest, UnloadDistributedZkTest, SyncSliceTest, TestIndexingPerformance, TestExpandComponent, ReplicationFactorTest, TestSmileRequest, ResourceLoaderTest, ClassificationUpdateProcessorFactoryTest, ExplicitHLLTest, TestJavabinTupleStreamParser, TestCodecSupport, StatsReloadRaceTest, TestLockTree, TestUnifiedSolrHighlighter, TestTolerantUpdateProcessorRandomCloud, URLClassifyProcessorTest, TestCustomDocTransformer, TestOnReconnectListenerSupport, OverseerTest, SpatialFilterTest, TestLegacyNumericRangeQueryBuilder, PathHierarchyTokenizerFactoryTest, SortByFunctionTest, AnalyticsQueryTest, WordBreakSolrSpellCheckerTest, TestNoOpRegenerator, TestSweetSpotSimilarityFactory, ParsingFieldUpdateProcessorsTest, TestUseDocValuesAsStored2, TestGeoJSONResponseWriter, TestSchemaSimilarityResource, TestCustomSort, TestRandomFlRTGCloud, TestInPlaceUpdatesStandalone, TestSubQueryTransformerDistrib, SolrCoreMetricManagerTest, HighlighterTest, ConcurrentDeleteAndCreateCollectionTest, ClassificationUpdateProcessorTest, ConnectionManagerTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, UninvertDocValuesMergePolicyTest, TestZkAclsWithHadoopAuth, TestManagedStopFilterFactory, OutputWriterTest, TestSolrCloudWithDelegationTokens, TestCoreContainer, TestFreeTextSuggestions, BinaryUpdateRequestHandlerTest, AtomicUpdateProcessorFactoryTest, AddBlockUpdateTest, NodeMutatorTest, TestPseudoReturnFields, TestLegacyFieldReuse, HdfsThreadLeakTest, TestCollectionAPIs, TestJsonFacets, MinimalSchemaTest, HdfsRestartWhileUpdatingTest, TestConfigSetImmutable, TestAddFieldRealTimeGet, TestConfigSets, CdcrReplicationDistributedZkTest, TestExactStatsCache, SolrSlf4jReporterTest, HdfsRecoverLeaseTest, BasicDistributedZkTest, SoftAutoCommitTest, TestFoldingMultitermQuery, DistributedQueueTest, TestSolrCoreParser, ImplicitSnitchTest, TimeZoneUtilsTest, AnalysisAfterCoreReloadTest, ClusterStateTest, ZkControllerTest, JavabinLoaderTest, TestPhraseSuggestions, ReplicaListTransformerTest, TestRequestForwarding, TestManagedResourceStorage, TestPKIAuthenticationPlugin, TestDistribDocBasedVersion, HdfsUnloadDistributedZkTest, TestSQLHandlerNonCloud, TestCSVResponseWriter, SolrShardReporterTest, HdfsBasicDistributedZk2Test, DisMaxRequestHandlerTest, TestInitParams, ReturnFieldsTest, TestSolrQueryResponse, TestQueryWrapperFilter, CloudExitableDirectoryReaderTest, SimpleFacetsTest, RegexBoostProcessorTest, QueryElevationComponentTest, TestHalfAndHalfDocValues, ZkStateReaderTest, TestNestedDocsSort, CdcrUpdateLogTest, TestCoreAdminApis, TestRTimerTree, TestBlendedInfixSuggestions, ShardRoutingTest, SuggesterTest, TestOmitPositions, TestExclusionRuleCollectionAccess, SuggestComponentTest, TestMacros, BlockJoinFacetRandomTest, TestFilteredDocIdSet, TestConfigReload, CursorPagingTest, SmileWriterTest, TestStressRecovery, TestSearcherReuse, AssignTest, RollingRestartTest, DefaultValueUpdateProcessorTest, UUIDUpdateProcessorFallbackTest, SimpleCollectionCreateDeleteTest, CSVRequestHandlerTest, SolrGangliaReporterTest, DistributedFacetPivotSmallAdvancedTest, TestStressVersions, TestSerializedLuceneMatchVersion, TestFunctionQuery, OpenCloseCoreStressTest, FullSolrCloudDistribCmdsTest, ClusterStateUpdateTest, TestRandomFaceting, LeaderElectionTest, ZkSolrClientTest, TestZkChroot, TestRandomDVFaceting, TestDistributedSearch, TestStressReorder, TestJoin, HardAutoCommitTest, StatsComponentTest, PeerSyncTest, DirectUpdateHandlerTest, TestBadConfig, TestIndexSearcher, SimplePostToolTest, TestExtendedDismaxParser, CoreAdminHandlerTest, TestStressLucene, SuggesterWFSTTest, NoCacheHeaderTest, TestUpdate, TestAtomicUpdateErrorCases, TestWordDelimiterFilterFactory, DirectUpdateHandlerOptimizeTest, XsltUpdateRequestHandlerTest, CacheHeaderTest, LukeRequestHandlerTest, PrimitiveFieldTypeTest, XmlUpdateRequestHandlerTest, TestValueSourceCache, TestSolrQueryParser, LoggingHandlerTest, MBeansHandlerTest, TestAnalyzedSuggestions, TestPHPSerializedResponseWriter, PingRequestHandlerTest, TestSolrCoreProperties, DirectSolrConnectionTest, TestXIncludeConfig, TestDFRSimilarityFactory, TestIBSimilarityFactory, OpenExchangeRatesOrgProviderTest, PluginInfoTest, ChaosMonkeyNothingIsSafeTest, BaseCdcrDistributedZkTest, CdcrBootstrapTest] [junit4] Completed [502/729 (1!)] on J0 in 160.34s, 3 tests, 1 failure <<< FAILURES! [...truncated 44175 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org