Return-Path: X-Original-To: archive-asf-public-internal@cust-asf2.ponee.io Delivered-To: archive-asf-public-internal@cust-asf2.ponee.io Received: from cust-asf.ponee.io (cust-asf.ponee.io [163.172.22.183]) by cust-asf2.ponee.io (Postfix) with ESMTP id A71212004F5 for ; Fri, 1 Sep 2017 18:39:30 +0200 (CEST) Received: by cust-asf.ponee.io (Postfix) id A557B16D4F6; Fri, 1 Sep 2017 16:39:30 +0000 (UTC) Delivered-To: archive-asf-public@cust-asf.ponee.io Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by cust-asf.ponee.io (Postfix) with SMTP id 2E46A16D4F4 for ; Fri, 1 Sep 2017 18:39:28 +0200 (CEST) Received: (qmail 27147 invoked by uid 500); 1 Sep 2017 16:39:26 -0000 Mailing-List: contact dev-help@lucene.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@lucene.apache.org Delivered-To: mailing list dev@lucene.apache.org Received: (qmail 27137 invoked by uid 99); 1 Sep 2017 16:39:25 -0000 Received: from pnap-us-west-generic-nat.apache.org (HELO spamd3-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Fri, 01 Sep 2017 16:39:25 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd3-us-west.apache.org (ASF Mail Server at spamd3-us-west.apache.org) with ESMTP id 3BCE51838C9 for ; Fri, 1 Sep 2017 16:39:25 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd3-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: -0.3 X-Spam-Level: X-Spam-Status: No, score=-0.3 tagged_above=-999 required=6.31 tests=[KAM_BADIPHTTP=2, RCVD_IN_DNSWL_MED=-2.3, SPF_PASS=-0.001, WEIRD_PORT=0.001] autolearn=disabled Received: from mx1-lw-us.apache.org ([10.40.0.8]) by localhost (spamd3-us-west.apache.org [10.40.0.10]) (amavisd-new, port 10024) with ESMTP id RKdUbHhCDRZU for ; Fri, 1 Sep 2017 16:39:10 +0000 (UTC) Received: from serv1.sd-datasolutions.de (serv1.sd-datasolutions.de [88.99.242.108]) by mx1-lw-us.apache.org (ASF Mail Server at mx1-lw-us.apache.org) with ESMTP id A92175FC69 for ; Fri, 1 Sep 2017 16:39:09 +0000 (UTC) Received: from serv1 (ip6-localhost [IPv6:::1]) by serv1.sd-datasolutions.de (Postfix) with ESMTP id 9016C1081E4C for ; Fri, 1 Sep 2017 16:39:09 +0000 (UTC) Date: Fri, 1 Sep 2017 16:37:11 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org Message-ID: <496090979.111.1504283949591.JavaMail.jenkins@serv1.sd-datasolutions.de> Subject: [JENKINS] Lucene-Solr-master-Windows (64bit/jdk1.8.0_144) - Build # 6862 - Failure! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_110_1170041281.1504283949548" X-Jenkins-Job: Lucene-Solr-master-Windows X-Jenkins-Result: FAILURE archived-at: Fri, 01 Sep 2017 16:39:30 -0000 ------=_Part_110_1170041281.1504283949548 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6862/ Java: 64bit/jdk1.8.0_144 -XX:+UseCompressedOops -XX:+UseParallelGC 2 tests failed. FAILED: org.apache.solr.cloud.CdcrBootstrapTest.testBootstrapWithContinous= IndexingOnSourceCluster Error Message: Document mismatch on target after sync expected:<2000> but was:<1100> Stack Trace: java.lang.AssertionError: Document mismatch on target after sync expected:<= 2000> but was:<1100> =09at __randomizedtesting.SeedInfo.seed([2C130725E8813CEE:F8564C7C0FD78F15]= :0) =09at org.junit.Assert.fail(Assert.java:93) =09at org.junit.Assert.failNotEquals(Assert.java:647) =09at org.junit.Assert.assertEquals(Assert.java:128) =09at org.junit.Assert.assertEquals(Assert.java:472) =09at org.apache.solr.cloud.CdcrBootstrapTest.testBootstrapWithContinousInd= exingOnSourceCluster(CdcrBootstrapTest.java:309) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:62) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:498) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1737) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:934) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Random= izedRunner.java:970) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rando= mizedRunner.java:984) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:49) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:817) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:468) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:943) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:829) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:879) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:890) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:41) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:53) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:54) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.handler.TestSQLHandler.doTest Error Message: Stack Trace: java.lang.AssertionError =09at __randomizedtesting.SeedInfo.seed([2C130725E8813CEE:8B57BF81853A2F57]= :0) =09at org.apache.solr.handler.TestSQLHandler.testBasicSelect(TestSQLHandler= .java:197) =09at org.apache.solr.handler.TestSQLHandler.doTest(TestSQLHandler.java:83) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:62) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:498) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1737) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:934) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Random= izedRunner.java:970) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rando= mizedRunner.java:984) =09at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= FixedStatement.callStatement(BaseDistributedSearchTestCase.java:993) =09at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= Statement.evaluate(BaseDistributedSearchTestCase.java:968) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:49) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:817) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:468) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:943) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:829) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:879) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:890) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:41) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:53) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:54) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at java.lang.Thread.run(Thread.java:748) Build Log: [...truncated 11365 lines...] [junit4] Suite: org.apache.solr.cloud.CdcrBootstrapTest [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-m= aster-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTes= t_2C130725E8813CEE-001\init-core-data-001 [junit4] 2> 360110 WARN (SUITE-CdcrBootstrapTest-seed#[2C130725E8813C= EE]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=3D= 25 numCloses=3D25 [junit4] 2> 360110 INFO (SUITE-CdcrBootstrapTest-seed#[2C130725E8813C= EE]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_S= YSPROP=3Dtrue) w/NUMERIC_DOCVALUES_SYSPROP=3Dtrue [junit4] 2> 360113 INFO (SUITE-CdcrBootstrapTest-seed#[2C130725E8813C= EE]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAu= th (true) via: @org.apache.solr.util.RandomizeSSL(reason=3D, value=3DNaN, s= sl=3DNaN, clientAuth=3DNaN) [junit4] 2> 360113 INFO (SUITE-CdcrBootstrapTest-seed#[2C130725E8813C= EE]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.so= lr.allowed.securerandom=3Dnull & java.security.egd=3Dfile:/dev/./urandom [junit4] 2> 360114 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.SolrTestCaseJ4 ###Sta= rting testConvertClusterToCdcrAndBootstrap [junit4] 2> 360116 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.c.MiniSolrCloudCluste= r Starting cluster of 1 servers in C:\Users\jenkins\workspace\Lucene-Solr-m= aster-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTes= t_2C130725E8813CEE-001\cdcr-target-001 [junit4] 2> 360116 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.c.ZkTestServer STARTI= NG ZK TEST SERVER [junit4] 2> 360116 INFO (Thread-966) [ ] o.a.s.c.ZkTestServer clie= nt port:0.0.0.0/0.0.0.0:0 [junit4] 2> 360116 INFO (Thread-966) [ ] o.a.s.c.ZkTestServer Star= ting server [junit4] 2> 360118 ERROR (Thread-966) [ ] o.a.z.s.ZooKeeperServer Z= KShutdownHandler is not registered, so ZooKeeper server won't take any acti= on on ERROR or SHUTDOWN server state changes [junit4] 2> 360216 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.c.ZkTestServer start = zk server on port:51067 [junit4] 2> 360237 INFO (jetty-launcher-423-thread-1) [ ] o.e.j.s.= Server jetty-9.3.20.v20170531 [junit4] 2> 360239 INFO (jetty-launcher-423-thread-1) [ ] o.e.j.s.= h.ContextHandler Started o.e.j.s.ServletContextHandler@1a9734d4{/solr,null,= AVAILABLE} [junit4] 2> 360242 INFO (jetty-launcher-423-thread-1) [ ] o.e.j.s.= AbstractConnector Started ServerConnector@5f26270{HTTP/1.1,[http/1.1]}{127.= 0.0.1:51071} [junit4] 2> 360242 INFO (jetty-launcher-423-thread-1) [ ] o.e.j.s.= Server Started @368308ms [junit4] 2> 360242 INFO (jetty-launcher-423-thread-1) [ ] o.a.s.c.= s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D5107= 1} [junit4] 2> 360242 ERROR (jetty-launcher-423-thread-1) [ ] o.a.s.u.= StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missin= g or incomplete. [junit4] 2> 360243 INFO (jetty-launcher-423-thread-1) [ ] o.a.s.s.= SolrDispatchFilter ___ _ Welcome to Apache Solr=E2=84=A2 versio= n 8.0.0 [junit4] 2> 360243 INFO (jetty-launcher-423-thread-1) [ ] o.a.s.s.= SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 360243 INFO (jetty-launcher-423-thread-1) [ ] o.a.s.s.= SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir:= null [junit4] 2> 360243 INFO (jetty-launcher-423-thread-1) [ ] o.a.s.s.= SolrDispatchFilter |___/\___/_|_| Start time: 2017-09-01T15:32:50.299Z [junit4] 2> 360252 INFO (jetty-launcher-423-thread-1) [ ] o.a.s.s.= SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 360275 INFO (jetty-launcher-423-thread-1) [ ] o.a.s.c.= ZkContainer Zookeeper client=3D127.0.0.1:51067/solr [junit4] 2> 360387 INFO (jetty-launcher-423-thread-1) [n:127.0.0.1:51= 071_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 360388 INFO (jetty-launcher-423-thread-1) [n:127.0.0.1:51= 071_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 1= 27.0.0.1:51071_solr [junit4] 2> 360389 INFO (jetty-launcher-423-thread-1) [n:127.0.0.1:51= 071_solr ] o.a.s.c.Overseer Overseer (id=3D98584492125323267-127.0.0.1:5= 1071_solr-n_0000000000) starting [junit4] 2> 360395 INFO (jetty-launcher-423-thread-1) [n:127.0.0.1:51= 071_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live= _nodes/127.0.0.1:51071_solr [junit4] 2> 360397 INFO (OverseerStateUpdate-98584492125323267-127.0.= 0.1:51071_solr-n_0000000000) [n:127.0.0.1:51071_solr ] o.a.s.c.c.ZkState= Reader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 360498 INFO (jetty-launcher-423-thread-1) [n:127.0.0.1:51= 071_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51071.solr= .node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@fdf37d3 [junit4] 2> 360504 INFO (jetty-launcher-423-thread-1) [n:127.0.0.1:51= 071_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51071.solr= .jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxM= BeanServer@fdf37d3 [junit4] 2> 360504 INFO (jetty-launcher-423-thread-1) [n:127.0.0.1:51= 071_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51071.solr= .jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.= JmxMBeanServer@fdf37d3 [junit4] 2> 360505 INFO (jetty-launcher-423-thread-1) [n:127.0.0.1:51= 071_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions undern= eath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-= core\test\J0\temp\solr.cloud.CdcrBootstrapTest_2C130725E8813CEE-001\cdcr-ta= rget-001\node1\. [junit4] 1> Target zkHost =3D 127.0.0.1:51067/solr [junit4] 2> 360529 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 0x15e3e12c17e0005, likely client has closed socket [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOSe= rverCnxn.java:239) [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxnFactory.run= (NIOServerCnxnFactory.java:203) [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> 360530 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.c.MiniSolrCloudCluste= r Starting cluster of 1 servers in C:\Users\jenkins\workspace\Lucene-Solr-m= aster-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTes= t_2C130725E8813CEE-001\cdcr-source-001 [junit4] 2> 360530 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.c.ZkTestServer STARTI= NG ZK TEST SERVER [junit4] 2> 360530 INFO (Thread-976) [ ] o.a.s.c.ZkTestServer clie= nt port:0.0.0.0/0.0.0.0:0 [junit4] 2> 360530 INFO (Thread-976) [ ] o.a.s.c.ZkTestServer Star= ting server [junit4] 2> 360533 ERROR (Thread-976) [ ] o.a.z.s.ZooKeeperServer Z= KShutdownHandler is not registered, so ZooKeeper server won't take any acti= on on ERROR or SHUTDOWN server state changes [junit4] 2> 360630 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.c.ZkTestServer start = zk server on port:51093 [junit4] 2> 360649 INFO (jetty-launcher-435-thread-1) [ ] o.e.j.s.= Server jetty-9.3.20.v20170531 [junit4] 2> 360650 INFO (jetty-launcher-435-thread-1) [ ] o.e.j.s.= h.ContextHandler Started o.e.j.s.ServletContextHandler@4cbf4d35{/solr,null,= AVAILABLE} [junit4] 2> 360651 INFO (jetty-launcher-435-thread-1) [ ] o.e.j.s.= AbstractConnector Started ServerConnector@16c1396b{HTTP/1.1,[http/1.1]}{127= .0.0.1:51097} [junit4] 2> 360652 INFO (jetty-launcher-435-thread-1) [ ] o.e.j.s.= Server Started @368718ms [junit4] 2> 360652 INFO (jetty-launcher-435-thread-1) [ ] o.a.s.c.= s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D5109= 7} [junit4] 2> 360653 ERROR (jetty-launcher-435-thread-1) [ ] o.a.s.u.= StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missin= g or incomplete. [junit4] 2> 360653 INFO (jetty-launcher-435-thread-1) [ ] o.a.s.s.= SolrDispatchFilter ___ _ Welcome to Apache Solr=E2=84=A2 versio= n 8.0.0 [junit4] 2> 360653 INFO (jetty-launcher-435-thread-1) [ ] o.a.s.s.= SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 360653 INFO (jetty-launcher-435-thread-1) [ ] o.a.s.s.= SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir:= null [junit4] 2> 360653 INFO (jetty-launcher-435-thread-1) [ ] o.a.s.s.= SolrDispatchFilter |___/\___/_|_| Start time: 2017-09-01T15:32:50.709Z [junit4] 2> 360661 INFO (jetty-launcher-435-thread-1) [ ] o.a.s.s.= SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 360683 INFO (jetty-launcher-435-thread-1) [ ] o.a.s.c.= ZkContainer Zookeeper client=3D127.0.0.1:51093/solr [junit4] 2> 360688 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 0x15e3e12c31d0002, likely client has closed socket [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOSe= rverCnxn.java:239) [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxnFactory.run= (NIOServerCnxnFactory.java:203) [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> 360803 INFO (jetty-launcher-435-thread-1) [n:127.0.0.1:51= 097_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 360804 INFO (jetty-launcher-435-thread-1) [n:127.0.0.1:51= 097_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 1= 27.0.0.1:51097_solr [junit4] 2> 360805 INFO (jetty-launcher-435-thread-1) [n:127.0.0.1:51= 097_solr ] o.a.s.c.Overseer Overseer (id=3D98584492152520707-127.0.0.1:5= 1097_solr-n_0000000000) starting [junit4] 2> 360810 INFO (jetty-launcher-435-thread-1) [n:127.0.0.1:51= 097_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live= _nodes/127.0.0.1:51097_solr [junit4] 2> 360810 INFO (OverseerStateUpdate-98584492152520707-127.0.= 0.1:51097_solr-n_0000000000) [n:127.0.0.1:51097_solr ] o.a.s.c.c.ZkState= Reader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 360900 INFO (jetty-launcher-435-thread-1) [n:127.0.0.1:51= 097_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51097.solr= .node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@fdf37d3 [junit4] 2> 360904 INFO (jetty-launcher-435-thread-1) [n:127.0.0.1:51= 097_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51097.solr= .jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxM= BeanServer@fdf37d3 [junit4] 2> 360904 INFO (jetty-launcher-435-thread-1) [n:127.0.0.1:51= 097_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51097.solr= .jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.= JmxMBeanServer@fdf37d3 [junit4] 2> 360905 INFO (jetty-launcher-435-thread-1) [n:127.0.0.1:51= 097_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions undern= eath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-= core\test\J0\temp\solr.cloud.CdcrBootstrapTest_2C130725E8813CEE-001\cdcr-so= urce-001\node1\. [junit4] 2> 360946 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.c.c.ZkStateReader Upd= ated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 360947 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.c.s.i.ZkClientCluster= StateProvider Cluster at 127.0.0.1:51093/solr ready [junit4] 2> 360949 INFO (qtp220987415-3283) [n:127.0.0.1:51097_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with par= ams replicationFactor=3D1&collection.configName=3Dcdcr-source&name=3Dcdcr-s= ource&nrtReplicas=3D1&action=3DCREATE&numShards=3D1&property.solr.directory= Factory=3Dsolr.StandardDirectoryFactory&wt=3Djavabin&version=3D2 and sendTo= OCPQueue=3Dtrue [junit4] 2> 360951 INFO (OverseerThreadFactory-1443-thread-1-processi= ng-n:127.0.0.1:51097_solr) [n:127.0.0.1:51097_solr ] o.a.s.c.CreateColle= ctionCmd Create collection cdcr-source [junit4] 2> 361064 INFO (OverseerStateUpdate-98584492152520707-127.0.= 0.1:51097_solr-n_0000000000) [n:127.0.0.1:51097_solr ] o.a.s.c.o.SliceMu= tator 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:51097/solr", [junit4] 2> "type":"NRT"}=20 [junit4] 2> 361269 INFO (qtp220987415-3284) [n:127.0.0.1:51097_solr = ] o.a.s.h.a.CoreAdminOperation core create command qt=3D/admin/cores&coll= ection.configName=3Dcdcr-source&newCollection=3Dtrue&collection=3Dcdcr-sour= ce&version=3D2&replicaType=3DNRT&coreNodeName=3Dcore_node2&name=3Dcdcr-sour= ce_shard1_replica_n1&action=3DCREATE&numShards=3D1&shard=3Dshard1&property.= solr.directoryFactory=3Dsolr.StandardDirectoryFactory&wt=3Djavabin [junit4] 2> 361271 INFO (qtp220987415-3284) [n:127.0.0.1:51097_solr = ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 21= 47483647 transient cores [junit4] 2> 361386 INFO (zkCallback-442-thread-1-processing-n:127.0.0= .1:51097_solr) [n:127.0.0.1:51097_solr ] o.a.s.c.c.ZkStateReader A clust= er state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pat= h:/collections/cdcr-source/state.json] for collection [cdcr-source] has occ= urred - updating... (live nodes size: [1]) [junit4] 2> 361387 INFO (zkCallback-442-thread-2-processing-n:127.0.0= .1:51097_solr) [n:127.0.0.1:51097_solr ] o.a.s.c.c.ZkStateReader A clust= er state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pat= h:/collections/cdcr-source/state.json] for collection [cdcr-source] has occ= urred - updating... (live nodes size: [1]) [junit4] 2> 362330 INFO (qtp220987415-3284) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.c= .SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 362336 INFO (qtp220987415-3284) [n:127.0.0.1:51097_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=3Dminimal [junit4] 2> 362340 INFO (qtp220987415-3284) [n:127.0.0.1:51097_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> 362340 INFO (qtp220987415-3284) [n:127.0.0.1:51097_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 conf= iguration from collection cdcr-source, trusted=3Dtrue [junit4] 2> 362341 INFO (qtp220987415-3284) [n:127.0.0.1:51097_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_51097.solr.core.cdcr-source.sha= rd1.replica_n1' (registry 'solr.core.cdcr-source.shard1.replica_n1') enable= d at server: com.sun.jmx.mbeanserver.JmxMBeanServer@fdf37d3 [junit4] 2> 362358 INFO (qtp220987415-3284) [n:127.0.0.1:51097_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> 362358 INFO (qtp220987415-3284) [n:127.0.0.1:51097_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:\Us= ers\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\= J0\temp\solr.cloud.CdcrBootstrapTest_2C130725E8813CEE-001\cdcr-source-001\n= ode1\cdcr-source_shard1_replica_n1], dataDir=3D[C:\Users\jenkins\workspace\= Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.Cdc= rBootstrapTest_2C130725E8813CEE-001\cdcr-source-001\node1\.\cdcr-source_sha= rd1_replica_n1\data\] [junit4] 2> 362429 INFO (qtp220987415-3284) [n:127.0.0.1:51097_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.Updat= eLog [junit4] 2> 362429 INFO (qtp220987415-3284) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .UpdateLog Initializing UpdateLog: dataDir=3D defaultSyncLevel=3DFLUSH numR= ecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 362430 INFO (qtp220987415-3284) [n:127.0.0.1:51097_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> 362430 INFO (qtp220987415-3284) [n:127.0.0.1:51097_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> 362432 INFO (qtp220987415-3284) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.s= .SolrIndexSearcher Opening [Searcher@6569dfb5[cdcr-source_shard1_replica_n1= ] main] [junit4] 2> 362434 INFO (qtp220987415-3284) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.r= .ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /conf= igs/cdcr-source [junit4] 2> 362434 INFO (qtp220987415-3284) [n:127.0.0.1:51097_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 ZooKee= perStorageIO:path=3D/configs/cdcr-source [junit4] 2> 362434 INFO (qtp220987415-3284) [n:127.0.0.1:51097_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> 362434 INFO (qtp220987415-3284) [n:127.0.0.1:51097_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 1577351876433674240 [junit4] 2> 362435 INFO (searcherExecutor-1446-thread-1-processing-n:= 127.0.0.1:51097_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source= r:core_node2) [n:127.0.0.1:51097_solr c:cdcr-source s:shard1 r:core_node2 = x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_repli= ca_n1] Registered new searcher Searcher@6569dfb5[cdcr-source_shard1_replica= _n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 362439 INFO (qtp220987415-3284) [n:127.0.0.1:51097_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> 362439 INFO (qtp220987415-3284) [n:127.0.0.1:51097_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> 362439 INFO (qtp220987415-3284) [n:127.0.0.1:51097_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:51097/solr/cdcr-source_shar= d1_replica_n1/ [junit4] 2> 362439 INFO (qtp220987415-3284) [n:127.0.0.1:51097_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> 362439 INFO (qtp220987415-3284) [n:127.0.0.1:51097_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:51097/solr/cdcr-source_shard1_replica_n1/ ha= s no replicas [junit4] 2> 362439 INFO (qtp220987415-3284) [n:127.0.0.1:51097_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, c= lear LIR [junit4] 2> 362443 INFO (qtp220987415-3284) [n:127.0.0.1:51097_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:51097/sol= r/cdcr-source_shard1_replica_n1/ shard1 [junit4] 2> 362548 INFO (zkCallback-442-thread-1-processing-n:127.0.0= .1:51097_solr) [n:127.0.0.1:51097_solr ] o.a.s.c.c.ZkStateReader A clust= er state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pat= h:/collections/cdcr-source/state.json] for collection [cdcr-source] has occ= urred - updating... (live nodes size: [1]) [junit4] 2> 362548 INFO (zkCallback-442-thread-2-processing-n:127.0.0= .1:51097_solr) [n:127.0.0.1:51097_solr ] o.a.s.c.c.ZkStateReader A clust= er state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pat= h:/collections/cdcr-source/state.json] for collection [cdcr-source] has occ= urred - updating... (live nodes size: [1]) [junit4] 2> 362595 INFO (qtp220987415-3284) [n:127.0.0.1:51097_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> 362598 INFO (qtp220987415-3284) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.s= .HttpSolrCall [admin] webapp=3Dnull path=3D/admin/cores params=3D{qt=3D/adm= in/cores&collection.configName=3Dcdcr-source&newCollection=3Dtrue&collectio= n=3Dcdcr-source&version=3D2&replicaType=3DNRT&coreNodeName=3Dcore_node2&nam= e=3Dcdcr-source_shard1_replica_n1&action=3DCREATE&numShards=3D1&shard=3Dsha= rd1&property.solr.directoryFactory=3Dsolr.StandardDirectoryFactory&wt=3Djav= abin} status=3D0 QTime=3D1328 [junit4] 2> 362604 INFO (qtp220987415-3283) [n:127.0.0.1:51097_solr = ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for a= t most 30 seconds. Check all shard replicas [junit4] 2> 362704 INFO (zkCallback-442-thread-1-processing-n:127.0.0= .1:51097_solr) [n:127.0.0.1:51097_solr ] o.a.s.c.c.ZkStateReader A clust= er state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pat= h:/collections/cdcr-source/state.json] for collection [cdcr-source] has occ= urred - updating... (live nodes size: [1]) [junit4] 2> 362704 INFO (zkCallback-442-thread-2-processing-n:127.0.0= .1:51097_solr) [n:127.0.0.1:51097_solr ] o.a.s.c.c.ZkStateReader A clust= er state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pat= h:/collections/cdcr-source/state.json] for collection [cdcr-source] has occ= urred - updating... (live nodes size: [1]) [junit4] 2> 362955 INFO (OverseerCollectionConfigSetProcessor-9858449= 2152520707-127.0.0.1:51097_solr-n_0000000000) [n:127.0.0.1:51097_solr ] = o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work= /qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKee= per [junit4] 2> 363605 INFO (qtp220987415-3283) [n:127.0.0.1:51097_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections pa= rams=3D{replicationFactor=3D1&collection.configName=3Dcdcr-source&name=3Dcd= cr-source&nrtReplicas=3D1&action=3DCREATE&numShards=3D1&property.solr.direc= toryFactory=3Dsolr.StandardDirectoryFactory&wt=3Djavabin&version=3D2} statu= s=3D0 QTime=3D2655 [junit4] 1> Adding 10 docs with commit=3Dtrue, numDocs=3D100 [junit4] 2> 363624 INFO (qtp220987415-3285) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .p.LogUpdateProcessorFactory [cdcr-source_shard1_replica_n1] webapp=3D/sol= r path=3D/update params=3D{_stateVer_=3Dcdcr-source:4&wt=3Djavabin&version= =3D2}{add=3D[source_0 (1577351877665751040), source_1 (1577351877675188224)= , source_2 (1577351877675188225), source_3 (1577351877675188226), source_4 = (1577351877675188227), source_5 (1577351877675188228), source_6 (1577351877= 675188229), source_7 (1577351877675188230), source_8 (1577351877675188231),= source_9 (1577351877675188232), ... (100 adds)]} 0 13 [junit4] 2> 363625 INFO (qtp220987415-3287) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .DirectUpdateHandler2 start commit{_version_=3D1577351877682528256,optimize= =3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,sof= tCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 363625 INFO (qtp220987415-3287) [n:127.0.0.1:51097_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.SolrI= ndexWriter@ab13217 commitCommandVersion:1577351877682528256 [junit4] 2> 363670 INFO (qtp220987415-3287) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.s= .SolrIndexSearcher Opening [Searcher@3da4bd67[cdcr-source_shard1_replica_n1= ] main] [junit4] 2> 363671 INFO (qtp220987415-3287) [n:127.0.0.1:51097_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> 363672 INFO (searcherExecutor-1446-thread-1-processing-n:= 127.0.0.1:51097_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source= r:core_node2) [n:127.0.0.1:51097_solr c:cdcr-source s:shard1 r:core_node2 = x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_repli= ca_n1] Registered new searcher Searcher@3da4bd67[cdcr-source_shard1_replica= _n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0= (8.0.0):C100)))} [junit4] 2> 363672 INFO (qtp220987415-3287) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .p.LogUpdateProcessorFactory [cdcr-source_shard1_replica_n1] webapp=3D/sol= r path=3D/update params=3D{_stateVer_=3Dcdcr-source:4&waitSearcher=3Dtrue&c= ommit=3Dtrue&softCommit=3Dfalse&wt=3Djavabin&version=3D2}{commit=3D} 0 47 [junit4] 1> Adding 10 docs with commit=3Dtrue, numDocs=3D200 [junit4] 2> 363678 INFO (qtp220987415-3288) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .p.LogUpdateProcessorFactory [cdcr-source_shard1_replica_n1] webapp=3D/sol= r path=3D/update params=3D{_stateVer_=3Dcdcr-source:4&wt=3Djavabin&version= =3D2}{add=3D[source_100 (1577351877732859904), source_101 (1577351877736005= 632), source_102 (1577351877736005633), source_103 (1577351877736005634), s= ource_104 (1577351877737054208), source_105 (1577351877737054209), source_1= 06 (1577351877737054210), source_107 (1577351877737054211), source_108 (157= 7351877737054212), source_109 (1577351877737054213), ... (100 adds)]} 0 4 [junit4] 2> 363679 INFO (qtp220987415-3281) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .DirectUpdateHandler2 start commit{_version_=3D1577351877739151360,optimize= =3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,sof= tCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 363679 INFO (qtp220987415-3281) [n:127.0.0.1:51097_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.SolrI= ndexWriter@ab13217 commitCommandVersion:1577351877739151360 [junit4] 2> 363706 INFO (qtp220987415-3281) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.s= .SolrIndexSearcher Opening [Searcher@586f4ef0[cdcr-source_shard1_replica_n1= ] main] [junit4] 2> 363708 INFO (qtp220987415-3281) [n:127.0.0.1:51097_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> 363709 INFO (searcherExecutor-1446-thread-1-processing-n:= 127.0.0.1:51097_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source= r:core_node2) [n:127.0.0.1:51097_solr c:cdcr-source s:shard1 r:core_node2 = x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_repli= ca_n1] Registered new searcher Searcher@586f4ef0[cdcr-source_shard1_replica= _n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0= (8.0.0):C100) Uninverting(_1(8.0.0):C100)))} [junit4] 2> 363709 INFO (qtp220987415-3281) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .p.LogUpdateProcessorFactory [cdcr-source_shard1_replica_n1] webapp=3D/sol= r path=3D/update params=3D{_stateVer_=3Dcdcr-source:4&waitSearcher=3Dtrue&c= ommit=3Dtrue&softCommit=3Dfalse&wt=3Djavabin&version=3D2}{commit=3D} 0 30 [junit4] 1> Adding 10 docs with commit=3Dtrue, numDocs=3D300 [junit4] 2> 363713 INFO (qtp220987415-3286) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .p.LogUpdateProcessorFactory [cdcr-source_shard1_replica_n1] webapp=3D/sol= r path=3D/update params=3D{_stateVer_=3Dcdcr-source:4&wt=3Djavabin&version= =3D2}{add=3D[source_200 (1577351877771657216), source_201 (1577351877773754= 368), source_202 (1577351877773754369), source_203 (1577351877773754370), s= ource_204 (1577351877773754371), source_205 (1577351877773754372), source_2= 06 (1577351877773754373), source_207 (1577351877773754374), source_208 (157= 7351877773754375), source_209 (1577351877773754376), ... (100 adds)]} 0 3 [junit4] 2> 363714 INFO (qtp220987415-3284) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .DirectUpdateHandler2 start commit{_version_=3D1577351877775851520,optimize= =3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,sof= tCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 363714 INFO (qtp220987415-3284) [n:127.0.0.1:51097_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.SolrI= ndexWriter@ab13217 commitCommandVersion:1577351877775851520 [junit4] 2> 363736 INFO (qtp220987415-3284) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.s= .SolrIndexSearcher Opening [Searcher@1ecbab45[cdcr-source_shard1_replica_n1= ] main] [junit4] 2> 363737 INFO (qtp220987415-3284) [n:127.0.0.1:51097_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> 363738 INFO (searcherExecutor-1446-thread-1-processing-n:= 127.0.0.1:51097_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source= r:core_node2) [n:127.0.0.1:51097_solr c:cdcr-source s:shard1 r:core_node2 = x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_repli= ca_n1] Registered new searcher Searcher@1ecbab45[cdcr-source_shard1_replica= _n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0= (8.0.0):C100) Uninverting(_1(8.0.0):C100) Uninverting(_2(8.0.0):C100)))} [junit4] 2> 363738 INFO (qtp220987415-3284) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .p.LogUpdateProcessorFactory [cdcr-source_shard1_replica_n1] webapp=3D/sol= r path=3D/update params=3D{_stateVer_=3Dcdcr-source:4&waitSearcher=3Dtrue&c= ommit=3Dtrue&softCommit=3Dfalse&wt=3Djavabin&version=3D2}{commit=3D} 0 24 [junit4] 1> Adding 10 docs with commit=3Dtrue, numDocs=3D400 [junit4] 2> 363743 INFO (qtp220987415-3283) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .p.LogUpdateProcessorFactory [cdcr-source_shard1_replica_n1] webapp=3D/sol= r path=3D/update params=3D{_stateVer_=3Dcdcr-source:4&wt=3Djavabin&version= =3D2}{add=3D[source_300 (1577351877802065920), source_301 (1577351877804163= 072), source_302 (1577351877804163073), source_303 (1577351877804163074), s= ource_304 (1577351877804163075), source_305 (1577351877804163076), source_3= 06 (1577351877804163077), source_307 (1577351877804163078), source_308 (157= 7351877804163079), source_309 (1577351877804163080), ... (100 adds)]} 0 3 [junit4] 2> 363743 INFO (qtp220987415-3285) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .DirectUpdateHandler2 start commit{_version_=3D1577351877806260224,optimize= =3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,sof= tCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 363743 INFO (qtp220987415-3285) [n:127.0.0.1:51097_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.SolrI= ndexWriter@ab13217 commitCommandVersion:1577351877806260224 [junit4] 2> 363769 INFO (qtp220987415-3285) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.s= .SolrIndexSearcher Opening [Searcher@270e4e6d[cdcr-source_shard1_replica_n1= ] main] [junit4] 2> 363771 INFO (qtp220987415-3285) [n:127.0.0.1:51097_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> 363772 INFO (searcherExecutor-1446-thread-1-processing-n:= 127.0.0.1:51097_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source= r:core_node2) [n:127.0.0.1:51097_solr c:cdcr-source s:shard1 r:core_node2 = x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_repli= ca_n1] Registered new searcher Searcher@270e4e6d[cdcr-source_shard1_replica= _n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0= (8.0.0):C100) Uninverting(_1(8.0.0):C100) Uninverting(_2(8.0.0):C100) Uninv= erting(_3(8.0.0):C100)))} [junit4] 2> 363772 INFO (qtp220987415-3285) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .p.LogUpdateProcessorFactory [cdcr-source_shard1_replica_n1] webapp=3D/sol= r path=3D/update params=3D{_stateVer_=3Dcdcr-source:4&waitSearcher=3Dtrue&c= ommit=3Dtrue&softCommit=3Dfalse&wt=3Djavabin&version=3D2}{commit=3D} 0 28 [junit4] 1> Adding 10 docs with commit=3Dtrue, numDocs=3D500 [junit4] 2> 363776 INFO (qtp220987415-3287) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .p.LogUpdateProcessorFactory [cdcr-source_shard1_replica_n1] webapp=3D/sol= r path=3D/update params=3D{_stateVer_=3Dcdcr-source:4&wt=3Djavabin&version= =3D2}{add=3D[source_400 (1577351877837717504), source_401 (1577351877839814= 656), source_402 (1577351877839814657), source_403 (1577351877839814658), s= ource_404 (1577351877839814659), source_405 (1577351877839814660), source_4= 06 (1577351877839814661), source_407 (1577351877839814662), source_408 (157= 7351877839814663), source_409 (1577351877839814664), ... (100 adds)]} 0 3 [junit4] 2> 363777 INFO (qtp220987415-3288) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .DirectUpdateHandler2 start commit{_version_=3D1577351877841911808,optimize= =3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,sof= tCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 363777 INFO (qtp220987415-3288) [n:127.0.0.1:51097_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.SolrI= ndexWriter@ab13217 commitCommandVersion:1577351877841911808 [junit4] 2> 363804 INFO (qtp220987415-3288) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.s= .SolrIndexSearcher Opening [Searcher@211e51f8[cdcr-source_shard1_replica_n1= ] main] [junit4] 2> 363806 INFO (qtp220987415-3288) [n:127.0.0.1:51097_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> 363807 INFO (searcherExecutor-1446-thread-1-processing-n:= 127.0.0.1:51097_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source= r:core_node2) [n:127.0.0.1:51097_solr c:cdcr-source s:shard1 r:core_node2 = x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_repli= ca_n1] Registered new searcher Searcher@211e51f8[cdcr-source_shard1_replica= _n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0= (8.0.0):C100) Uninverting(_1(8.0.0):C100) Uninverting(_2(8.0.0):C100) Uninv= erting(_3(8.0.0):C100) Uninverting(_4(8.0.0):C100)))} [junit4] 2> 363807 INFO (qtp220987415-3288) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .p.LogUpdateProcessorFactory [cdcr-source_shard1_replica_n1] webapp=3D/sol= r path=3D/update params=3D{_stateVer_=3Dcdcr-source:4&waitSearcher=3Dtrue&c= ommit=3Dtrue&softCommit=3Dfalse&wt=3Djavabin&version=3D2}{commit=3D} 0 29 [junit4] 1> Adding 10 docs with commit=3Dtrue, numDocs=3D600 [junit4] 2> 363811 INFO (qtp220987415-3281) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .p.LogUpdateProcessorFactory [cdcr-source_shard1_replica_n1] webapp=3D/sol= r path=3D/update params=3D{_stateVer_=3Dcdcr-source:4&wt=3Djavabin&version= =3D2}{add=3D[source_500 (1577351877874417664), source_501 (1577351877876514= 816), source_502 (1577351877876514817), source_503 (1577351877876514818), s= ource_504 (1577351877876514819), source_505 (1577351877876514820), source_5= 06 (1577351877876514821), source_507 (1577351877876514822), source_508 (157= 7351877876514823), source_509 (1577351877876514824), ... (100 adds)]} 0 3 [junit4] 2> 363812 INFO (qtp220987415-3286) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .DirectUpdateHandler2 start commit{_version_=3D1577351877878611968,optimize= =3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,sof= tCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 363812 INFO (qtp220987415-3286) [n:127.0.0.1:51097_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.SolrI= ndexWriter@ab13217 commitCommandVersion:1577351877878611968 [junit4] 2> 363841 INFO (qtp220987415-3286) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.s= .SolrIndexSearcher Opening [Searcher@7ba062fb[cdcr-source_shard1_replica_n1= ] main] [junit4] 2> 363843 INFO (qtp220987415-3286) [n:127.0.0.1:51097_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> 363844 INFO (searcherExecutor-1446-thread-1-processing-n:= 127.0.0.1:51097_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source= r:core_node2) [n:127.0.0.1:51097_solr c:cdcr-source s:shard1 r:core_node2 = x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_repli= ca_n1] Registered new searcher Searcher@7ba062fb[cdcr-source_shard1_replica= _n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0= (8.0.0):C100) Uninverting(_1(8.0.0):C100) Uninverting(_2(8.0.0):C100) Uninv= erting(_3(8.0.0):C100) Uninverting(_4(8.0.0):C100) Uninverting(_5(8.0.0):C1= 00)))} [junit4] 2> 363844 INFO (qtp220987415-3286) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .p.LogUpdateProcessorFactory [cdcr-source_shard1_replica_n1] webapp=3D/sol= r path=3D/update params=3D{_stateVer_=3Dcdcr-source:4&waitSearcher=3Dtrue&c= ommit=3Dtrue&softCommit=3Dfalse&wt=3Djavabin&version=3D2}{commit=3D} 0 31 [junit4] 1> Adding 10 docs with commit=3Dtrue, numDocs=3D700 [junit4] 2> 363848 INFO (qtp220987415-3284) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .p.LogUpdateProcessorFactory [cdcr-source_shard1_replica_n1] webapp=3D/sol= r path=3D/update params=3D{_stateVer_=3Dcdcr-source:4&wt=3Djavabin&version= =3D2}{add=3D[source_600 (1577351877913214976), source_601 (1577351877915312= 128), source_602 (1577351877915312129), source_603 (1577351877915312130), s= ource_604 (1577351877915312131), source_605 (1577351877915312132), source_6= 06 (1577351877915312133), source_607 (1577351877915312134), source_608 (157= 7351877915312135), source_609 (1577351877915312136), ... (100 adds)]} 0 3 [junit4] 2> 363848 INFO (qtp220987415-3283) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .DirectUpdateHandler2 start commit{_version_=3D1577351877916360728,optimize= =3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,sof= tCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 363848 INFO (qtp220987415-3283) [n:127.0.0.1:51097_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.SolrI= ndexWriter@ab13217 commitCommandVersion:1577351877916360728 [junit4] 2> 363881 INFO (qtp220987415-3283) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.s= .SolrIndexSearcher Opening [Searcher@49619eba[cdcr-source_shard1_replica_n1= ] main] [junit4] 2> 363882 INFO (qtp220987415-3283) [n:127.0.0.1:51097_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> 363882 INFO (searcherExecutor-1446-thread-1-processing-n:= 127.0.0.1:51097_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source= r:core_node2) [n:127.0.0.1:51097_solr c:cdcr-source s:shard1 r:core_node2 = x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_repli= ca_n1] Registered new searcher Searcher@49619eba[cdcr-source_shard1_replica= _n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0= (8.0.0):C100) Uninverting(_1(8.0.0):C100) Uninverting(_2(8.0.0):C100) Uninv= erting(_3(8.0.0):C100) Uninverting(_4(8.0.0):C100) Uninverting(_5(8.0.0):C1= 00) Uninverting(_6(8.0.0):C100)))} [junit4] 2> 363883 INFO (qtp220987415-3283) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .p.LogUpdateProcessorFactory [cdcr-source_shard1_replica_n1] webapp=3D/sol= r path=3D/update params=3D{_stateVer_=3Dcdcr-source:4&waitSearcher=3Dtrue&c= ommit=3Dtrue&softCommit=3Dfalse&wt=3Djavabin&version=3D2}{commit=3D} 0 34 [junit4] 1> Adding 10 docs with commit=3Dtrue, numDocs=3D800 [junit4] 2> 363888 INFO (qtp220987415-3285) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .p.LogUpdateProcessorFactory [cdcr-source_shard1_replica_n1] webapp=3D/sol= r path=3D/update params=3D{_stateVer_=3Dcdcr-source:4&wt=3Djavabin&version= =3D2}{add=3D[source_700 (1577351877953060864), source_701 (1577351877956206= 592), source_702 (1577351877956206593), source_703 (1577351877956206594), s= ource_704 (1577351877956206595), source_705 (1577351877956206596), source_7= 06 (1577351877956206597), source_707 (1577351877956206598), source_708 (157= 7351877956206599), source_709 (1577351877956206600), ... (100 adds)]} 0 3 [junit4] 2> 363889 INFO (qtp220987415-3287) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .DirectUpdateHandler2 start commit{_version_=3D1577351877959352320,optimize= =3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,sof= tCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 363889 INFO (qtp220987415-3287) [n:127.0.0.1:51097_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.SolrI= ndexWriter@ab13217 commitCommandVersion:1577351877959352320 [junit4] 2> 363926 INFO (qtp220987415-3287) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.s= .SolrIndexSearcher Opening [Searcher@6df5c7fc[cdcr-source_shard1_replica_n1= ] main] [junit4] 2> 363929 INFO (qtp220987415-3287) [n:127.0.0.1:51097_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> 363929 INFO (searcherExecutor-1446-thread-1-processing-n:= 127.0.0.1:51097_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source= r:core_node2) [n:127.0.0.1:51097_solr c:cdcr-source s:shard1 r:core_node2 = x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_repli= ca_n1] Registered new searcher Searcher@6df5c7fc[cdcr-source_shard1_replica= _n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0= (8.0.0):C100) Uninverting(_1(8.0.0):C100) Uninverting(_2(8.0.0):C100) Uninv= erting(_3(8.0.0):C100) Uninverting(_4(8.0.0):C100) Uninverting(_5(8.0.0):C1= 00) Uninverting(_6(8.0.0):C100) Uninverting(_7(8.0.0):C100)))} [junit4] 2> 363930 INFO (qtp220987415-3287) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .p.LogUpdateProcessorFactory [cdcr-source_shard1_replica_n1] webapp=3D/sol= r path=3D/update params=3D{_stateVer_=3Dcdcr-source:4&waitSearcher=3Dtrue&c= ommit=3Dtrue&softCommit=3Dfalse&wt=3Djavabin&version=3D2}{commit=3D} 0 41 [junit4] 1> Adding 10 docs with commit=3Dtrue, numDocs=3D900 [junit4] 2> 363935 INFO (qtp220987415-3288) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .p.LogUpdateProcessorFactory [cdcr-source_shard1_replica_n1] webapp=3D/sol= r path=3D/update params=3D{_stateVer_=3Dcdcr-source:4&wt=3Djavabin&version= =3D2}{add=3D[source_800 (1577351878004441088), source_801 (1577351878006538= 240), source_802 (1577351878006538241), source_803 (1577351878006538242), s= ource_804 (1577351878006538243), source_805 (1577351878006538244), source_8= 06 (1577351878006538245), source_807 (1577351878006538246), source_808 (157= 7351878006538247), source_809 (1577351878006538248), ... (100 adds)]} 0 3 [junit4] 2> 363935 INFO (qtp220987415-3281) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .DirectUpdateHandler2 start commit{_version_=3D1577351878007586816,optimize= =3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,sof= tCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 363935 INFO (qtp220987415-3281) [n:127.0.0.1:51097_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.SolrI= ndexWriter@ab13217 commitCommandVersion:1577351878007586816 [junit4] 2> 363975 INFO (qtp220987415-3281) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.s= .SolrIndexSearcher Opening [Searcher@2d6a8e01[cdcr-source_shard1_replica_n1= ] main] [junit4] 2> 363977 INFO (qtp220987415-3281) [n:127.0.0.1:51097_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> 363978 INFO (searcherExecutor-1446-thread-1-processing-n:= 127.0.0.1:51097_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source= r:core_node2) [n:127.0.0.1:51097_solr c:cdcr-source s:shard1 r:core_node2 = x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_repli= ca_n1] Registered new searcher Searcher@2d6a8e01[cdcr-source_shard1_replica= _n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0= (8.0.0):C100) Uninverting(_1(8.0.0):C100) Uninverting(_2(8.0.0):C100) Uninv= erting(_3(8.0.0):C100) Uninverting(_4(8.0.0):C100) Uninverting(_5(8.0.0):C1= 00) Uninverting(_6(8.0.0):C100) Uninverting(_7(8.0.0):C100) Uninverting(_8(= 8.0.0):C100)))} [junit4] 2> 363978 INFO (qtp220987415-3281) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .p.LogUpdateProcessorFactory [cdcr-source_shard1_replica_n1] webapp=3D/sol= r path=3D/update params=3D{_stateVer_=3Dcdcr-source:4&waitSearcher=3Dtrue&c= ommit=3Dtrue&softCommit=3Dfalse&wt=3Djavabin&version=3D2}{commit=3D} 0 41 [junit4] 1> Adding 10 docs with commit=3Dtrue, numDocs=3D1000 [junit4] 2> 363981 INFO (qtp220987415-3286) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .p.LogUpdateProcessorFactory [cdcr-source_shard1_replica_n1] webapp=3D/sol= r path=3D/update params=3D{_stateVer_=3Dcdcr-source:4&wt=3Djavabin&version= =3D2}{add=3D[source_900 (1577351878053724160), source_901 (1577351878054772= 736), source_902 (1577351878054772737), source_903 (1577351878054772738), s= ource_904 (1577351878054772739), source_905 (1577351878054772740), source_9= 06 (1577351878054772741), source_907 (1577351878054772742), source_908 (157= 7351878054772743), source_909 (1577351878054772744), ... (100 adds)]} 0 3 [junit4] 2> 363982 INFO (qtp220987415-3284) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .DirectUpdateHandler2 start commit{_version_=3D1577351878056869888,optimize= =3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,sof= tCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 363982 INFO (qtp220987415-3284) [n:127.0.0.1:51097_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.SolrI= ndexWriter@ab13217 commitCommandVersion:1577351878056869888 [junit4] 2> 364025 INFO (qtp220987415-3284) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.s= .SolrIndexSearcher Opening [Searcher@2a5a8a2[cdcr-source_shard1_replica_n1]= main] [junit4] 2> 364026 INFO (qtp220987415-3284) [n:127.0.0.1:51097_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> 364027 INFO (searcherExecutor-1446-thread-1-processing-n:= 127.0.0.1:51097_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source= r:core_node2) [n:127.0.0.1:51097_solr c:cdcr-source s:shard1 r:core_node2 = x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_repli= ca_n1] Registered new searcher Searcher@2a5a8a2[cdcr-source_shard1_replica_= n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(= 8.0.0):C100) Uninverting(_1(8.0.0):C100) Uninverting(_2(8.0.0):C100) Uninve= rting(_3(8.0.0):C100) Uninverting(_4(8.0.0):C100) Uninverting(_5(8.0.0):C10= 0) Uninverting(_6(8.0.0):C100) Uninverting(_7(8.0.0):C100) Uninverting(_8(8= .0.0):C100) Uninverting(_9(8.0.0):C100)))} [junit4] 2> 364027 INFO (qtp220987415-3284) [n:127.0.0.1:51097_solr c= :cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_replica_n1] o.a.s.u= .p.LogUpdateProcessorFactory [cdcr-source_shard1_replica_n1] webapp=3D/sol= r path=3D/update params=3D{_stateVer_=3Dcdcr-source:4&waitSearcher=3Dtrue&c= ommit=3Dtrue&softCommit=3Dfalse&wt=3Djavabin&version=3D2}{commit=3D} 0 44 [junit4] 2> 364028 INFO (qtp220987415-3283) [n:127.0.0.1:51097_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=3D/solr path=3D/select p= arams=3D{q=3D*:*&_stateVer_=3Dcdcr-source:4&wt=3Djavabin&version=3D2} hits= =3D1000 status=3D0 QTime=3D0 [junit4] 2> 364028 INFO (qtp220987415-3283) [n:127.0.0.1:51097_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=3D/solr path=3D/get para= ms=3D{qt=3D/get&_stateVer_=3Dcdcr-source:4&getVersions=3D1000&wt=3Djavabin&= version=3D2} status=3D0 QTime=3D0 [junit4] 2> 364044 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 0x15e3e12c31d0008, likely client has closed socket [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOSe= rverCnxn.java:239) [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxnFactory.run= (NIOServerCnxnFactory.java:203) [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> 364046 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.e.j.s.AbstractConnector S= topped ServerConnector@16c1396b{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 364046 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.c.CoreContainer Shutt= ing down CoreContainer instance=3D1045653098 [junit4] 2> 364046 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.m.SolrMetricManager C= losing metric reporters for registry=3Dsolr.node, tag=3Dnull [junit4] 2> 364046 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.m.r.SolrJmxReporter C= losing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5cb8baea= : rootName =3D solr_51097, domain =3D solr.node, service url =3D null, agen= t id =3D null] for registry solr.node / com.codahale.metrics.MetricRegistry= @4676f221 [junit4] 2> 364051 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.m.SolrMetricManager C= losing metric reporters for registry=3Dsolr.jvm, tag=3Dnull [junit4] 2> 364051 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.m.r.SolrJmxReporter C= losing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@75986370= : rootName =3D solr_51097, domain =3D solr.jvm, service url =3D null, agent= id =3D null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1= 490e23a [junit4] 2> 364054 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.m.SolrMetricManager C= losing metric reporters for registry=3Dsolr.jetty, tag=3Dnull [junit4] 2> 364054 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.m.r.SolrJmxReporter C= losing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7a0deec5= : rootName =3D solr_51097, domain =3D solr.jetty, service url =3D null, age= nt id =3D null] for registry solr.jetty / com.codahale.metrics.MetricRegist= ry@67c58bc8 [junit4] 2> 364058 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.m.SolrMetricManager C= losing metric reporters for registry=3Dsolr.cluster, tag=3Dnull [junit4] 2> 364062 INFO (coreCloseExecutor-1451-thread-1) [n:127.0.0.= 1:51097_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_repli= ca_n1] o.a.s.c.SolrCore [cdcr-source_shard1_replica_n1] CLOSING SolrCore o= rg.apache.solr.core.SolrCore@631d9969 [junit4] 2> 364062 INFO (coreCloseExecutor-1451-thread-1) [n:127.0.0.= 1:51097_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_repli= ca_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=3Dso= lr.core.cdcr-source.shard1.replica_n1, tag=3D1662884201 [junit4] 2> 364062 INFO (coreCloseExecutor-1451-thread-1) [n:127.0.0.= 1:51097_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_repli= ca_n1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.= reporters.SolrJmxReporter@4d1edd35: rootName =3D solr_51097, domain =3D sol= r.core.cdcr-source.shard1.replica_n1, service url =3D null, agent id =3D nu= ll] for registry solr.core.cdcr-source.shard1.replica_n1 / com.codahale.met= rics.MetricRegistry@138143f [junit4] 2> 364067 INFO (coreCloseExecutor-1451-thread-1) [n:127.0.0.= 1:51097_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_shard1_repli= ca_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=3Dso= lr.collection.cdcr-source.shard1.leader, tag=3D1662884201 [junit4] 2> 364086 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.c.Overseer Overseer (= id=3D98584492152520707-127.0.0.1:51097_solr-n_0000000000) closing [junit4] 2> 364086 INFO (OverseerStateUpdate-98584492152520707-127.0.= 0.1:51097_solr-n_0000000000) [n:127.0.0.1:51097_solr ] o.a.s.c.Overseer = Overseer Loop exiting : 127.0.0.1:51097_solr [junit4] 2> 364089 WARN (zkCallback-442-thread-1-processing-n:127.0.0= .1:51097_solr) [n:127.0.0.1:51097_solr ] o.a.s.c.c.ZkStateReader ZooKeep= er watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode =3D Sessio= n expired for /live_nodes] [junit4] 2> 364090 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.e.j.s.h.ContextHandler St= opped o.e.j.s.ServletContextHandler@4cbf4d35{/solr,null,UNAVAILABLE} [junit4] 2> 364090 INFO (zkCallback-449-thread-1) [ ] o.a.s.c.c.Zk= StateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 364091 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.e.j.s.Server jetty-9.3.20= .v20170531 [junit4] 2> 364092 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.e.j.s.h.ContextHandler St= arted o.e.j.s.ServletContextHandler@5f8e38a7{/solr,null,AVAILABLE} [junit4] 2> 364093 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.e.j.s.AbstractConnector S= tarted ServerConnector@54275134{HTTP/1.1,[http/1.1]}{127.0.0.1:51140} [junit4] 2> 364093 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.e.j.s.Server Started @372= 158ms [junit4] 2> 364093 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.c.s.e.JettySolrRunner= Jetty properties: {hostContext=3D/solr, hostPort=3D51140} [junit4] 2> 364093 ERROR (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.u.StartupLoggingUtils= Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 364093 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.s.SolrDispatchFilter = ___ _ Welcome to Apache Solr=E2=84=A2 version 8.0.0 [junit4] 2> 364093 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.s.SolrDispatchFilter = / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 364093 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.s.SolrDispatchFilter = \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 364093 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.s.SolrDispatchFilter = |___/\___/_|_| Start time: 2017-09-01T15:32:54.149Z [junit4] 2> 364097 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.s.SolrDispatchFilter = solr.xml found in ZooKeeper. Loading... [junit4] 2> 364109 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.c.ZkContainer Zookeep= er client=3D127.0.0.1:51093/solr [junit4] 2> 364113 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 0x15e3e12c31d000a, likely client has closed socket [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOSe= rverCnxn.java:239) [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxnFactory.run= (NIOServerCnxnFactory.java:203) [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> 364124 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [n:127.0.0.1:51140_solr ] o.a.s= .c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 364124 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [n:127.0.0.1:51140_solr ] o.a.s= .c.OverseerElectionContext I am going to be the leader 127.0.0.1:51140_solr [junit4] 2> 364125 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [n:127.0.0.1:51140_solr ] o.a.s= .c.Overseer Overseer (id=3D98584492152520715-127.0.0.1:51140_solr-n_0000000= 001) starting [junit4] 2> 364129 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [n:127.0.0.1:51140_solr ] o.a.s= .c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51= 140_solr [junit4] 2> 364131 INFO (OverseerStateUpdate-98584492152520715-127.0.= 0.1:51140_solr-n_0000000001) [n:127.0.0.1:51140_solr ] o.a.s.c.c.ZkState= Reader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 364132 INFO (zkCallback-449-thread-1) [ ] o.a.s.c.c.Zk= StateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 364227 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [n:127.0.0.1:51140_solr ] o.a.s= .m.r.SolrJmxReporter JMX monitoring for 'solr_51140.solr.node' (registry 's= olr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@fdf37d= 3 [junit4] 2> 364232 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [n:127.0.0.1:51140_solr ] o.a.s= .m.r.SolrJmxReporter JMX monitoring for 'solr_51140.solr.jvm' (registry 'so= lr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@fdf37d3 [junit4] 2> 364232 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [n:127.0.0.1:51140_solr ] o.a.s= .m.r.SolrJmxReporter JMX monitoring for 'solr_51140.solr.jetty' (registry '= solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@fdf3= 7d3 [junit4] 2> 364235 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [n:127.0.0.1:51140_solr ] o.a.s= .c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenki= ns\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\s= olr.cloud.CdcrBootstrapTest_2C130725E8813CEE-001\cdcr-source-001\node1\. [junit4] 2> 364235 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [n:127.0.0.1:51140_solr ] o.a.s= .c.CorePropertiesLocator Cores are: [cdcr-source_shard1_replica_n1] [junit4] 2> 364237 INFO (coreLoadExecutor-1460-thread-1-processing-n:= 127.0.0.1:51140_solr) [n:127.0.0.1:51140_solr c:cdcr-source s:shard1 r:core= _node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.TransientSolrCoreCacheDefau= lt Allocating transient cache for 2147483647 transient cores [junit4] 2> 364341 INFO (zkCallback-455-thread-1-processing-n:127.0.0= .1:51140_solr) [n:127.0.0.1:51140_solr ] o.a.s.c.c.ZkStateReader A clust= er state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pat= h:/collections/cdcr-source/state.json] for collection [cdcr-source] has occ= urred - updating... (live nodes size: [1]) [junit4] 2> 364342 INFO (zkCallback-455-thread-2-processing-n:127.0.0= .1:51140_solr) [n:127.0.0.1:51140_solr ] o.a.s.c.c.ZkStateReader A clust= er state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pat= h:/collections/cdcr-source/state.json] for collection [cdcr-source] has occ= urred - updating... (live nodes size: [1]) [junit4] 2> 365272 INFO (coreLoadExecutor-1460-thread-1-processing-n:= 127.0.0.1:51140_solr) [n:127.0.0.1:51140_solr c:cdcr-source s:shard1 r:core= _node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene Mat= chVersion: 8.0.0 [junit4] 2> 365277 INFO (coreLoadExecutor-1460-thread-1-processing-n:= 127.0.0.1:51140_solr) [n:127.0.0.1:51140_solr c:cdcr-source s:shard1 r:core= _node2 x:cdcr-source_shard1_replica_n1] o.a.s.s.ManagedIndexSchemaFactory T= he schema is configured as managed, but managed schema resource managed-sch= ema not found - loading non-managed schema schema.xml instead [junit4] 2> 365281 INFO (coreLoadExecutor-1460-thread-1-processing-n:= 127.0.0.1:51140_solr) [n:127.0.0.1:51140_solr c:cdcr-source s:shard1 r:core= _node2 x:cdcr-source_shard1_replica_n1] o.a.s.s.IndexSchema [cdcr-source_sh= ard1_replica_n1] Schema name=3Dminimal [junit4] 2> 365286 INFO (coreLoadExecutor-1460-thread-1-processing-n:= 127.0.0.1:51140_solr) [n:127.0.0.1:51140_solr c:cdcr-source s:shard1 r:core= _node2 x:cdcr-source_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema m= inimal/1.1 with uniqueid field id [junit4] 2> 365295 INFO (coreLoadExecutor-1460-thread-1-processing-n:= 127.0.0.1:51140_solr) [n:127.0.0.1:51140_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> 365298 INFO (coreLoadExecutor-1460-thread-1-processing-n:= 127.0.0.1:51140_solr) [n:127.0.0.1:51140_solr c:cdcr-source s:shard1 r:core= _node2 x:cdcr-source_shard1_replica_n1] o.a.s.s.ManagedIndexSchemaFactory A= fter upgrading to managed schema in ZooKeeper, renamed the non-managed sche= ma /configs/cdcr-source/schema.xml to /configs/cdcr-source/schema.xml.bak [junit4] 2> 365298 INFO (coreLoadExecutor-1460-thread-1-processing-n:= 127.0.0.1:51140_solr) [n:127.0.0.1:51140_solr c:cdcr-source s:shard1 r:core= _node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.CoreContainer Creating Solr= Core 'cdcr-source_shard1_replica_n1' using configuration from collection cd= cr-source, trusted=3Dtrue [junit4] 2> 365298 INFO (coreLoadExecutor-1460-thread-1-processing-n:= 127.0.0.1:51140_solr) [n:127.0.0.1:51140_solr c:cdcr-source s:shard1 r:core= _node2 x:cdcr-source_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monit= oring for 'solr_51140.solr.core.cdcr-source.shard1.replica_n1' (registry 's= olr.core.cdcr-source.shard1.replica_n1') enabled at server: com.sun.jmx.mbe= anserver.JmxMBeanServer@fdf37d3 [junit4] 2> 365298 INFO (coreLoadExecutor-1460-thread-1-processing-n:= 127.0.0.1:51140_solr) [n:127.0.0.1:51140_solr c:cdcr-source s:shard1 r:core= _node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrat= egy.Builder [junit4] 2> 365298 INFO (coreLoadExecutor-1460-thread-1-processing-n:= 127.0.0.1:51140_solr) [n:127.0.0.1:51140_solr c:cdcr-source s:shard1 r:core= _node2 x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [[cdcr-source_shar= d1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene= -Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBoots= trapTest_2C130725E8813CEE-001\cdcr-source-001\node1\cdcr-source_shard1_repl= ica_n1], dataDir=3D[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\s= olr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTest_2C130725E8813= CEE-001\cdcr-source-001\node1\.\cdcr-source_shard1_replica_n1\data\] [junit4] 2> 365419 INFO (coreLoadExecutor-1460-thread-1-processing-n:= 127.0.0.1:51140_solr) [n:127.0.0.1:51140_solr c:cdcr-source s:shard1 r:core= _node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateL= og implementation: org.apache.solr.update.CdcrUpdateLog [junit4] 2> 365419 INFO (coreLoadExecutor-1460-thread-1-processing-n:= 127.0.0.1:51140_solr) [n:127.0.0.1:51140_solr c:cdcr-source s:shard1 r:core= _node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.UpdateLog Initializing Upda= teLog: dataDir=3D defaultSyncLevel=3DFLUSH numRecordsToKeep=3D100 maxNumLog= sToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 365424 INFO (coreLoadExecutor-1460-thread-1-processing-n:= 127.0.0.1:51140_solr) [n:127.0.0.1:51140_solr c:cdcr-source s:shard1 r:core= _node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoComm= it: disabled [junit4] 2> 365424 INFO (coreLoadExecutor-1460-thread-1-processing-n:= 127.0.0.1:51140_solr) [n:127.0.0.1:51140_solr c:cdcr-source s:shard1 r:core= _node2 x:cdcr-source_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoComm= it: disabled [junit4] 2> 365468 INFO (coreLoadExecutor-1460-thread-1-processing-n:= 127.0.0.1:51140_solr) [n:127.0.0.1:51140_solr c:cdcr-source s:shard1 r:core= _node2 x:cdcr-source_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [= Searcher@4788e7b9[cdcr-source_shard1_replica_n1] main] [junit4] 2> 365469 INFO (coreLoadExecutor-1460-thread-1-processing-n:= 127.0.0.1:51140_solr) [n:127.0.0.1:51140_solr c:cdcr-source s:shard1 r:core= _node2 x:cdcr-source_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Conf= igured ZooKeeperStorageIO with znodeBase: /configs/cdcr-source [junit4] 2> 365470 INFO (coreLoadExecutor-1460-thread-1-processing-n:= 127.0.0.1:51140_solr) [n:127.0.0.1:51140_solr c:cdcr-source s:shard1 r:core= _node2 x:cdcr-source_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Load= ed null at path _rest_managed.json using ZooKeeperStorageIO:path=3D/configs= /cdcr-source [junit4] 2> 365470 INFO (coreLoadExecutor-1460-thread-1-processing-n:= 127.0.0.1:51140_solr) [n:127.0.0.1:51140_solr c:cdcr-source s:shard1 r:core= _node2 x:cdcr-source_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Creatin= g ZooKeeper watch for the managed schema at /configs/cdcr-source/managed-sc= hema [junit4] 2> 365470 INFO (coreLoadExecutor-1460-thread-1-processing-n:= 127.0.0.1:51140_solr) [n:127.0.0.1:51140_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> 365471 INFO (coreLoadExecutor-1460-thread-1-processing-n:= 127.0.0.1:51140_solr) [n:127.0.0.1:51140_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> 365479 INFO (coreLoadExecutor-1460-thread-1-processing-n:= 127.0.0.1:51140_solr) [n:127.0.0.1:51140_solr c:cdcr-source s:shard1 r:core= _node2 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrBufferStateManager Crea= ted znode /collections/cdcr-source/cdcr/state/buffer [junit4] 2> 365485 INFO (coreLoadExecutor-1460-thread-1-processing-n:= 127.0.0.1:51140_solr) [n:127.0.0.1:51140_solr c:cdcr-source s:shard1 r:core= _node2 x:cdcr-source_shard1_replica_n1] o.a.s.h.CdcrProcessStateManager Cre= ated znode /collections/cdcr-source/cdcr/state/process [junit4] 2> 365517 WARN (cdcr-update-log-synchronizer-1466-thread-1) = [n:127.0.0.1:51140_solr c:cdcr-source s:shard1 r:core_node2 x:cdcr-source_s= hard1_replica_n1] o.a.s.h.CdcrUpdateLogSynchronizer Caught unexpected excep= tion [junit4] 2> org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSol= rException: Error from server at http://127.0.0.1:51140/solr/cdcr-source_sh= ard1_replica_n1: SolrCore is loading [junit4] 2> =09at org.apache.solr.client.solrj.impl.HttpSolrClient.exe= cuteMethod(HttpSolrClient.java:627) [junit4] 2> =09at org.apache.solr.client.solrj.impl.HttpSolrClient.req= uest(HttpSolrClient.java:253) [junit4] 2> =09at org.apache.solr.client.solrj.impl.HttpSolrClient.req= uest(HttpSolrClient.java:242) [junit4] 2> =09at org.apache.solr.client.solrj.SolrClient.request(Solr= Client.java:1219) [junit4] 2> =09at org.apache.solr.handler.CdcrUpdateLogSynchronizer$Up= dateLogSynchronisation.run(CdcrUpdateLogSynchronizer.java:147) [junit4] 2> =09at java.util.concurrent.Executors$RunnableAdapter.call(= Executors.java:511) [junit4] 2> =09at java.util.concurrent.FutureTask.runAndReset(FutureTa= sk.java:308) [junit4] 2> =09at java.util.concurrent.ScheduledThreadPoolExecutor$Sch= eduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180) [junit4] 2> =09at java.util.concurrent.ScheduledThreadPoolExecutor$Sch= eduledFutureTask.run(ScheduledThreadPoolExecutor.java:294) [junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor.runWorker(Th= readPoolExecutor.java:1149) [junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor$Worker.run(T= hreadPoolExecutor.java:624) [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> 365523 INFO (searcherExecutor-1461-thread-1-processing-n:= 127.0.0.1:51140_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source= r:core_node2) [n:127.0.0.1:51140_solr c:cdcr-source s:shard1 r:core_node2 = x:cdcr-source_shard1_replica_n1] o.a.s.c.SolrCore [cdcr-source_shard1_repli= ca_n1] Registered new searcher Searcher@4788e7b9[cdcr-source_shard1_replica= _n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_a= (8.0.0):C1000)))} [junit4] 2> 365528 INFO (coreZkRegister-1453-thread-1-processing-n:12= 7.0.0.1:51140_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r= :core_node2) [n:127.0.0.1:51140_solr c:cdcr-source s:shard1 r:core_node2 x:= cdcr-source_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough re= plicas found to continue. [junit4] 2> 365528 INFO (coreZkRegister-1453-thread-1-processing-n:12= 7.0.0.1:51140_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r= :core_node2) [n:127.0.0.1:51140_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> 365528 INFO (coreZkRegister-1453-thread-1-processing-n:12= 7.0.0.1:51140_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r= :core_node2) [n:127.0.0.1:51140_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:51140/solr/cdcr-source_shard1_replica_n1/ [junit4] 2> 365528 INFO (coreZkRegister-1453-thread-1-processing-n:12= 7.0.0.1:51140_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r= :core_node2) [n:127.0.0.1:51140_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> 365528 INFO (coreZkRegister-1453-thread-1-processing-n:12= 7.0.0.1:51140_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r= :core_node2) [n:127.0.0.1:51140_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:51140/= solr/cdcr-source_shard1_replica_n1/ has no replicas [junit4] 2> 365528 INFO (coreZkRegister-1453-thread-1-processing-n:12= 7.0.0.1:51140_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r= :core_node2) [n:127.0.0.1:51140_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> 365530 INFO (zkCallback-455-thread-2-processing-n:127.0.0= .1:51140_solr) [n:127.0.0.1:51140_solr ] o.a.s.h.CdcrLeaderStateManager = Received new leader state @ cdcr-source:shard1 [junit4] 2> 365531 INFO (coreZkRegister-1453-thread-1-processing-n:12= 7.0.0.1:51140_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r= :core_node2) [n:127.0.0.1:51140_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:51140/solr/cdcr-source_shard1_replica_n1/ shar= d1 [junit4] 2> 365532 INFO (coreZkRegister-1453-thread-1-processing-n:12= 7.0.0.1:51140_solr x:cdcr-source_shard1_replica_n1 s:shard1 c:cdcr-source r= :core_node2) [n:127.0.0.1:51140_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 rec= overy necessary [junit4] 2> 365636 INFO (zkCallback-455-thread-2-processing-n:127.0.0= .1:51140_solr) [n:127.0.0.1:51140_solr ] o.a.s.c.c.ZkStateReader A clust= er state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pat= h:/collections/cdcr-source/state.json] for collection [cdcr-source] has occ= urred - updating... (live nodes size: [1]) [junit4] 2> 365637 INFO (zkCallback-455-thread-1-processing-n:127.0.0= .1:51140_solr) [n:127.0.0.1:51140_solr ] o.a.s.c.c.ZkStateReader A clust= er state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pat= h:/collections/cdcr-source/state.json] for collection [cdcr-source] has occ= urred - updating... (live nodes size: [1]) [junit4] 2> 365764 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.c.AbstractDistribZkTe= stBase Wait for recoveries to finish - collection: cdcr-source failOnTimeou= t:true timeout (sec):330 [junit4] 1> - [junit4] 1> replica:core_node2 rstate:active live:true [junit4] 1> no one is recoverying [junit4] 2> 365765 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.c.AbstractDistribZkTe= stBase Recoveries finished - collection: cdcr-source [junit4] 2> 365765 ERROR (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.c.s.i.CloudSolrClient= Request to collection cdcr-source failed due to (510) org.apache.solr.comm= on.SolrException: Could not find a healthy node to handle the request., ret= ry? 0 [junit4] 2> 365766 WARN (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.c.s.i.CloudSolrClient= Re-trying request to collection(s) cdcr-source after stale state error fr= om server. [junit4] 2> 365771 INFO (qtp975015069-3341) [n:127.0.0.1:51140_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=3D/solr path=3D/select p= arams=3D{q=3D*:*&_stateVer_=3Dcdcr-source:7&wt=3Djavabin&version=3D2} hits= =3D1000 status=3D0 QTime=3D0 [junit4] 2> 365792 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 0x15e3e12c17e0006, likely client has closed socket [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOSe= rverCnxn.java:239) [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxnFactory.run= (NIOServerCnxnFactory.java:203) [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> 365804 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.c.c.ZkStateReader Upd= ated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 365806 INFO (TEST-CdcrBootstrapTest.testConvertClusterToC= dcrAndBootstrap-seed#[2C130725E8813CEE]) [ ] o.a.s.c.s.i.ZkClientCluster= StateProvider Cluster at 127.0.0.1:51067/solr ready [junit4] 2> 365810 INFO (qtp1721263818-3242) [n:127.0.0.1:51071_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with pa= rams replicationFactor=3D1&collection.configName=3Dcdcr-target&name=3Dcdcr-= target&nrtReplicas=3D1&action=3DCREATE&numShards=3D1&wt=3Djavabin&version= =3D2 and sendToOCPQueue=3Dtrue [junit4] 2> 365814 INFO (OverseerThreadFactory-1435-thread-1-processi= ng-n:127.0.0.1:51071_solr) [n:127.0.0.1:51071_solr ] o.a.s.c.CreateColle= ctionCmd Create collection cdcr-target [junit4] 2> 365924 INFO (OverseerStateUpdate-98584492125323267-127.0.= 0.1:51071_solr-n_0000000000) [n:127.0.0.1:51071_solr ] o.a.s.c.o.SliceMu= tator 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:51071/solr", [junit4] 2> "type":"NRT"}=20 [junit4] 2> 366127 INFO (qtp1721263818-3244) [n:127.0.0.1:51071_solr = ] o.a.s.h.a.CoreAdminOperation core create command qt=3D/admin/cores&cor= eNodeName=3Dcore_node2&collection.configName=3Dcdcr-target&newCollection=3D= true&name=3Dcdcr-target_shard1_replica_n1&action=3DCREATE&numShards=3D1&col= lection=3Dcdcr-target&shard=3Dshard1&wt=3Djavabin&version=3D2&replicaType= =3DNRT [junit4] 2> 366129 INFO (qtp1721263818-3244) [n:127.0.0.1:51071_solr = ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2= 147483647 transient cores [junit4] 2> 366234 INFO (zkCallback-430-thread-1-processing-n:127.0.0= .1:51071_solr) [n:127.0.0.1:51071_solr ] o.a.s.c.c.ZkStateReader A clust= er state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pat= h:/collections/cdcr-target/state.json] for collection [cdcr-target] has occ= urred - updating... (live nodes size: [1]) [junit4] 2> 366236 INFO (zkCallback-430-thread-2-processing-n:127.0.0= .1:51071_solr) [n:127.0.0.1:51071_solr ] o.a.s.c.c.ZkStateReader A clust= er state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pat= h:/collections/cdcr-target/state.json] for collection [cdcr-target] has occ= urred - updating... (live nodes size: [1]) [junit4] 2> 367166 INFO (qtp1721263818-3244) [n:127.0.0.1:51071_solr = c:cdcr-target s:shard1 r:core_node2 x:cdcr-target_shard1_replica_n1] o.a.s.= c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 367172 INFO (qtp1721263818-3244) [n:127.0.0.1:51071_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 manage= d schema resource managed-schema not found - [...truncated too long message...] ode, JsonLoaderTest, HdfsChaosMonkeyNothingIsSafeTest, SmileWriterTest, Tes= tSegmentSorting, LeaderElectionContextKeyTest, BasicAuthIntegrationTest, Sc= hemaVersionSpecificBehaviorTest, TestPartialUpdateDeduplication, SpellCheck= ComponentTest, DebugComponentTest, TestNoOpRegenerator, CdcrRequestHandlerT= est, StatsReloadRaceTest, DeleteLastCustomShardedReplicaTest, BasicFunction= alityTest, OutOfBoxZkACLAndCredentialsProvidersTest, TestRemoteStreaming, T= estEmbeddedSolrServerSchemaAPI, TestValueSourceCache, SolrCoreCheckLockOnSt= artupTest, RankQueryTest, CacheHeaderTest, FullSolrCloudDistribCmdsTest, Te= stMergePolicyConfig, TestSolrJ, TestStressLucene, HdfsRestartWhileUpdatingT= est, TestReplicaProperties, HdfsBasicDistributedZk2Test, TestConfigSetsAPI,= CheckHdfsIndexTest, TestTestInjection, FileUtilsTest, TestSubQueryTransfor= merDistrib, TestInPlaceUpdatesStandalone, EchoParamsTest, TestBlendedInfixS= uggestions, TestDistributedGrouping, PluginInfoTest, TestLeaderElectionZkEx= piry, CdcrReplicationDistributedZkTest, SearchHandlerTest, AtomicUpdateProc= essorFactoryTest, SpellPossibilityIteratorTest, CurrencyFieldTypeTest, Test= Solr4Spatial, TestManagedResourceStorage, TestSolrCoreParser, TestCustomStr= eam, TestNumericTokenStream, SpellCheckCollatorTest, OverseerTaskQueueTest,= HdfsDirectoryFactoryTest, TestRestoreCore, ClassificationUpdateProcessorFa= ctoryTest, HdfsCollectionsAPIDistributedZkTest, CloudExitableDirectoryReade= rTest, TestSchemalessBufferedUpdates, CollectionsAPIAsyncDistributedZkTest,= RestartWhileUpdatingTest, HLLSerializationTest, ExternalFileFieldSortTest,= ConfigureRecoveryStrategyTest, TestTrieFacet, SolrCLIZkUtilsTest, TestFiel= dCacheVsDocValues, HdfsLockFactoryTest, TestDocSet, DocExpirationUpdateProc= essorFactoryTest, ConcurrentDeleteAndCreateCollectionTest, SolrIndexConfigT= est, VMParamsZkACLAndCredentialsProvidersTest, FacetPivotSmallTest, SharedF= SAutoReplicaFailoverUtilsTest, TestQueryWrapperFilter, TestClassicSimilarit= yFactory, TestDocumentBuilder, TestFastWriter, SolrShardReporterTest, Class= ificationUpdateProcessorTest, TestTrie, DistributedFacetPivotSmallAdvancedT= est, TestHashQParserPlugin, OpenExchangeRatesOrgProviderTest, MoveReplicaHD= FSUlogDirTest, TestFieldCollectionResource, BaseCdcrDistributedZkTest, Spel= lingQueryConverterTest, TestFiltering, TestUseDocValuesAsStored2, MBeansHan= dlerTest, TestElisionMultitermQuery, LeaderInitiatedRecoveryOnShardRestartT= est, BadIndexSchemaTest, TestXmlQParserPlugin, SOLR749Test, TestImplicitCor= eProperties, DeleteStatusTest, TestReloadAndDeleteDocs, TestCoreAdminApis, = TestCustomSort, TestSizeLimitedDistributedMap, BasicZkTest, TestCustomDocTr= ansformer, TestLegacyNumericRangeQueryBuilder, SolrSlf4jReporterTest, Parsi= ngFieldUpdateProcessorsTest, TestNestedDocsSort, UnloadDistributedZkTest, C= ollectionsAPIDistributedZkTest, SyncSliceTest, OpenCloseCoreStressTest, Ove= rseerTest, LeaderElectionIntegrationTest, ShardRoutingTest, LeaderElectionT= est, ZkSolrClientTest, ShardRoutingCustomTest, AutoCommitTest, TestRecovery= , TestHashPartitioner, DistributedSpellCheckComponentTest, TermVectorCompon= entDistributedTest, TestRealTimeGet, TestStressReorder, TestJoin, Distribut= edTermsComponentTest, SolrCoreTest, StatsComponentTest, TestGroupingSearch,= QueryElevationComponentTest, PeerSyncTest, ConvertedLegacyTest, TestBadCon= fig, TestIndexSearcher, SoftAutoCommitTest, ShowFileRequestHandlerTest, Dis= tributedQueryElevationComponentTest, SimplePostToolTest, TestCoreDiscovery,= SignatureUpdateProcessorFactoryTest, SuggesterFSTTest, SolrRequestParserTe= st, TestFoldingMultitermQuery, DocValuesTest, SuggesterTSTTest, SuggesterTe= st, PolyFieldTest, WordBreakSolrSpellCheckerTest, TestWordDelimiterFilterFa= ctory, QueryEqualityTest, DirectUpdateHandlerOptimizeTest, StatelessScriptU= pdateProcessorFactoryTest, SortByFunctionTest, DocValuesMultiTest, TestSolr= DeletionPolicy1, SolrInfoBeanTest, XsltUpdateRequestHandlerTest, IndexBased= SpellCheckerTest, LukeRequestHandlerTest, DisMaxRequestHandlerTest, TestQue= ryUtils, TestWriterPerf, DirectSolrSpellCheckerTest, TestReversedWildcardFi= lterFactory, DocumentAnalysisRequestHandlerTest, TestQueryTypes, PrimitiveF= ieldTypeTest, FileBasedSpellCheckerTest, TermsComponentTest, RequestHandler= sTest, DocumentBuilderTest, PathHierarchyTokenizerFactoryTest, TermVectorCo= mponentTest, TestIndexingPerformance, MoreLikeThisHandlerTest, RequiredFiel= dsTest, TestSolrQueryParser, FastVectorHighlighterTest, IndexSchemaRuntimeF= ieldTest, LoggingHandlerTest, RegexBoostProcessorTest, SolrPluginUtilsTest,= TestJmxIntegration, UpdateRequestProcessorFactoryTest, TestCSVResponseWrit= er, UniqFieldsUpdateProcessorFactoryTest, BinaryUpdateRequestHandlerTest, T= estPHPSerializedResponseWriter, PingRequestHandlerTest, CSVRequestHandlerTe= st, TestLFUCache, HighlighterConfigTest, TestQuerySenderListener, UpdatePar= amsTest, TestQuerySenderNoQuery, CopyFieldTest, TestStressRecovery, TestSol= rDeletionPolicy2, MultiTermTest, SampleTest, TestSearchPerf, TestConfig, Ou= tputWriterTest, TestPostingsSolrHighlighter, DirectSolrConnectionTest, NotR= equiredUniqueKeyTest, TestLuceneMatchVersion, TestPhraseSuggestions, TestCh= arFilters, TestCodecSupport, SynonymTokenizerTest, TestXIncludeConfig, Test= DFRSimilarityFactory, TestPerFieldSimilarity, TestLMJelinekMercerSimilarity= Factory, TimeZoneUtilsTest, ResourceLoaderTest, TestFastOutputStream, Scrip= tEngineTest, TestFastLRUCache, DateMathParserTest, PreAnalyzedFieldTest, Te= stSystemIdResolver, PrimUtilsTest, DateFieldTest, DOMUtilTest, RAMDirectory= FactoryTest, ClusterStateTest, TestLRUCache, TestUtils, ZkNodePropsTest, Sl= iceStateTest, SystemInfoHandlerTest, UUIDFieldTest, CircularListTest, Curso= rPagingTest, DistributedIntervalFacetingTest, TestCrossCoreJoin, TestCursor= MarkWithoutUniqueKey, TestDistributedMissingSort, TestTolerantSearch, TestE= mbeddedSolrServerAdminHandler, TestEmbeddedSolrServerConstructors, ChaosMon= keyNothingIsSafeWithPullReplicasTest, ChaosMonkeySafeLeaderWithPullReplicas= Test, CollectionsAPISolrJTest, ConnectionManagerTest, CreateCollectionClean= upTest, CustomCollectionTest, DeleteInactiveReplicaTest, DeleteReplicaTest,= DeleteShardTest, DistribDocExpirationUpdateProcessorTest, DistribJoinFromC= ollectionTest, DistributedQueueTest, DistributedVersionInfoTest, HealthChec= kHandlerTest, LeaderInitiatedRecoveryOnCommitTest, MissingSegmentRecoveryTe= st, MoveReplicaTest, MultiThreadedOCPTest, OverriddenZkACLAndCredentialsPro= vidersTest, OverseerCollectionConfigSetProcessorTest, OverseerRolesTest, Ov= erseerStatusTest, RollingRestartTest, SolrCloudExampleTest, SolrXmlInZkTest= , TestAuthenticationFramework, TestCloudDeleteByQuery, TestCloudInspectUtil= , TestCloudJSONFacetJoinDomain, TestCloudPseudoReturnFields, TestCloudSearc= herWarming, TestCollectionsAPIViaSolrCloudCluster, TestConfigSetsAPIExclusi= vity, TestDeleteCollectionOnDownNodes, TestDownShardTolerantSearch, TestLea= derElectionWithEmptyReplica, TestMiniSolrCloudClusterSSL, TestPullReplica, = TestSSLRandomization, TestShortCircuitedRequests, TestStressCloudBlindAtomi= cUpdates, TestStressInPlaceUpdates, HdfsChaosMonkeySafeLeaderTest, HdfsNNFa= iloverTest, HdfsRecoverLeaseTest, HdfsRecoveryZkTest, HdfsThreadLeakTest, H= dfsUnloadDistributedZkTest, HdfsWriteToMultipleCollectionsTest, StressHdfsT= est, TestClusterStateMutator, RuleEngineTest, RulesTest, TestSolrXml, TestS= olrCoreSnapshots, RequestLoggingTest, SearchHandlerTest, TestBlobHandler, T= estReqParamsAPI, TestSQLHandler] [junit4] Completed [596/732 (2!)] on J1 in 29.73s, 1 test, 1 failure <<<= FAILURES! [...truncated 35186 lines...] -ecj-javadoc-lint-src: [mkdir] Created dir: C:\Users\jenkins\AppData\Local\Temp\ecj87240347 [ecj-lint] Compiling 30 source files to C:\Users\jenkins\AppData\Local\Tem= p\ecj87240347 [ecj-lint] ---------- [ecj-lint] 1. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-master-Windo= ws\lucene\replicator\src\java\org\apache\lucene\replicator\nrt\PrimaryNode.= java (at line 33) [ecj-lint] =09import org.apache.lucene.index.Term; [ecj-lint] =09 ^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [ecj-lint] The import org.apache.lucene.index.Term is never used [ecj-lint] ---------- [ecj-lint] 2. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-master-Windo= ws\lucene\replicator\src\java\org\apache\lucene\replicator\nrt\PrimaryNode.= java (at line 37) [ecj-lint] =09import org.apache.lucene.search.TermQuery; [ecj-lint] =09 ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [ecj-lint] The import org.apache.lucene.search.TermQuery is never used [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 3. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-master-Windo= ws\lucene\replicator\src\java\org\apache\lucene\replicator\nrt\ReplicaNode.= java (at line 36) [ecj-lint] =09import org.apache.lucene.index.DirectoryReader; [ecj-lint] =09 ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [ecj-lint] The import org.apache.lucene.index.DirectoryReader is never use= d [ecj-lint] ---------- [ecj-lint] 3 problems (3 errors) BUILD FAILED C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\build.xml:810: The fo= llowing error occurred while executing this line: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\build.xml:101: The fo= llowing error occurred while executing this line: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build.xml:208:= The following error occurred while executing this line: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.x= ml:2205: The following error occurred while executing this line: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.x= ml:2030: The following error occurred while executing this line: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.x= ml:2069: Compile failed; see the compiler error output for details. Total time: 86 minutes 47 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts [WARNINGS] Skipping publisher since build result is FAILURE Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any ------=_Part_110_1170041281.1504283949548 Content-Type: text/plain; charset=us-ascii --------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org For additional commands, e-mail: dev-help@lucene.apache.org ------=_Part_110_1170041281.1504283949548--