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 35F94200CE3 for ; Sun, 13 Aug 2017 21:49:32 +0200 (CEST) Received: by cust-asf.ponee.io (Postfix) id 3448816414D; Sun, 13 Aug 2017 19:49:32 +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 88C5E16414C for ; Sun, 13 Aug 2017 21:49:29 +0200 (CEST) Received: (qmail 58000 invoked by uid 500); 13 Aug 2017 19:49:27 -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 57990 invoked by uid 99); 13 Aug 2017 19:49:27 -0000 Received: from pnap-us-west-generic-nat.apache.org (HELO spamd4-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Sun, 13 Aug 2017 19:49:27 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd4-us-west.apache.org (ASF Mail Server at spamd4-us-west.apache.org) with ESMTP id 0FDB2C024D for ; Sun, 13 Aug 2017 19:49:27 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd4-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 (spamd4-us-west.apache.org [10.40.0.11]) (amavisd-new, port 10024) with ESMTP id 7wShCBxEZQ0N for ; Sun, 13 Aug 2017 19:49: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 C88E75FD06 for ; Sun, 13 Aug 2017 19:49:09 +0000 (UTC) Received: from serv1 (ip6-localhost [IPv6:::1]) by serv1.sd-datasolutions.de (Postfix) with ESMTP id D2CC310800CB for ; Sun, 13 Aug 2017 19:49:01 +0000 (UTC) Date: Sun, 13 Aug 2017 19:47:35 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org Message-ID: <1004455994.139.1502653741864.JavaMail.jenkins@serv1.sd-datasolutions.de> In-Reply-To: <1677783197.115.1502563496684.JavaMail.jenkins@serv1.sd-datasolutions.de> References: <1677783197.115.1502563496684.JavaMail.jenkins@serv1.sd-datasolutions.de> Subject: [JENKINS] Lucene-Solr-master-Windows (64bit/jdk1.8.0_144) - Build # 6820 - Still Unstable! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_138_216578160.1502653741813" X-Jenkins-Job: Lucene-Solr-master-Windows X-Jenkins-Result: UNSTABLE archived-at: Sun, 13 Aug 2017 19:49:32 -0000 ------=_Part_138_216578160.1502653741813 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6820/ Java: 64bit/jdk1.8.0_144 -XX:+UseCompressedOops -XX:+UseSerialGC 2 tests failed. FAILED: org.apache.solr.cloud.DocValuesNotIndexedTest.testGroupingDVOnly Error Message: Unexpected number of elements in the group for intGSF: 8 Stack Trace: java.lang.AssertionError: Unexpected number of elements in the group for in= tGSF: 8 =09at __randomizedtesting.SeedInfo.seed([723BDDF842FD1BE5:E980B3A00FA529BB]= :0) =09at org.junit.Assert.fail(Assert.java:93) =09at org.apache.solr.cloud.DocValuesNotIndexedTest.testGroupingDVOnly(DocV= aluesNotIndexedTest.java:377) =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:1713) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:907) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Random= izedRunner.java:943) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rando= mizedRunner.java:957) =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:916) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:802) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:852) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:863) =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.cloud.HealthCheckHandlerTest.testHealthCheckHandle= rSolrJ Error Message: Error from server at http://127.0.0.1:52432/solr: Host Unavailable: Not con= nected to zk Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error= from server at http://127.0.0.1:52432/solr: Host Unavailable: Not connecte= d to zk =09at __randomizedtesting.SeedInfo.seed([723BDDF842FD1BE5:D8C802EC391B53E4]= :0) =09at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSo= lrClient.java:627) =09at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClie= nt.java:253) =09at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClie= nt.java:242) =09at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:178= ) =09at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:195= ) =09at org.apache.solr.cloud.HealthCheckHandlerTest.testHealthCheckHandlerSo= lrJ(HealthCheckHandlerTest.java:78) =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:1713) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:907) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Random= izedRunner.java:943) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rando= mizedRunner.java:957) =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:916) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:802) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:852) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:863) =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 11071 lines...] [junit4] Suite: org.apache.solr.cloud.HealthCheckHandlerTest [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-m= aster-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.HealthCheckHandl= erTest_723BDDF842FD1BE5-001\init-core-data-001 [junit4] 2> 382198 WARN (SUITE-HealthCheckHandlerTest-seed#[723BDDF84= 2FD1BE5]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpe= ns=3D2 numCloses=3D2 [junit4] 2> 382198 INFO (SUITE-HealthCheckHandlerTest-seed#[723BDDF84= 2FD1BE5]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POI= NTS_SYSPROP=3Dtrue) w/NUMERIC_DOCVALUES_SYSPROP=3Dtrue [junit4] 2> 382202 INFO (SUITE-HealthCheckHandlerTest-seed#[723BDDF84= 2FD1BE5]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and cli= entAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=3D, value=3D= NaN, ssl=3DNaN, clientAuth=3DNaN) [junit4] 2> 382202 INFO (SUITE-HealthCheckHandlerTest-seed#[723BDDF84= 2FD1BE5]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: te= st.solr.allowed.securerandom=3Dnull & java.security.egd=3Dfile:/dev/./urand= om [junit4] 2> 382204 INFO (SUITE-HealthCheckHandlerTest-seed#[723BDDF84= 2FD1BE5]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 = servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build= \solr-core\test\J0\temp\solr.cloud.HealthCheckHandlerTest_723BDDF842FD1BE5-= 001\tempDir-001 [junit4] 2> 382204 INFO (SUITE-HealthCheckHandlerTest-seed#[723BDDF84= 2FD1BE5]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 382205 INFO (Thread-1296) [ ] o.a.s.c.ZkTestServer cli= ent port:0.0.0.0/0.0.0.0:0 [junit4] 2> 382205 INFO (Thread-1296) [ ] o.a.s.c.ZkTestServer Sta= rting server [junit4] 2> 382209 ERROR (Thread-1296) [ ] o.a.z.s.ZooKeeperServer = ZKShutdownHandler is not registered, so ZooKeeper server won't take any act= ion on ERROR or SHUTDOWN server state changes [junit4] 2> 382305 INFO (SUITE-HealthCheckHandlerTest-seed#[723BDDF84= 2FD1BE5]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:52428 [junit4] 2> 382335 INFO (jetty-launcher-1240-thread-1) [ ] o.e.j.s= .Server jetty-9.3.14.v20161028 [junit4] 2> 382335 INFO (jetty-launcher-1240-thread-1) [ ] o.e.j.s= .h.ContextHandler Started o.e.j.s.ServletContextHandler@1f20b3d{/solr,null,= AVAILABLE} [junit4] 2> 382341 INFO (jetty-launcher-1240-thread-1) [ ] o.e.j.s= .AbstractConnector Started ServerConnector@45775159{HTTP/1.1,[http/1.1]}{12= 7.0.0.1:52432} [junit4] 2> 382341 INFO (jetty-launcher-1240-thread-1) [ ] o.e.j.s= .Server Started @390549ms [junit4] 2> 382341 INFO (jetty-launcher-1240-thread-1) [ ] o.a.s.c= .s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D524= 32} [junit4] 2> 382342 ERROR (jetty-launcher-1240-thread-1) [ ] o.a.s.s= .StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missi= ng or incomplete. [junit4] 2> 382342 INFO (jetty-launcher-1240-thread-1) [ ] o.a.s.s= .SolrDispatchFilter ___ _ Welcome to Apache Solr=E2=84=A2 versi= on 8.0.0 [junit4] 2> 382342 INFO (jetty-launcher-1240-thread-1) [ ] o.a.s.s= .SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 382342 INFO (jetty-launcher-1240-thread-1) [ ] o.a.s.s= .SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir= : null [junit4] 2> 382342 INFO (jetty-launcher-1240-thread-1) [ ] o.a.s.s= .SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-13T18:33:55.797Z [junit4] 2> 382357 INFO (jetty-launcher-1240-thread-1) [ ] o.a.s.s= .SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 382370 INFO (jetty-launcher-1240-thread-1) [ ] o.a.s.c= .ZkContainer Zookeeper client=3D127.0.0.1:52428/solr [junit4] 2> 382373 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 0x15ddcdfb8d00002, 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> 382475 INFO (jetty-launcher-1240-thread-1) [n:127.0.0.1:5= 2432_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 382476 INFO (jetty-launcher-1240-thread-1) [n:127.0.0.1:5= 2432_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader = 127.0.0.1:52432_solr [junit4] 2> 382476 INFO (jetty-launcher-1240-thread-1) [n:127.0.0.1:5= 2432_solr ] o.a.s.c.Overseer Overseer (id=3D98477620308475907-127.0.0.1:= 52432_solr-n_0000000000) starting [junit4] 2> 382479 INFO (jetty-launcher-1240-thread-1) [n:127.0.0.1:5= 2432_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/liv= e_nodes/127.0.0.1:52432_solr [junit4] 2> 382485 INFO (zkCallback-1247-thread-1-processing-n:127.0.= 0.1:52432_solr) [n:127.0.0.1:52432_solr ] o.a.s.c.c.ZkStateReader Update= d live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 382615 INFO (jetty-launcher-1240-thread-1) [n:127.0.0.1:5= 2432_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52432.sol= r.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.J= mxMBeanServer@74cc2851 [junit4] 2> 382617 INFO (jetty-launcher-1240-thread-1) [n:127.0.0.1:5= 2432_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52432.sol= r.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.Jmx= MBeanServer@74cc2851 [junit4] 2> 382617 INFO (jetty-launcher-1240-thread-1) [n:127.0.0.1:5= 2432_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52432.sol= r.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver= .JmxMBeanServer@74cc2851 [junit4] 2> 382618 INFO (jetty-launcher-1240-thread-1) [n:127.0.0.1:5= 2432_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions under= neath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr= -core\test\J0\temp\solr.cloud.HealthCheckHandlerTest_723BDDF842FD1BE5-001\t= empDir-001\node1\. [junit4] 2> 382642 INFO (SUITE-HealthCheckHandlerTest-seed#[723BDDF84= 2FD1BE5]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from Zoo= Keeper... (0) -> (1) [junit4] 2> 382643 INFO (SUITE-HealthCheckHandlerTest-seed#[723BDDF84= 2FD1BE5]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at= 127.0.0.1:52428/solr ready [junit4] 2> 382652 INFO (TEST-HealthCheckHandlerTest.testHealthCheckH= andlerWithCloudClient-seed#[723BDDF842FD1BE5]) [ ] o.a.s.SolrTestCaseJ4 = ###Starting testHealthCheckHandlerWithCloudClient [junit4] 2> 382656 INFO (TEST-HealthCheckHandlerTest.testHealthCheckH= andlerWithCloudClient-seed#[723BDDF842FD1BE5]) [ ] o.a.s.SolrTestCaseJ4 = ###Ending testHealthCheckHandlerWithCloudClient [junit4] 2> 382665 INFO (TEST-HealthCheckHandlerTest.testHealthCheckH= andler-seed#[723BDDF842FD1BE5]) [ ] o.a.s.SolrTestCaseJ4 ###Starting tes= tHealthCheckHandler [junit4] 2> 382669 INFO (qtp1373278149-6630) [n:127.0.0.1:52432_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/health params= =3D{wt=3Djavabin&version=3D2} status=3D0 QTime=3D0 [junit4] 2> 382670 INFO (TEST-HealthCheckHandlerTest.testHealthCheckH= andler-seed#[723BDDF842FD1BE5]) [ ] o.a.s.c.MiniSolrCloudCluster Expired= zookeeper session 98477620308475907 from node http://127.0.0.1:52432/solr [junit4] 2> 382671 INFO (zkCallback-1252-thread-1) [ ] o.a.s.c.c.Z= kStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 382772 INFO (qtp1373278149-6625) [n:127.0.0.1:52432_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/health params= =3D{wt=3Djavabin&version=3D2} status=3D0 QTime=3D0 [junit4] 2> 382772 INFO (TEST-HealthCheckHandlerTest.testHealthCheckH= andler-seed#[723BDDF842FD1BE5]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testH= ealthCheckHandler [junit4] 2> 382773 WARN (zkCallback-1247-thread-8-processing-n:127.0.= 0.1:52432_solr) [n:127.0.0.1:52432_solr ] o.a.s.c.c.ConnectionManager Wa= tcher org.apache.solr.common.cloud.ConnectionManager@22e3542a name: ZooKeep= erConnection Watcher:127.0.0.1:52428/solr got event WatchedEvent state:Disc= onnected type:None path:null path: null type: None [junit4] 2> 382774 WARN (zkCallback-1247-thread-8-processing-n:127.0.= 0.1:52432_solr) [n:127.0.0.1:52432_solr ] o.a.s.c.c.ConnectionManager zk= Client has disconnected [junit4] 2> 382794 INFO (TEST-HealthCheckHandlerTest.testHealthCheckH= andlerSolrJ-seed#[723BDDF842FD1BE5]) [ ] o.a.s.SolrTestCaseJ4 ###Startin= g testHealthCheckHandlerSolrJ [junit4] 2> 382808 INFO (qtp1373278149-6622) [n:127.0.0.1:52432_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/health params= =3D{wt=3Djavabin&version=3D2} status=3D503 QTime=3D0 [junit4] 2> 382809 INFO (TEST-HealthCheckHandlerTest.testHealthCheckH= andlerSolrJ-seed#[723BDDF842FD1BE5]) [ ] o.a.s.SolrTestCaseJ4 ###Ending = testHealthCheckHandlerSolrJ [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DHealthCheckHa= ndlerTest -Dtests.method=3DtestHealthCheckHandlerSolrJ -Dtests.seed=3D723BD= DF842FD1BE5 -Dtests.slow=3Dtrue -Dtests.locale=3Dnl-NL -Dtests.timezone=3DA= merica/Los_Angeles -Dtests.asserts=3Dtrue -Dtests.file.encoding=3DUTF-8 [junit4] ERROR 0.04s J0 | HealthCheckHandlerTest.testHealthCheckHandle= rSolrJ <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrCl= ient$RemoteSolrException: Error from server at http://127.0.0.1:52432/solr:= Host Unavailable: Not connected to zk [junit4] > =09at __randomizedtesting.SeedInfo.seed([723BDDF842FD1BE5:= D8C802EC391B53E4]:0) [junit4] > =09at org.apache.solr.client.solrj.impl.HttpSolrClient.exe= cuteMethod(HttpSolrClient.java:627) [junit4] > =09at org.apache.solr.client.solrj.impl.HttpSolrClient.req= uest(HttpSolrClient.java:253) [junit4] > =09at org.apache.solr.client.solrj.impl.HttpSolrClient.req= uest(HttpSolrClient.java:242) [junit4] > =09at org.apache.solr.client.solrj.SolrRequest.process(Sol= rRequest.java:178) [junit4] > =09at org.apache.solr.client.solrj.SolrRequest.process(Sol= rRequest.java:195) [junit4] > =09at org.apache.solr.cloud.HealthCheckHandlerTest.testHea= lthCheckHandlerSolrJ(HealthCheckHandlerTest.java:78) [junit4] > =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> 382816 INFO (jetty-closer-1241-thread-1) [ ] o.e.j.s.A= bstractConnector Stopped ServerConnector@45775159{HTTP/1.1,[http/1.1]}{127.= 0.0.1:0} [junit4] 2> 382816 INFO (jetty-closer-1241-thread-1) [ ] o.a.s.c.C= oreContainer Shutting down CoreContainer instance=3D710518707 [junit4] 2> 382817 INFO (jetty-closer-1241-thread-1) [ ] o.a.s.m.S= olrMetricManager Closing metric reporters for registry=3Dsolr.node, tag=3Dn= ull [junit4] 2> 382819 INFO (jetty-closer-1241-thread-1) [ ] o.a.s.m.S= olrMetricManager Closing metric reporters for registry=3Dsolr.jvm, tag=3Dnu= ll [junit4] 2> 382821 INFO (jetty-closer-1241-thread-1) [ ] o.a.s.m.S= olrMetricManager Closing metric reporters for registry=3Dsolr.jetty, tag=3D= null [junit4] 2> 383808 WARN (jetty-launcher-1240-thread-1-SendThread(127.= 0.0.1:52428)) [n:127.0.0.1:52432_solr ] o.a.z.ClientCnxn Unable to recon= nect to ZooKeeper service, session 0x15ddcdfb8d00003 has expired [junit4] 2> 383808 WARN (zkCallback-1247-thread-4-processing-n:127.0.= 0.1:52432_solr) [n:127.0.0.1:52432_solr ] o.a.s.c.c.ConnectionManager Wa= tcher org.apache.solr.common.cloud.ConnectionManager@22e3542a name: ZooKeep= erConnection Watcher:127.0.0.1:52428/solr got event WatchedEvent state:Expi= red type:None path:null path: null type: None [junit4] 2> 383808 WARN (zkCallback-1247-thread-4-processing-n:127.0.= 0.1:52432_solr) [n:127.0.0.1:52432_solr ] o.a.s.c.c.ConnectionManager Ou= r previous ZooKeeper session was expired. Attempting to reconnect to recove= r relationship with ZooKeeper... [junit4] 2> 383808 WARN (OverseerStateUpdate-98477620308475907-127.0.= 0.1:52432_solr-n_0000000000) [n:127.0.0.1:52432_solr ] o.a.s.c.Overseer = Solr cannot talk to ZK, exiting Overseer main queue loop [junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredExcepti= on: KeeperErrorCode =3D Session expired for /overseer/queue [junit4] 2> =09at org.apache.zookeeper.KeeperException.create(KeeperEx= ception.java:127) [junit4] 2> =09at org.apache.zookeeper.KeeperException.create(KeeperEx= ception.java:51) [junit4] 2> =09at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper= .java:1532) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$6.execute(= SolrZkClient.java:340) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$6.execute(= SolrZkClient.java:337) [junit4] 2> =09at org.apache.solr.common.cloud.ZkCmdExecutor.retryOper= ation(ZkCmdExecutor.java:60) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient.getChildre= n(SolrZkClient.java:337) [junit4] 2> =09at org.apache.solr.cloud.DistributedQueue.fetchZkChildr= en(DistributedQueue.java:305) [junit4] 2> =09at org.apache.solr.cloud.DistributedQueue.firstChild(Di= stributedQueue.java:282) [junit4] 2> =09at org.apache.solr.cloud.DistributedQueue.firstElement(= DistributedQueue.java:398) [junit4] 2> =09at org.apache.solr.cloud.DistributedQueue.peek(Distribu= tedQueue.java:161) [junit4] 2> =09at org.apache.solr.cloud.DistributedQueue.peek(Distribu= tedQueue.java:139) [junit4] 2> =09at org.apache.solr.cloud.Overseer$ClusterStateUpdater.r= un(Overseer.java:184) [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> 383808 INFO (OverseerStateUpdate-98477620308475907-127.0.= 0.1:52432_solr-n_0000000000) [n:127.0.0.1:52432_solr ] o.a.s.c.Overseer = Overseer Loop exiting : 127.0.0.1:52432_solr [junit4] 2> 383808 INFO (zkCallback-1247-thread-4-processing-n:127.0.= 0.1:52432_solr) [n:127.0.0.1:52432_solr ] o.a.s.c.Overseer Overseer (id= =3D98477620308475907-127.0.0.1:52432_solr-n_0000000000) closing [junit4] 2> 383808 WARN (OverseerCollectionConfigSetProcessor-9847762= 0308475907-127.0.0.1:52432_solr-n_0000000000) [n:127.0.0.1:52432_solr ] = o.a.s.c.OverseerTaskProcessor Overseer cannot talk to ZK [junit4] 2> 383809 INFO (zkCallback-1247-thread-4-processing-n:127.0.= 0.1:52432_solr) [n:127.0.0.1:52432_solr ] o.a.s.c.TransientSolrCoreCache= Default Allocating transient cache for 2147483647 transient cores [junit4] 2> 383809 WARN (zkCallback-1247-thread-4-processing-n:127.0.= 0.1:52432_solr) [n:127.0.0.1:52432_solr ] o.a.s.c.c.DefaultConnectionStr= ategy Connection expired - starting a new one... [junit4] 2> 383811 INFO (zkCallback-1247-thread-4-processing-n:127.0.= 0.1:52432_solr) [n:127.0.0.1:52432_solr ] o.a.s.c.c.ConnectionManager Co= nnection with ZooKeeper reestablished. [junit4] 2> 383811 INFO (zkCallback-1247-thread-4-processing-n:127.0.= 0.1:52432_solr) [n:127.0.0.1:52432_solr ] o.a.s.c.ZkController ZooKeeper= session re-connected ... refreshing core states after session expiration. [junit4] 2> 383912 ERROR (jetty-closer-1241-thread-1) [ ] o.a.s.c.O= verseer Could not create Overseer node [junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredExcepti= on: KeeperErrorCode =3D Session expired for /overseer [junit4] 2> =09at org.apache.zookeeper.KeeperException.create(KeeperEx= ception.java:127) [junit4] 2> =09at org.apache.zookeeper.KeeperException.create(KeeperEx= ception.java:51) [junit4] 2> =09at org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java= :783) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$9.execute(= SolrZkClient.java:392) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$9.execute(= SolrZkClient.java:389) [junit4] 2> =09at org.apache.solr.common.cloud.ZkCmdExecutor.retryOper= ation(ZkCmdExecutor.java:60) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient.create(Sol= rZkClient.java:389) [junit4] 2> =09at org.apache.solr.cloud.Overseer.createOverseerNode(Ov= erseer.java:758) [junit4] 2> =09at org.apache.solr.cloud.Overseer.getStateUpdateQueue(O= verseer.java:631) [junit4] 2> =09at org.apache.solr.cloud.Overseer.getStateUpdateQueue(O= verseer.java:618) [junit4] 2> =09at org.apache.solr.cloud.ZkController.publishNodeAsDown= (ZkController.java:2515) [junit4] 2> =09at org.apache.solr.core.CoreContainer.shutdown(CoreCont= ainer.java:743) [junit4] 2> =09at org.apache.solr.servlet.SolrDispatchFilter.destroy(S= olrDispatchFilter.java:313) [junit4] 2> =09at org.eclipse.jetty.servlet.FilterHolder.destroyInstan= ce(FilterHolder.java:172) [junit4] 2> =09at org.eclipse.jetty.servlet.FilterHolder.doStop(Filter= Holder.java:150) [junit4] 2> =09at org.eclipse.jetty.util.component.AbstractLifeCycle.s= top(AbstractLifeCycle.java:89) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler.doStop(Serv= letHandler.java:238) [junit4] 2> =09at org.eclipse.jetty.util.component.AbstractLifeCycle.s= top(AbstractLifeCycle.java:89) [junit4] 2> =09at org.eclipse.jetty.util.component.ContainerLifeCycle.= stop(ContainerLifeCycle.java:142) [junit4] 2> =09at org.eclipse.jetty.util.component.ContainerLifeCycle.= doStop(ContainerLifeCycle.java:160) [junit4] 2> =09at org.eclipse.jetty.server.handler.AbstractHandler.doS= top(AbstractHandler.java:73) [junit4] 2> =09at org.eclipse.jetty.server.session.SessionHandler.doSt= op(SessionHandler.java:127) [junit4] 2> =09at org.eclipse.jetty.util.component.AbstractLifeCycle.s= top(AbstractLifeCycle.java:89) [junit4] 2> =09at org.eclipse.jetty.util.component.ContainerLifeCycle.= stop(ContainerLifeCycle.java:142) [junit4] 2> =09at org.eclipse.jetty.util.component.ContainerLifeCycle.= doStop(ContainerLifeCycle.java:160) [junit4] 2> =09at org.eclipse.jetty.server.handler.AbstractHandler.doS= top(AbstractHandler.java:73) [junit4] 2> =09at org.eclipse.jetty.server.handler.ContextHandler.stop= Context(ContextHandler.java:825) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletContextHandler.stop= Context(ServletContextHandler.java:356) [junit4] 2> =09at org.eclipse.jetty.server.handler.ContextHandler.doSt= op(ContextHandler.java:880) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletContextHandler.doSt= op(ServletContextHandler.java:272) [junit4] 2> =09at org.eclipse.jetty.util.component.AbstractLifeCycle.s= top(AbstractLifeCycle.java:89) [junit4] 2> =09at org.eclipse.jetty.util.component.ContainerLifeCycle.= stop(ContainerLifeCycle.java:142) [junit4] 2> =09at org.eclipse.jetty.util.component.ContainerLifeCycle.= doStop(ContainerLifeCycle.java:160) [junit4] 2> =09at org.eclipse.jetty.server.handler.AbstractHandler.doS= top(AbstractHandler.java:73) [junit4] 2> =09at org.eclipse.jetty.util.component.AbstractLifeCycle.s= top(AbstractLifeCycle.java:89) [junit4] 2> =09at org.eclipse.jetty.util.component.ContainerLifeCycle.= stop(ContainerLifeCycle.java:142) [junit4] 2> =09at org.eclipse.jetty.util.component.ContainerLifeCycle.= doStop(ContainerLifeCycle.java:160) [junit4] 2> =09at org.eclipse.jetty.server.handler.AbstractHandler.doS= top(AbstractHandler.java:73) [junit4] 2> =09at org.eclipse.jetty.server.Server.doStop(Server.java:4= 93) [junit4] 2> =09at org.eclipse.jetty.util.component.AbstractLifeCycle.s= top(AbstractLifeCycle.java:89) [junit4] 2> =09at org.apache.solr.client.solrj.embedded.JettySolrRunne= r.stop(JettySolrRunner.java:428) [junit4] 2> =09at org.apache.solr.cloud.MiniSolrCloudCluster.stopJetty= SolrRunner(MiniSolrCloudCluster.java:423) [junit4] 2> =09at org.apache.solr.cloud.MiniSolrCloudCluster.lambda$sh= utdown$1(MiniSolrCloudCluster.java:460) [junit4] 2> =09at java.util.concurrent.FutureTask.run(FutureTask.java:= 266) [junit4] 2> =09at org.apache.solr.common.util.ExecutorUtil$MDCAwareThr= eadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [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> 383912 WARN (jetty-closer-1241-thread-1) [ ] o.a.s.c.Z= kController Could not publish node as down: org.apache.zookeeper.KeeperExce= ption$SessionExpiredException: KeeperErrorCode =3D Session expired for /ove= rseer [junit4] 2> 383913 INFO (jetty-closer-1241-thread-1) [ ] o.a.s.m.S= olrMetricManager Closing metric reporters for registry=3Dsolr.cluster, tag= =3Dnull [junit4] 2> 384272 WARN (zkCallback-1247-thread-1-processing-n:127.0.= 0.1:52432_solr) [n:127.0.0.1:52432_solr ] o.a.s.c.c.ZkStateReader ZooKee= per watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode =3D Sessi= on expired for /live_nodes] [junit4] 2> 384273 ERROR (zkCallback-1247-thread-4-processing-n:127.0.= 0.1:52432_solr) [n:127.0.0.1:52432_solr ] o.a.s.c.ZkController :org.apac= he.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode =3D S= ession expired for /live_nodes [junit4] 2> =09at org.apache.zookeeper.KeeperException.create(KeeperEx= ception.java:127) [junit4] 2> =09at org.apache.zookeeper.KeeperException.create(KeeperEx= ception.java:51) [junit4] 2> =09at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper= .java:1532) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$6.execute(= SolrZkClient.java:340) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$6.execute(= SolrZkClient.java:337) [junit4] 2> =09at org.apache.solr.common.cloud.ZkCmdExecutor.retryOper= ation(ZkCmdExecutor.java:60) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient.getChildre= n(SolrZkClient.java:337) [junit4] 2> =09at org.apache.solr.common.cloud.ZkStateReader.refreshLi= veNodes(ZkStateReader.java:674) [junit4] 2> =09at org.apache.solr.common.cloud.ZkStateReader.createClu= sterStateWatchersAndUpdate(ZkStateReader.java:391) [junit4] 2> =09at org.apache.solr.cloud.ZkController$1.command(ZkContr= oller.java:320) [junit4] 2> =09at org.apache.solr.common.cloud.ConnectionManager$1.upd= ate(ConnectionManager.java:168) [junit4] 2> =09at org.apache.solr.common.cloud.DefaultConnectionStrate= gy.reconnect(DefaultConnectionStrategy.java:57) [junit4] 2> =09at org.apache.solr.common.cloud.ConnectionManager.proce= ss(ConnectionManager.java:142) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$3.lambda$p= rocess$0(SolrZkClient.java:269) [junit4] 2> =09at java.util.concurrent.Executors$RunnableAdapter.call(= Executors.java:511) [junit4] 2> =09at java.util.concurrent.FutureTask.run(FutureTask.java:= 266) [junit4] 2> =09at org.apache.solr.common.util.ExecutorUtil$MDCAwareThr= eadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [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>=20 [junit4] 2> 384273 ERROR (zkCallback-1247-thread-4-processing-n:127.0.= 0.1:52432_solr) [n:127.0.0.1:52432_solr ] o.a.s.c.c.DefaultConnectionStr= ategy Reconnect to ZooKeeper failed:org.apache.solr.common.cloud.ZooKeeperE= xception:=20 [junit4] 2> =09at org.apache.solr.cloud.ZkController$1.command(ZkContr= oller.java:405) [junit4] 2> =09at org.apache.solr.common.cloud.ConnectionManager$1.upd= ate(ConnectionManager.java:168) [junit4] 2> =09at org.apache.solr.common.cloud.DefaultConnectionStrate= gy.reconnect(DefaultConnectionStrategy.java:57) [junit4] 2> =09at org.apache.solr.common.cloud.ConnectionManager.proce= ss(ConnectionManager.java:142) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$3.lambda$p= rocess$0(SolrZkClient.java:269) [junit4] 2> =09at java.util.concurrent.Executors$RunnableAdapter.call(= Executors.java:511) [junit4] 2> =09at java.util.concurrent.FutureTask.run(FutureTask.java:= 266) [junit4] 2> =09at org.apache.solr.common.util.ExecutorUtil$MDCAwareThr= eadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [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> Caused by: org.apache.zookeeper.KeeperException$SessionExp= iredException: KeeperErrorCode =3D Session expired for /live_nodes [junit4] 2> =09at org.apache.zookeeper.KeeperException.create(KeeperEx= ception.java:127) [junit4] 2> =09at org.apache.zookeeper.KeeperException.create(KeeperEx= ception.java:51) [junit4] 2> =09at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper= .java:1532) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$6.execute(= SolrZkClient.java:340) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$6.execute(= SolrZkClient.java:337) [junit4] 2> =09at org.apache.solr.common.cloud.ZkCmdExecutor.retryOper= ation(ZkCmdExecutor.java:60) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient.getChildre= n(SolrZkClient.java:337) [junit4] 2> =09at org.apache.solr.common.cloud.ZkStateReader.refreshLi= veNodes(ZkStateReader.java:674) [junit4] 2> =09at org.apache.solr.common.cloud.ZkStateReader.createClu= sterStateWatchersAndUpdate(ZkStateReader.java:391) [junit4] 2> =09at org.apache.solr.cloud.ZkController$1.command(ZkContr= oller.java:320) [junit4] 2> =09... 10 more [junit4] 2>=20 [junit4] 2> 384274 WARN (zkCallback-1247-thread-4-processing-n:127.0.= 0.1:52432_solr) [n:127.0.0.1:52432_solr ] o.a.s.c.c.DefaultConnectionStr= ategy Reconnect to ZooKeeper failed [junit4] 2> 384275 INFO (zkCallback-1247-thread-4-processing-n:127.0.= 0.1:52432_solr) [n:127.0.0.1:52432_solr ] o.a.s.c.c.ConnectionManager Co= nnected:true [junit4] 2> 384276 INFO (jetty-closer-1241-thread-1) [ ] o.e.j.s.h= .ContextHandler Stopped o.e.j.s.ServletContextHandler@1f20b3d{/solr,null,UN= AVAILABLE} [junit4] 2> 384279 ERROR (SUITE-HealthCheckHandlerTest-seed#[723BDDF84= 2FD1BE5]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not re= gistered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN se= rver state changes [junit4] 2> 384279 INFO (SUITE-HealthCheckHandlerTest-seed#[723BDDF84= 2FD1BE5]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:52428 = 52428 [junit4] 2> 384280 INFO (Thread-1296) [ ] o.a.s.c.ZkTestServer con= necting to 127.0.0.1:52428 52428 [junit4] 2> 384284 WARN (Thread-1296) [ ] o.a.s.c.ZkTestServer Wat= ch limit violations:=20 [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2>=20 [junit4] 2> =093=09/solr/clusterprops.json [junit4] 2> =092=09/solr/aliases.json [junit4] 2>=20 [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2>=20 [junit4] 2> =092=09/solr/clusterstate.json [junit4] 2>=20 [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2>=20 [junit4] 2> =092=09/solr/live_nodes [junit4] 2> =092=09/solr/collections [junit4] 2>=20 [junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins= \workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\sol= r.cloud.HealthCheckHandlerTest_723BDDF842FD1BE5-001 [junit4] 2> Aug 13, 2017 6:33:57 PM com.carrotsearch.randomizedtesting= .ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thre= ad(s). [junit4] 2> NOTE: test params are: codec=3DLucene70, sim=3DRandomSimil= arity(queryNorm=3Dtrue): {}, locale=3Dnl-NL, timezone=3DAmerica/Los_Angeles [junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_144 (= 64-bit)/cpus=3D3,threads=3D1,free=3D58543712,total=3D222011392 [junit4] 2> NOTE: All tests run in this JVM: [TestImpersonationWithHad= oopAuth, DefaultValueUpdateProcessorTest, TestSizeLimitedDistributedMap, Te= stNestedDocsSort, TestReplicationHandler, PrimitiveFieldTypeTest, TestManag= edSchemaAPI, TestFunctionQuery, TestSolrQueryResponse, PropertiesRequestHan= dlerTest, SliceStateTest, ClusterStateTest, TestMacros, TestTrackingShardHa= ndlerFactory, SolrIndexSplitterTest, HdfsRecoveryZkTest, CdcrUpdateLogTest,= TestExactStatsCache, TestSmileRequest, BasicAuthIntegrationTest, NumericFi= eldsTest, SyncSliceTest, TestFuzzyAnalyzedSuggestions, V2StandaloneTest, Si= mpleFacetsTest, TestManagedResourceStorage, TestPKIAuthenticationPlugin, Ov= erseerTaskQueueTest, TestSubQueryTransformer, VersionInfoTest, TestSha256Au= thenticationProvider, TestStressLiveNodes, TimeZoneUtilsTest, DistributedFa= cetExistsSmallTest, UniqFieldsUpdateProcessorFactoryTest, ResponseHeaderTes= t, DateMathParserTest, SparseHLLTest, CircularListTest, TestPointFields, Te= stJmxIntegration, TestStressRecovery, DistributedDebugComponentTest, TestSy= stemCollAutoCreate, TestLegacyNumericRangeQueryBuilder, TestQueryWrapperFil= ter, TestCustomDocTransformer, DirectSolrConnectionTest, SSLMigrationTest, = TestStressCloudBlindAtomicUpdates, BigEndianAscendingWordSerializerTest, Te= stNoOpRegenerator, TestConfigOverlay, TestNRTOpen, SolrShardReporterTest, T= estOrdValues, TestSolrCloudSnapshots, FastVectorHighlighterTest, PathHierar= chyTokenizerFactoryTest, HealthCheckHandlerTest] [junit4] Completed [110/729 (1!)] on J0 in 2.62s, 3 tests, 1 error <<< F= AILURES! [...truncated 1384 lines...] [junit4] Suite: org.apache.solr.cloud.DocValuesNotIndexedTest [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-m= aster-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotInde= xedTest_723BDDF842FD1BE5-001\init-core-data-001 [junit4] 2> 1989111 WARN (SUITE-DocValuesNotIndexedTest-seed#[723BDDF= 842FD1BE5]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numO= pens=3D7 numCloses=3D7 [junit4] 2> 1989111 INFO (SUITE-DocValuesNotIndexedTest-seed#[723BDDF= 842FD1BE5]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_PO= INTS_SYSPROP=3Dfalse) w/NUMERIC_DOCVALUES_SYSPROP=3Dfalse [junit4] 2> 1989117 INFO (SUITE-DocValuesNotIndexedTest-seed#[723BDDF= 842FD1BE5]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and c= lientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=3D, value= =3DNaN, ssl=3DNaN, clientAuth=3DNaN) [junit4] 2> 1989117 INFO (SUITE-DocValuesNotIndexedTest-seed#[723BDDF= 842FD1BE5]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: = test.solr.allowed.securerandom=3Dnull & java.security.egd=3Dfile:/dev/./ura= ndom [junit4] 2> 1989119 INFO (SUITE-DocValuesNotIndexedTest-seed#[723BDDF= 842FD1BE5]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of = 2 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\bui= ld\solr-core\test\J1\temp\solr.cloud.DocValuesNotIndexedTest_723BDDF842FD1B= E5-001\tempDir-001 [junit4] 2> 1989120 INFO (SUITE-DocValuesNotIndexedTest-seed#[723BDDF= 842FD1BE5]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1989120 INFO (Thread-5329) [ ] o.a.s.c.ZkTestServer cl= ient port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1989120 INFO (Thread-5329) [ ] o.a.s.c.ZkTestServer St= arting server [junit4] 2> 1989126 ERROR (Thread-5329) [ ] o.a.z.s.ZooKeeperServer= ZKShutdownHandler is not registered, so ZooKeeper server won't take any ac= tion on ERROR or SHUTDOWN server state changes [junit4] 2> 1989220 INFO (SUITE-DocValuesNotIndexedTest-seed#[723BDDF= 842FD1BE5]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:6494= 2 [junit4] 2> 1989231 INFO (jetty-launcher-3214-thread-1) [ ] o.e.j.= s.Server jetty-9.3.14.v20161028 [junit4] 2> 1989231 INFO (jetty-launcher-3214-thread-2) [ ] o.e.j.= s.Server jetty-9.3.14.v20161028 [junit4] 2> 1989233 INFO (jetty-launcher-3214-thread-1) [ ] o.e.j.= s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2a087f8e{/solr,nul= l,AVAILABLE} [junit4] 2> 1989234 INFO (jetty-launcher-3214-thread-2) [ ] o.e.j.= s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3ce1e42f{/solr,nul= l,AVAILABLE} [junit4] 2> 1989235 INFO (jetty-launcher-3214-thread-1) [ ] o.e.j.= s.AbstractConnector Started ServerConnector@401ded7c{HTTP/1.1,[http/1.1]}{1= 27.0.0.1:64946} [junit4] 2> 1989235 INFO (jetty-launcher-3214-thread-2) [ ] o.e.j.= s.AbstractConnector Started ServerConnector@66942c1a{HTTP/1.1,[http/1.1]}{1= 27.0.0.1:64947} [junit4] 2> 1989236 INFO (jetty-launcher-3214-thread-2) [ ] o.e.j.= s.Server Started @1997230ms [junit4] 2> 1989235 INFO (jetty-launcher-3214-thread-1) [ ] o.e.j.= s.Server Started @1997229ms [junit4] 2> 1989237 INFO (jetty-launcher-3214-thread-2) [ ] o.a.s.= c.s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D64= 947} [junit4] 2> 1989237 INFO (jetty-launcher-3214-thread-1) [ ] o.a.s.= c.s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D64= 946} [junit4] 2> 1989237 ERROR (jetty-launcher-3214-thread-1) [ ] o.a.s.= s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be miss= ing or incomplete. [junit4] 2> 1989237 ERROR (jetty-launcher-3214-thread-2) [ ] o.a.s.= s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be miss= ing or incomplete. [junit4] 2> 1989237 INFO (jetty-launcher-3214-thread-1) [ ] o.a.s.= s.SolrDispatchFilter ___ _ Welcome to Apache Solr=E2=84=A2 vers= ion 8.0.0 [junit4] 2> 1989237 INFO (jetty-launcher-3214-thread-2) [ ] o.a.s.= s.SolrDispatchFilter ___ _ Welcome to Apache Solr=E2=84=A2 vers= ion 8.0.0 [junit4] 2> 1989237 INFO (jetty-launcher-3214-thread-1) [ ] o.a.s.= s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1989237 INFO (jetty-launcher-3214-thread-2) [ ] o.a.s.= s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1989237 INFO (jetty-launcher-3214-thread-1) [ ] o.a.s.= s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config di= r: null [junit4] 2> 1989237 INFO (jetty-launcher-3214-thread-2) [ ] o.a.s.= s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config di= r: null [junit4] 2> 1989237 INFO (jetty-launcher-3214-thread-1) [ ] o.a.s.= s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-13T19:00:42.478Z [junit4] 2> 1989237 INFO (jetty-launcher-3214-thread-2) [ ] o.a.s.= s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-13T19:00:42.478Z [junit4] 2> 1989243 INFO (jetty-launcher-3214-thread-2) [ ] o.a.s.= s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1989243 INFO (jetty-launcher-3214-thread-1) [ ] o.a.s.= s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1989263 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 0x15ddcf83cfe0001, 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> 1989266 INFO (jetty-launcher-3214-thread-1) [ ] o.a.s.= c.ZkContainer Zookeeper client=3D127.0.0.1:64942/solr [junit4] 2> 1989267 INFO (jetty-launcher-3214-thread-2) [ ] o.a.s.= c.ZkContainer Zookeeper client=3D127.0.0.1:64942/solr [junit4] 2> 1989270 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 0x15ddcf83cfe0003, 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> 1989412 INFO (jetty-launcher-3214-thread-2) [n:127.0.0.1:= 64947_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 1989412 INFO (jetty-launcher-3214-thread-1) [n:127.0.0.1:= 64946_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 1989412 INFO (jetty-launcher-3214-thread-2) [n:127.0.0.1:= 64947_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader= 127.0.0.1:64947_solr [junit4] 2> 1989413 INFO (jetty-launcher-3214-thread-1) [n:127.0.0.1:= 64946_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/li= ve_nodes/127.0.0.1:64946_solr [junit4] 2> 1989414 INFO (jetty-launcher-3214-thread-2) [n:127.0.0.1:= 64947_solr ] o.a.s.c.Overseer Overseer (id=3D98477725605298181-127.0.0.1= :64947_solr-n_0000000000) starting [junit4] 2> 1989415 INFO (zkCallback-3225-thread-1-processing-n:127.0= .0.1:64946_solr) [n:127.0.0.1:64946_solr ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1989416 INFO (zkCallback-3226-thread-1-processing-n:127.0= .0.1:64947_solr) [n:127.0.0.1:64947_solr ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1989424 INFO (jetty-launcher-3214-thread-2) [n:127.0.0.1:= 64947_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/li= ve_nodes/127.0.0.1:64947_solr [junit4] 2> 1989427 INFO (zkCallback-3225-thread-1-processing-n:127.0= .0.1:64946_solr) [n:127.0.0.1:64946_solr ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1989427 INFO (zkCallback-3226-thread-1-processing-n:127.0= .0.1:64947_solr) [n:127.0.0.1:64947_solr ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1989543 INFO (jetty-launcher-3214-thread-1) [n:127.0.0.1:= 64946_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64946.so= lr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.= JmxMBeanServer@446fd10c [junit4] 2> 1989543 INFO (jetty-launcher-3214-thread-2) [n:127.0.0.1:= 64947_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64947.so= lr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.= JmxMBeanServer@446fd10c [junit4] 2> 1989544 INFO (jetty-launcher-3214-thread-1) [n:127.0.0.1:= 64946_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64946.so= lr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@446fd10c [junit4] 2> 1989545 INFO (jetty-launcher-3214-thread-1) [n:127.0.0.1:= 64946_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64946.so= lr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserve= r.JmxMBeanServer@446fd10c [junit4] 2> 1989546 INFO (jetty-launcher-3214-thread-1) [n:127.0.0.1:= 64946_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions unde= rneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\sol= r-core\test\J1\temp\solr.cloud.DocValuesNotIndexedTest_723BDDF842FD1BE5-001= \tempDir-001\node1\. [junit4] 2> 1989547 INFO (jetty-launcher-3214-thread-2) [n:127.0.0.1:= 64947_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64947.so= lr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@446fd10c [junit4] 2> 1989548 INFO (jetty-launcher-3214-thread-2) [n:127.0.0.1:= 64947_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64947.so= lr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserve= r.JmxMBeanServer@446fd10c [junit4] 2> 1989549 INFO (jetty-launcher-3214-thread-2) [n:127.0.0.1:= 64947_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions unde= rneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\sol= r-core\test\J1\temp\solr.cloud.DocValuesNotIndexedTest_723BDDF842FD1BE5-001= \tempDir-001\node2\. [junit4] 2> 1989605 INFO (SUITE-DocValuesNotIndexedTest-seed#[723BDDF= 842FD1BE5]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from Z= ooKeeper... (0) -> (2) [junit4] 2> 1989607 INFO (SUITE-DocValuesNotIndexedTest-seed#[723BDDF= 842FD1BE5]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster = at 127.0.0.1:64942/solr ready [junit4] 2> 1989613 INFO (qtp1657275198-20565) [n:127.0.0.1:64946_sol= r ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with = params replicationFactor=3D1&collection.configName=3Dconf1&maxShardsPerNode= =3D2&name=3Ddv_coll&nrtReplicas=3D1&action=3DCREATE&numShards=3D4&wt=3Djava= bin&version=3D2 and sendToOCPQueue=3Dtrue [junit4] 2> 1989615 INFO (OverseerThreadFactory-9551-thread-1-process= ing-n:127.0.0.1:64947_solr) [n:127.0.0.1:64947_solr ] o.a.s.c.CreateColl= ectionCmd Create collection dv_coll [junit4] 2> 1989727 INFO (OverseerStateUpdate-98477725605298181-127.0= .0.1:64947_solr-n_0000000000) [n:127.0.0.1:64947_solr ] o.a.s.c.o.SliceM= utator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"dv_coll", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"dv_coll_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:64946/solr", [junit4] 2> "type":"NRT"}=20 [junit4] 2> 1989733 INFO (OverseerStateUpdate-98477725605298181-127.0= .0.1:64947_solr-n_0000000000) [n:127.0.0.1:64947_solr ] o.a.s.c.o.SliceM= utator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"dv_coll", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"dv_coll_shard2_replica_n2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:64947/solr", [junit4] 2> "type":"NRT"}=20 [junit4] 2> 1989736 INFO (OverseerStateUpdate-98477725605298181-127.0= .0.1:64947_solr-n_0000000000) [n:127.0.0.1:64947_solr ] o.a.s.c.o.SliceM= utator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"dv_coll", [junit4] 2> "shard":"shard3", [junit4] 2> "core":"dv_coll_shard3_replica_n4", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:64946/solr", [junit4] 2> "type":"NRT"}=20 [junit4] 2> 1989740 INFO (OverseerStateUpdate-98477725605298181-127.0= .0.1:64947_solr-n_0000000000) [n:127.0.0.1:64947_solr ] o.a.s.c.o.SliceM= utator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"dv_coll", [junit4] 2> "shard":"shard4", [junit4] 2> "core":"dv_coll_shard4_replica_n6", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:64947/solr", [junit4] 2> "type":"NRT"}=20 [junit4] 2> 1989944 INFO (qtp1657275198-20566) [n:127.0.0.1:64946_sol= r ] o.a.s.h.a.CoreAdminOperation core create command qt=3D/admin/cores&c= oreNodeName=3Dcore_node3&collection.configName=3Dconf1&newCollection=3Dtrue= &name=3Ddv_coll_shard1_replica_n1&action=3DCREATE&numShards=3D4&collection= =3Ddv_coll&shard=3Dshard1&wt=3Djavabin&version=3D2&replicaType=3DNRT [junit4] 2> 1989944 INFO (qtp1379579731-20579) [n:127.0.0.1:64947_sol= r ] o.a.s.h.a.CoreAdminOperation core create command qt=3D/admin/cores&c= oreNodeName=3Dcore_node5&collection.configName=3Dconf1&newCollection=3Dtrue= &name=3Ddv_coll_shard2_replica_n2&action=3DCREATE&numShards=3D4&collection= =3Ddv_coll&shard=3Dshard2&wt=3Djavabin&version=3D2&replicaType=3DNRT [junit4] 2> 1989944 INFO (qtp1657275198-20568) [n:127.0.0.1:64946_sol= r ] o.a.s.h.a.CoreAdminOperation core create command qt=3D/admin/cores&c= oreNodeName=3Dcore_node7&collection.configName=3Dconf1&newCollection=3Dtrue= &name=3Ddv_coll_shard3_replica_n4&action=3DCREATE&numShards=3D4&collection= =3Ddv_coll&shard=3Dshard3&wt=3Djavabin&version=3D2&replicaType=3DNRT [junit4] 2> 1989945 INFO (qtp1657275198-20566) [n:127.0.0.1:64946_sol= r ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for= 2147483647 transient cores [junit4] 2> 1989946 INFO (qtp1379579731-20579) [n:127.0.0.1:64947_sol= r ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for= 2147483647 transient cores [junit4] 2> 1989946 INFO (qtp1379579731-20572) [n:127.0.0.1:64947_sol= r ] o.a.s.h.a.CoreAdminOperation core create command qt=3D/admin/cores&c= oreNodeName=3Dcore_node8&collection.configName=3Dconf1&newCollection=3Dtrue= &name=3Ddv_coll_shard4_replica_n6&action=3DCREATE&numShards=3D4&collection= =3Ddv_coll&shard=3Dshard4&wt=3Djavabin&version=3D2&replicaType=3DNRT [junit4] 2> 1990061 INFO (zkCallback-3226-thread-1-processing-n:127.0= .0.1:64947_solr) [n:127.0.0.1:64947_solr ] o.a.s.c.c.ZkStateReader A clu= ster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged p= ath:/collections/dv_coll/state.json] for collection [dv_coll] has occurred = - updating... (live nodes size: [2]) [junit4] 2> 1990061 INFO (zkCallback-3225-thread-1-processing-n:127.0= .0.1:64946_solr) [n:127.0.0.1:64946_solr ] o.a.s.c.c.ZkStateReader A clu= ster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged p= ath:/collections/dv_coll/state.json] for collection [dv_coll] has occurred = - updating... (live nodes size: [2]) [junit4] 2> 1990061 INFO (zkCallback-3226-thread-2-processing-n:127.0= .0.1:64947_solr) [n:127.0.0.1:64947_solr ] o.a.s.c.c.ZkStateReader A clu= ster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged p= ath:/collections/dv_coll/state.json] for collection [dv_coll] has occurred = - updating... (live nodes size: [2]) [junit4] 2> 1990061 INFO (zkCallback-3225-thread-2-processing-n:127.0= .0.1:64946_solr) [n:127.0.0.1:64946_solr ] o.a.s.c.c.ZkStateReader A clu= ster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged p= ath:/collections/dv_coll/state.json] for collection [dv_coll] has occurred = - updating... (live nodes size: [2]) [junit4] 2> 1991002 INFO (qtp1379579731-20572) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.Solr= Config Using Lucene MatchVersion: 8.0.0 [junit4] 2> 1991003 INFO (qtp1657275198-20566) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.Solr= Config Using Lucene MatchVersion: 8.0.0 [junit4] 2> 1991015 INFO (qtp1379579731-20572) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.s.Inde= xSchema [dv_coll_shard4_replica_n6] Schema name=3Dminimal [junit4] 2> 1991015 INFO (qtp1657275198-20566) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.s.Inde= xSchema [dv_coll_shard1_replica_n1] Schema name=3Dminimal [junit4] 2> 1991042 INFO (qtp1379579731-20572) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.s.Inde= xSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1991042 INFO (qtp1379579731-20572) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.Core= Container Creating SolrCore 'dv_coll_shard4_replica_n6' using configuration= from collection dv_coll, trusted=3Dtrue [junit4] 2> 1991043 INFO (qtp1379579731-20572) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.m.r.So= lrJmxReporter JMX monitoring for 'solr_64947.solr.core.dv_coll.shard4.repli= ca_n6' (registry 'solr.core.dv_coll.shard4.replica_n6') enabled at server: = com.sun.jmx.mbeanserver.JmxMBeanServer@446fd10c [junit4] 2> 1991043 INFO (qtp1379579731-20572) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.Solr= Core solr.RecoveryStrategy.Builder [junit4] 2> 1991044 INFO (qtp1379579731-20572) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.Solr= Core [[dv_coll_shard4_replica_n6] ] Opening new SolrCore at [C:\Users\jenki= ns\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\s= olr.cloud.DocValuesNotIndexedTest_723BDDF842FD1BE5-001\tempDir-001\node2\dv= _coll_shard4_replica_n6], dataDir=3D[C:\Users\jenkins\workspace\Lucene-Solr= -master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIn= dexedTest_723BDDF842FD1BE5-001\tempDir-001\node2\.\dv_coll_shard4_replica_n= 6\data\] [junit4] 2> 1991044 INFO (qtp1657275198-20566) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.s.Inde= xSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1991044 INFO (qtp1657275198-20566) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.Core= Container Creating SolrCore 'dv_coll_shard1_replica_n1' using configuration= from collection dv_coll, trusted=3Dtrue [junit4] 2> 1991045 INFO (qtp1657275198-20566) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.m.r.So= lrJmxReporter JMX monitoring for 'solr_64946.solr.core.dv_coll.shard1.repli= ca_n1' (registry 'solr.core.dv_coll.shard1.replica_n1') enabled at server: = com.sun.jmx.mbeanserver.JmxMBeanServer@446fd10c [junit4] 2> 1991045 INFO (qtp1657275198-20566) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.Solr= Core solr.RecoveryStrategy.Builder [junit4] 2> 1991045 INFO (qtp1657275198-20566) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.Solr= Core [[dv_coll_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenki= ns\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\s= olr.cloud.DocValuesNotIndexedTest_723BDDF842FD1BE5-001\tempDir-001\node1\dv= _coll_shard1_replica_n1], dataDir=3D[C:\Users\jenkins\workspace\Lucene-Solr= -master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIn= dexedTest_723BDDF842FD1BE5-001\tempDir-001\node1\.\dv_coll_shard1_replica_n= 1\data\] [junit4] 2> 1991065 INFO (qtp1657275198-20568) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.Solr= Config Using Lucene MatchVersion: 8.0.0 [junit4] 2> 1991066 INFO (qtp1379579731-20579) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.Solr= Config Using Lucene MatchVersion: 8.0.0 [junit4] 2> 1991078 INFO (qtp1379579731-20579) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.s.Inde= xSchema [dv_coll_shard2_replica_n2] Schema name=3Dminimal [junit4] 2> 1991078 INFO (qtp1657275198-20568) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.Inde= xSchema [dv_coll_shard3_replica_n4] Schema name=3Dminimal [junit4] 2> 1991081 INFO (qtp1657275198-20568) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.Inde= xSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1991081 INFO (qtp1379579731-20579) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.s.Inde= xSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1991081 INFO (qtp1657275198-20568) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.Core= Container Creating SolrCore 'dv_coll_shard3_replica_n4' using configuration= from collection dv_coll, trusted=3Dtrue [junit4] 2> 1991081 INFO (qtp1379579731-20579) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.Core= Container Creating SolrCore 'dv_coll_shard2_replica_n2' using configuration= from collection dv_coll, trusted=3Dtrue [junit4] 2> 1991082 INFO (qtp1379579731-20579) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.m.r.So= lrJmxReporter JMX monitoring for 'solr_64947.solr.core.dv_coll.shard2.repli= ca_n2' (registry 'solr.core.dv_coll.shard2.replica_n2') enabled at server: = com.sun.jmx.mbeanserver.JmxMBeanServer@446fd10c [junit4] 2> 1991082 INFO (qtp1379579731-20579) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.Solr= Core solr.RecoveryStrategy.Builder [junit4] 2> 1991082 INFO (qtp1379579731-20579) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.Solr= Core [[dv_coll_shard2_replica_n2] ] Opening new SolrCore at [C:\Users\jenki= ns\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\s= olr.cloud.DocValuesNotIndexedTest_723BDDF842FD1BE5-001\tempDir-001\node2\dv= _coll_shard2_replica_n2], dataDir=3D[C:\Users\jenkins\workspace\Lucene-Solr= -master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIn= dexedTest_723BDDF842FD1BE5-001\tempDir-001\node2\.\dv_coll_shard2_replica_n= 2\data\] [junit4] 2> 1991091 INFO (qtp1657275198-20568) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.m.r.So= lrJmxReporter JMX monitoring for 'solr_64946.solr.core.dv_coll.shard3.repli= ca_n4' (registry 'solr.core.dv_coll.shard3.replica_n4') enabled at server: = com.sun.jmx.mbeanserver.JmxMBeanServer@446fd10c [junit4] 2> 1991091 INFO (qtp1657275198-20568) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.Solr= Core solr.RecoveryStrategy.Builder [junit4] 2> 1991091 INFO (qtp1657275198-20568) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.Solr= Core [[dv_coll_shard3_replica_n4] ] Opening new SolrCore at [C:\Users\jenki= ns\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\s= olr.cloud.DocValuesNotIndexedTest_723BDDF842FD1BE5-001\tempDir-001\node1\dv= _coll_shard3_replica_n4], dataDir=3D[C:\Users\jenkins\workspace\Lucene-Solr= -master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIn= dexedTest_723BDDF842FD1BE5-001\tempDir-001\node1\.\dv_coll_shard3_replica_n= 4\data\] [junit4] 2> 1991275 INFO (qtp1379579731-20572) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.u.Upda= teHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1991275 INFO (qtp1379579731-20572) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.u.Upda= teLog Initializing UpdateLog: dataDir=3Dnull defaultSyncLevel=3DFLUSH numRe= cordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 1991279 INFO (qtp1657275198-20568) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.Upda= teHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1991279 INFO (qtp1657275198-20568) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.Upda= teLog Initializing UpdateLog: dataDir=3Dnull defaultSyncLevel=3DFLUSH numRe= cordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 1991281 INFO (qtp1379579731-20572) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.u.Comm= itTracker Hard AutoCommit: disabled [junit4] 2> 1991281 INFO (qtp1379579731-20572) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.u.Comm= itTracker Soft AutoCommit: disabled [junit4] 2> 1991282 INFO (qtp1657275198-20568) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.Comm= itTracker Hard AutoCommit: disabled [junit4] 2> 1991282 INFO (qtp1657275198-20568) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.Comm= itTracker Soft AutoCommit: disabled [junit4] 2> 1991363 INFO (qtp1657275198-20566) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.u.Upda= teHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1991363 INFO (qtp1657275198-20566) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.u.Upda= teLog Initializing UpdateLog: dataDir=3Dnull defaultSyncLevel=3DFLUSH numRe= cordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 1991365 INFO (qtp1379579731-20579) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.u.Upda= teHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1991365 INFO (qtp1657275198-20566) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.u.Comm= itTracker Hard AutoCommit: disabled [junit4] 2> 1991365 INFO (qtp1379579731-20579) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.u.Upda= teLog Initializing UpdateLog: dataDir=3Dnull defaultSyncLevel=3DFLUSH numRe= cordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 1991365 INFO (qtp1657275198-20566) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.u.Comm= itTracker Soft AutoCommit: disabled [junit4] 2> 1991367 INFO (qtp1379579731-20579) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.u.Comm= itTracker Hard AutoCommit: disabled [junit4] 2> 1991367 INFO (qtp1379579731-20579) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.u.Comm= itTracker Soft AutoCommit: disabled [junit4] 2> 1991369 INFO (qtp1379579731-20572) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.s.Solr= IndexSearcher Opening [Searcher@7440e658[dv_coll_shard4_replica_n6] main] [junit4] 2> 1991370 INFO (qtp1379579731-20572) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.r.Mana= gedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/c= onf1 [junit4] 2> 1991371 INFO (qtp1379579731-20572) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.r.Mana= gedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperSt= orageIO:path=3D/configs/conf1 [junit4] 2> 1991371 INFO (qtp1379579731-20572) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.s.ZkIn= dexSchemaReader Creating ZooKeeper watch for the managed schema at /configs= /conf1/managed-schema [junit4] 2> 1991371 INFO (qtp1657275198-20568) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.Solr= IndexSearcher Opening [Searcher@7bb39830[dv_coll_shard3_replica_n4] main] [junit4] 2> 1991371 INFO (qtp1379579731-20572) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.s.ZkIn= dexSchemaReader Current schema version 0 is already the latest [junit4] 2> 1991373 INFO (qtp1379579731-20572) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.h.Repl= icationHandler Commits will be reserved for 10000 [junit4] 2> 1991373 INFO (qtp1379579731-20572) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.u.Upda= teLog Could not find max version in index or recent updates, using new cloc= k 1575643612041969664 [junit4] 2> 1991374 INFO (searcherExecutor-9556-thread-1-processing-n= :127.0.0.1:64947_solr x:dv_coll_shard4_replica_n6 s:shard4 c:dv_coll r:core= _node8) [n:127.0.0.1:64947_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_s= hard4_replica_n6] o.a.s.c.SolrCore [dv_coll_shard4_replica_n6] Registered n= ew searcher Searcher@7440e658[dv_coll_shard4_replica_n6] main{ExitableDirec= toryReader(UninvertingDirectoryReader())} [junit4] 2> 1991375 INFO (qtp1657275198-20568) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.r.Mana= gedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/c= onf1 [junit4] 2> 1991375 INFO (qtp1657275198-20568) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.r.Mana= gedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperSt= orageIO:path=3D/configs/conf1 [junit4] 2> 1991375 INFO (qtp1657275198-20568) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.ZkIn= dexSchemaReader Creating ZooKeeper watch for the managed schema at /configs= /conf1/managed-schema [junit4] 2> 1991376 INFO (qtp1657275198-20568) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.ZkIn= dexSchemaReader Current schema version 0 is already the latest [junit4] 2> 1991376 INFO (qtp1657275198-20566) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.s.Solr= IndexSearcher Opening [Searcher@4598242c[dv_coll_shard1_replica_n1] main] [junit4] 2> 1991376 INFO (qtp1657275198-20568) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.h.Repl= icationHandler Commits will be reserved for 10000 [junit4] 2> 1991377 INFO (searcherExecutor-9558-thread-1-processing-n= :127.0.0.1:64946_solr x:dv_coll_shard3_replica_n4 s:shard3 c:dv_coll r:core= _node7) [n:127.0.0.1:64946_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_s= hard3_replica_n4] o.a.s.c.SolrCore [dv_coll_shard3_replica_n4] Registered n= ew searcher Searcher@7bb39830[dv_coll_shard3_replica_n4] main{ExitableDirec= toryReader(UninvertingDirectoryReader())} [junit4] 2> 1991378 INFO (qtp1657275198-20568) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.Upda= teLog Could not find max version in index or recent updates, using new cloc= k 1575643612047212544 [junit4] 2> 1991382 INFO (qtp1379579731-20579) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.s.Solr= IndexSearcher Opening [Searcher@75ff59d4[dv_coll_shard2_replica_n2] main] [junit4] 2> 1991382 INFO (qtp1379579731-20572) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.Shar= dLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1991382 INFO (qtp1379579731-20572) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.Shar= dLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1991382 INFO (qtp1379579731-20572) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.Sync= Strategy Sync replicas to http://127.0.0.1:64947/solr/dv_coll_shard4_replic= a_n6/ [junit4] 2> 1991382 INFO (qtp1379579731-20572) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.Sync= Strategy Sync Success - now sync replicas to me [junit4] 2> 1991383 INFO (qtp1379579731-20572) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.Sync= Strategy http://127.0.0.1:64947/solr/dv_coll_shard4_replica_n6/ has no repl= icas [junit4] 2> 1991383 INFO (qtp1379579731-20572) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.Shar= dLeaderElectionContext Found all replicas participating in election, clear = LIR [junit4] 2> 1991383 INFO (qtp1657275198-20566) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.r.Mana= gedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/c= onf1 [junit4] 2> 1991383 INFO (qtp1379579731-20579) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.r.Mana= gedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/c= onf1 [junit4] 2> 1991384 INFO (qtp1657275198-20566) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.r.Mana= gedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperSt= orageIO:path=3D/configs/conf1 [junit4] 2> 1991384 INFO (qtp1657275198-20566) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.s.ZkIn= dexSchemaReader Creating ZooKeeper watch for the managed schema at /configs= /conf1/managed-schema [junit4] 2> 1991384 INFO (qtp1379579731-20579) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.r.Mana= gedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperSt= orageIO:path=3D/configs/conf1 [junit4] 2> 1991384 INFO (qtp1379579731-20579) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.s.ZkIn= dexSchemaReader Creating ZooKeeper watch for the managed schema at /configs= /conf1/managed-schema [junit4] 2> 1991385 INFO (qtp1657275198-20566) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.s.ZkIn= dexSchemaReader Current schema version 0 is already the latest [junit4] 2> 1991386 INFO (qtp1379579731-20579) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.s.ZkIn= dexSchemaReader Current schema version 0 is already the latest [junit4] 2> 1991386 INFO (qtp1657275198-20566) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.h.Repl= icationHandler Commits will be reserved for 10000 [junit4] 2> 1991386 INFO (qtp1657275198-20566) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.u.Upda= teLog Could not find max version in index or recent updates, using new cloc= k 1575643612055601152 [junit4] 2> 1991386 INFO (qtp1379579731-20579) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.h.Repl= icationHandler Commits will be reserved for 10000 [junit4] 2> 1991387 INFO (qtp1379579731-20579) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.u.Upda= teLog Could not find max version in index or recent updates, using new cloc= k 1575643612056649728 [junit4] 2> 1991387 INFO (searcherExecutor-9557-thread-1-processing-n= :127.0.0.1:64946_solr x:dv_coll_shard1_replica_n1 s:shard1 c:dv_coll r:core= _node3) [n:127.0.0.1:64946_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_s= hard1_replica_n1] o.a.s.c.SolrCore [dv_coll_shard1_replica_n1] Registered n= ew searcher Searcher@4598242c[dv_coll_shard1_replica_n1] main{ExitableDirec= toryReader(UninvertingDirectoryReader())} [junit4] 2> 1991389 INFO (searcherExecutor-9559-thread-1-processing-n= :127.0.0.1:64947_solr x:dv_coll_shard2_replica_n2 s:shard2 c:dv_coll r:core= _node5) [n:127.0.0.1:64947_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_s= hard2_replica_n2] o.a.s.c.SolrCore [dv_coll_shard2_replica_n2] Registered n= ew searcher Searcher@75ff59d4[dv_coll_shard2_replica_n2] main{ExitableDirec= toryReader(UninvertingDirectoryReader())} [junit4] 2> 1991393 INFO (qtp1657275198-20568) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.Shar= dLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1991393 INFO (qtp1657275198-20568) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.Shar= dLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1991393 INFO (qtp1657275198-20568) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.Sync= Strategy Sync replicas to http://127.0.0.1:64946/solr/dv_coll_shard3_replic= a_n4/ [junit4] 2> 1991393 INFO (qtp1657275198-20568) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.Sync= Strategy Sync Success - now sync replicas to me [junit4] 2> 1991393 INFO (qtp1657275198-20568) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.Sync= Strategy http://127.0.0.1:64946/solr/dv_coll_shard3_replica_n4/ has no repl= icas [junit4] 2> 1991393 INFO (qtp1657275198-20568) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.Shar= dLeaderElectionContext Found all replicas participating in election, clear = LIR [junit4] 2> 1991396 INFO (qtp1379579731-20572) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.Shar= dLeaderElectionContext I am the new leader: http://127.0.0.1:64947/solr/dv_= coll_shard4_replica_n6/ shard4 [junit4] 2> 1991402 INFO (qtp1379579731-20579) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.Shar= dLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1991402 INFO (qtp1379579731-20579) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.Shar= dLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1991402 INFO (qtp1379579731-20579) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.Sync= Strategy Sync replicas to http://127.0.0.1:64947/solr/dv_coll_shard2_replic= a_n2/ [junit4] 2> 1991402 INFO (qtp1657275198-20566) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.Shar= dLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1991402 INFO (qtp1379579731-20579) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.Sync= Strategy Sync Success - now sync replicas to me [junit4] 2> 1991402 INFO (qtp1657275198-20566) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.Shar= dLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1991403 INFO (qtp1657275198-20566) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.Sync= Strategy Sync replicas to http://127.0.0.1:64946/solr/dv_coll_shard1_replic= a_n1/ [junit4] 2> 1991403 INFO (qtp1657275198-20566) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.Sync= Strategy Sync Success - now sync replicas to me [junit4] 2> 1991403 INFO (qtp1657275198-20566) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.Sync= Strategy http://127.0.0.1:64946/solr/dv_coll_shard1_replica_n1/ has no repl= icas [junit4] 2> 1991403 INFO (qtp1657275198-20566) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.Shar= dLeaderElectionContext Found all replicas participating in election, clear = LIR [junit4] 2> 1991402 INFO (qtp1379579731-20579) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.Sync= Strategy http://127.0.0.1:64947/solr/dv_coll_shard2_replica_n2/ has no repl= icas [junit4] 2> 1991403 INFO (qtp1379579731-20579) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.Shar= dLeaderElectionContext Found all replicas participating in election, clear = LIR [junit4] 2> 1991408 INFO (qtp1657275198-20568) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.Shar= dLeaderElectionContext I am the new leader: http://127.0.0.1:64946/solr/dv_= coll_shard3_replica_n4/ shard3 [junit4] 2> 1991411 INFO (qtp1657275198-20566) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.Shar= dLeaderElectionContext I am the new leader: http://127.0.0.1:64946/solr/dv_= coll_shard1_replica_n1/ shard1 [junit4] 2> 1991411 INFO (qtp1379579731-20579) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.Shar= dLeaderElectionContext I am the new leader: http://127.0.0.1:64947/solr/dv_= coll_shard2_replica_n2/ shard2 [junit4] 2> 1991519 INFO (zkCallback-3226-thread-2-processing-n:127.0= .0.1:64947_solr) [n:127.0.0.1:64947_solr ] o.a.s.c.c.ZkStateReader A clu= ster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged p= ath:/collections/dv_coll/state.json] for collection [dv_coll] has occurred = - updating... (live nodes size: [2]) [junit4] 2> 1991519 INFO (zkCallback-3226-thread-1-processing-n:127.0= .0.1:64947_solr) [n:127.0.0.1:64947_solr ] o.a.s.c.c.ZkStateReader A clu= ster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged p= ath:/collections/dv_coll/state.json] for collection [dv_coll] has occurred = - updating... (live nodes size: [2]) [junit4] 2> 1991519 INFO (zkCallback-3225-thread-1-processing-n:127.0= .0.1:64946_solr) [n:127.0.0.1:64946_solr ] o.a.s.c.c.ZkStateReader A clu= ster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged p= ath:/collections/dv_coll/state.json] for collection [dv_coll] has occurred = - updating... (live nodes size: [2]) [junit4] 2> 1991519 INFO (zkCallback-3225-thread-2-processing-n:127.0= .0.1:64946_solr) [n:127.0.0.1:64946_solr ] o.a.s.c.c.ZkStateReader A clu= ster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged p= ath:/collections/dv_coll/state.json] for collection [dv_coll] has occurred = - updating... (live nodes size: [2]) [junit4] 2> 1991550 INFO (qtp1379579731-20572) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.ZkCo= ntroller I am the leader, no recovery necessary [junit4] 2> 1991552 INFO (qtp1379579731-20572) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.s.Http= SolrCall [admin] webapp=3Dnull path=3D/admin/cores params=3D{qt=3D/admin/co= res&coreNodeName=3Dcore_node8&collection.configName=3Dconf1&newCollection= =3Dtrue&name=3Ddv_coll_shard4_replica_n6&action=3DCREATE&numShards=3D4&coll= ection=3Ddv_coll&shard=3Dshard4&wt=3Djavabin&version=3D2&replicaType=3DNRT}= status=3D0 QTime=3D1604 [junit4] 2> 1991561 INFO (qtp1657275198-20568) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.ZkCo= ntroller I am the leader, no recovery necessary [junit4] 2> 1991562 INFO (qtp1657275198-20566) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.ZkCo= ntroller I am the leader, no recovery necessary [junit4] 2> 1991563 INFO (qtp1657275198-20568) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.Http= SolrCall [admin] webapp=3Dnull path=3D/admin/cores params=3D{qt=3D/admin/co= res&coreNodeName=3Dcore_node7&collection.configName=3Dconf1&newCollection= =3Dtrue&name=3Ddv_coll_shard3_replica_n4&action=3DCREATE&numShards=3D4&coll= ection=3Ddv_coll&shard=3Dshard3&wt=3Djavabin&version=3D2&replicaType=3DNRT}= status=3D0 QTime=3D1619 [junit4] 2> 1991565 INFO (qtp1379579731-20579) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.ZkCo= ntroller I am the leader, no recovery necessary [junit4] 2> 1991565 INFO (qtp1657275198-20566) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.s.Http= SolrCall [admin] webapp=3Dnull path=3D/admin/cores params=3D{qt=3D/admin/co= res&coreNodeName=3Dcore_node3&collection.configName=3Dconf1&newCollection= =3Dtrue&name=3Ddv_coll_shard1_replica_n1&action=3DCREATE&numShards=3D4&coll= ection=3Ddv_coll&shard=3Dshard1&wt=3Djavabin&version=3D2&replicaType=3DNRT}= status=3D0 QTime=3D1620 [junit4] 2> 1991565 INFO (qtp1379579731-20579) [n:127.0.0.1:64947_sol= r c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.s.Http= SolrCall [admin] webapp=3Dnull path=3D/admin/cores params=3D{qt=3D/admin/co= res&coreNodeName=3Dcore_node5&collection.configName=3Dconf1&newCollection= =3Dtrue&name=3Ddv_coll_shard2_replica_n2&action=3DCREATE&numShards=3D4&coll= ection=3Ddv_coll&shard=3Dshard2&wt=3Djavabin&version=3D2&replicaType=3DNRT}= status=3D0 QTime=3D1622 [junit4] 2> 1991570 INFO (qtp1657275198-20565) [n:127.0.0.1:64946_sol= r ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active fo= r at most 30 seconds. Check all shard replicas [junit4] 2> 1991620 INFO (OverseerCollectionConfigSetProcessor-984777= 25605298181-127.0.0.1:64947_solr-n_0000000000) [n:127.0.0.1:64947_solr ]= o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-wor= k/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKe= eper [junit4] 2> 1991699 INFO (zkCallback-3226-thread-1-processing-n:127.0= .0.1:64947_solr) [n:127.0.0.1:64947_solr ] o.a.s.c.c.ZkStateReader A clu= ster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged p= ath:/collections/dv_coll/state.json] for collection [dv_coll] has occurred = - updating... (live nodes size: [2]) [junit4] 2> 1991699 INFO (zkCallback-3226-thread-2-processing-n:127.0= .0.1:64947_solr) [n:127.0.0.1:64947_solr ] o.a.s.c.c.ZkStateReader A clu= ster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged p= ath:/collections/dv_coll/state.json] for collection [dv_coll] has occurred = - updating... (live nodes size: [2]) [junit4] 2> 1991699 INFO (zkCallback-3225-thread-2-processing-n:127.0= .0.1:64946_solr) [n:127.0.0.1:64946_solr ] o.a.s.c.c.ZkStateReader A clu= ster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged p= ath:/collections/dv_coll/state.json] for collection [dv_coll] has occurred = - updating... (live nodes size: [2]) [junit4] 2> 1991699 INFO (zkCallback-3225-thread-1-processing-n:127.0= .0.1:64946_solr) [n:127.0.0.1:64946_solr ] o.a.s.c.c.ZkStateReader A clu= ster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged p= ath:/collections/dv_coll/state.json] for collection [dv_coll] has occurred = - updating... (live nodes size: [2]) [junit4] 2> 1992575 INFO (qtp1657275198-20565) [n:127.0.0.1:64946_sol= r ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections= params=3D{replicationFactor=3D1&collection.configName=3Dconf1&maxShardsPer= Node=3D2&name=3Ddv_coll&nrtReplicas=3D1&action=3DCREATE&numShards=3D4&wt=3D= javabin&version=3D2} status=3D0 QTime=3D2961 [junit4] 2> 1992587 INFO (qtp1657275198-20569) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.s.Sche= maManager managed schema loaded . version : 0=20 [junit4] 2> 1992602 INFO (qtp1657275198-20569) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.s.Inde= xSchema [dv_coll_shard1_replica_n1] Schema name=3Dminimal [junit4] 2> 1992616 INFO (qtp1657275198-20569) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.s.Inde= xSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1992652 INFO (qtp1657275198-20569) [n:127.0.0.1:64946_sol= r c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.ZkCo= ntroller Persisted config data to node /configs/conf1/managed-schema=20 [junit4] 2> 1992653 INFO (zkCallback-3226-thread-2-processing-n:127.0= .0.1:64947_solr) [n:127.0.0.1:64947_solr ] o.a.s.s.ZkIndexSchemaReader A= schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:= /configs/conf1/managed-schema, has occurred - updating schema from ZooKeepe= r ... [junit4] 2> 1992653 INFO (zkCallback-3226-thread-1-processing-n:127.0= .0.1:64947_solr) [n:127.0.0.1:64947_solr ] o.a.s.s.ZkIndexSchemaReader A= schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:= /configs/conf1/managed-schema, has occurred - updating schema from ZooKeepe= r ... [junit4] 2> 1992653 INFO (zkCallback-3225-thread-1-processing-n:127.0= .0.1:64946_solr) [n:127.0.0.1:64946_solr ] o.a.s.s.ZkIndexSchemaReader A= schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:= /configs/conf1/managed-schema, has occurred - updating schema from ZooKeepe= r ... [junit4] 2> 1992653 INFO (zkCallback-3225-thread-2-processing-n:127.0= .0.1:64946_solr) [n:127.0.0.1:64946_solr ] o.a.s.s.ZkIndexSchemaReader A= schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:= /configs/conf1/managed-schema, has occurred - updating schema from ZooKeepe= r ... [junit4] 2> 1992657 INFO (zkCallback-3226-thread-2-processing-n:127.0= .0.1:64947_solr) [n:127.0.0.1:64947_solr ] o.a.s.s.ZkIndexSchemaReader R= etrieved schema version 1 from ZooKeeper [junit4] 2> 1992658 INFO (zkCallback-3226-thread-1-processing-n:127.0= .0.1:64947_solr) [n:127.0.0.1:64947_solr ] o.a.s.s.ZkIndexSchemaReader R= etrieved schema version 1 from ZooKeeper [junit4] 2> 1992661 INFO (zkCallback-3226-thread-2-processing-n:127.0= .0.1:64947_solr) [n:127.0.0.1:64947_s [...truncated too long message...] MetricManager Closing metric reporters for registry=3Dsolr.collection.dv_co= ll.shard1.leader, tag=3D116345878 [junit4] 2> 1993995 INFO (jetty-closer-3215-thread-1) [ ] o.e.j.s.= h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2a087f8e{/solr,null,= UNAVAILABLE} [junit4] 2> 1993996 ERROR (SUITE-DocValuesNotIndexedTest-seed#[723BDDF= 842FD1BE5]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not = registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN = server state changes [junit4] 2> 1993997 INFO (SUITE-DocValuesNotIndexedTest-seed#[723BDDF= 842FD1BE5]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:6494= 2 64942 [junit4] 2> 1993998 INFO (Thread-5329) [ ] o.a.s.c.ZkTestServer co= nnecting to 127.0.0.1:64942 64942 [junit4] 2> 1993999 WARN (Thread-5329) [ ] o.a.s.c.ZkTestServer Wa= tch limit violations:=20 [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2>=20 [junit4] 2> =096=09/solr/configs/conf1/managed-schema [junit4] 2> =094=09/solr/configs/conf1 [junit4] 2> =093=09/solr/aliases.json [junit4] 2> =093=09/solr/clusterprops.json [junit4] 2> =092=09/solr/security.json [junit4] 2>=20 [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2>=20 [junit4] 2> =0912=09/solr/collections/dv_coll/state.json [junit4] 2> =093=09/solr/clusterstate.json [junit4] 2> =092=09/solr/configs/conf1/managed-schema [junit4] 2>=20 [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2>=20 [junit4] 2> =093=09/solr/live_nodes [junit4] 2> =093=09/solr/collections [junit4] 2>=20 [junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins= \workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\sol= r.cloud.DocValuesNotIndexedTest_723BDDF842FD1BE5-001 [junit4] 2> Aug 13, 2017 7:00:47 PM com.carrotsearch.randomizedtesting= .ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 3 leaked thre= ad(s). [junit4] 2> NOTE: test params are: codec=3DAsserting(Lucene70): {_vers= ion_=3DPostingsFormat(name=3DLuceneVarGapFixedInterval), id=3DPostingsForma= t(name=3DMemory)}, docValues:{floatField=3DDocValuesFormat(name=3DLucene70)= , doubleField=3DDocValuesFormat(name=3DMemory), intFieldMulti=3DDocValuesFo= rmat(name=3DLucene70), boolFieldMulti=3DDocValuesFormat(name=3DMemory), lon= gFieldMulti=3DDocValuesFormat(name=3DLucene70), floatGSL=3DDocValuesFormat(= name=3DAsserting), intField=3DDocValuesFormat(name=3DMemory), boolField=3DD= ocValuesFormat(name=3DAsserting), stringFieldMulti=3DDocValuesFormat(name= =3DAsserting), dateFieldMulti=3DDocValuesFormat(name=3DLucene70), doubleFie= ldMulti=3DDocValuesFormat(name=3DAsserting), longGSF=3DDocValuesFormat(name= =3DLucene70), stringGSL=3DDocValuesFormat(name=3DLucene70), doubleGSF=3DDoc= ValuesFormat(name=3DMemory), doubleGSL=3DDocValuesFormat(name=3DLucene70), = stringGSF=3DDocValuesFormat(name=3DMemory), intGSL=3DDocValuesFormat(name= =3DMemory), longField=3DDocValuesFormat(name=3DLucene70), dateField=3DDocVa= luesFormat(name=3DAsserting), dateGSF=3DDocValuesFormat(name=3DAsserting), = boolGSF=3DDocValuesFormat(name=3DAsserting), longGSL=3DDocValuesFormat(name= =3DAsserting), boolGSL=3DDocValuesFormat(name=3DLucene70), floatFieldMulti= =3DDocValuesFormat(name=3DMemory), floatGSF=3DDocValuesFormat(name=3DLucene= 70), dateGSL=3DDocValuesFormat(name=3DLucene70), intGSF=3DDocValuesFormat(n= ame=3DLucene70), stringField=3DDocValuesFormat(name=3DMemory)}, maxPointsIn= LeafNode=3D531, maxMBSortInHeap=3D5.3471448074457095, sim=3DRandomSimilarit= y(queryNorm=3Dtrue): {}, locale=3Dbg, timezone=3DAsia/Jerusalem [junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_144 (= 64-bit)/cpus=3D3,threads=3D1,free=3D253749512,total=3D491409408 [junit4] 2> NOTE: All tests run in this JVM: [SimpleMLTQParserTest, Hd= fsUnloadDistributedZkTest, TestShortCircuitedRequests, TestDistributedMissi= ngSort, SolrCloudReportersTest, ConjunctionSolrSpellCheckerTest, TestMultiW= ordSynonyms, OpenCloseCoreStressTest, TestReloadAndDeleteDocs, V2ApiIntegra= tionTest, TestExclusionRuleCollectionAccess, ConvertedLegacyTest, SolrIndex= ConfigTest, QueryEqualityTest, TestSimpleQParserPlugin, ReplicationFactorTe= st, TestInitParams, BasicZkTest, StressHdfsTest, MoreLikeThisHandlerTest, U= pdateParamsTest, BigEndianAscendingWordDeserializerTest, OpenExchangeRatesO= rgProviderTest, TestExpandComponent, SpatialRPTFieldTypeTest, SuggesterTSTT= est, BasicDistributedZk2Test, DistributedIntervalFacetingTest, TestSolrClou= dWithHadoopAuthPlugin, MergeStrategyTest, SaslZkACLProviderTest, SpatialHea= tmapFacetsTest, AddSchemaFieldsUpdateProcessorFactoryTest, OverseerStatusTe= st, TestCollectionAPIs, ChaosMonkeySafeLeaderTest, TestOnReconnectListenerS= upport, TestRecovery, TestObjectReleaseTracker, ReplicaListTransformerTest,= TestRuleBasedAuthorizationPlugin, TestAnalyzeInfixSuggestions, TestRawTran= sformer, TestSolrFieldCacheBean, TestFiltering, PreAnalyzedFieldManagedSche= maCloudTest, StatsComponentTest, TriLevelCompositeIdRoutingTest, SolrCloudE= xampleTest, ChaosMonkeySafeLeaderWithPullReplicasTest, HLLUtilTest, FacetPi= votSmallTest, HttpPartitionTest, TestMaxScoreQueryParser, TestCollectionsAP= IViaSolrCloudCluster, CoreMergeIndexesAdminHandlerTest, UpdateRequestProces= sorFactoryTest, ShardRoutingCustomTest, TestCoreDiscovery, TestManagedSchem= aThreadSafety, ActionThrottleTest, DistributedFacetPivotWhiteBoxTest, Leade= rFailoverAfterPartitionTest, TestInitQParser, TestDFISimilarityFactory, Dis= tributedQueryElevationComponentTest, TestCloudManagedSchema, JSONWriterTest= , TestRemoteStreaming, CoreAdminCreateDiscoverTest, TestScoreJoinQPNoScore,= TestSchemaVersionResource, TestSolrCLIRunExample, DocumentBuilderTest, Bad= CopyFieldTest, CursorMarkTest, SystemInfoHandlerTest, TestExceedMaxTermLeng= th, SuggestComponentTest, TolerantUpdateProcessorTest, TestManagedResource,= SortSpecParsingTest, TestSolrXml, DateRangeFieldTest, TestMergePolicyConfi= g, TestPerFieldSimilarityWithDefaultOverride, TestPerFieldSimilarity, TestC= ollationFieldDocValues, TestUseDocValuesAsStored, DirectSolrSpellCheckerTes= t, NodeMutatorTest, CollectionsAPISolrJTest, TestLMJelinekMercerSimilarityF= actory, TestRebalanceLeaders, TestHighFrequencyDictionaryFactory, TestManag= edSynonymFilterFactory, BitVectorTest, TestLeaderInitiatedRecoveryThread, T= estReloadDeadlock, TestTolerantUpdateProcessorRandomCloud, TestXmlQParser, = SolrGangliaReporterTest, TestChildDocTransformer, SuggesterFSTTest, TestDel= egationWithHadoopAuth, TestFieldSortValues, TestAuthenticationFramework, Bl= ockDirectoryTest, TestSolrConfigHandlerConcurrent, SchemaVersionSpecificBeh= aviorTest, CleanupOldIndexTest, FileBasedSpellCheckerTest, TestManagedSchem= a, TestSystemIdResolver, TestCollationField, TestSubQueryTransformerCrossCo= re, TestFastLRUCache, TestFieldCacheReopen, TestCopyFieldCollectionResource= , TestNumericTokenStream, CdcrBootstrapTest, TestIntervalFaceting, TestSche= maManager, PluginInfoTest, TestBackupRepositoryFactory, CacheHeaderTest, Te= stPartialUpdateDeduplication, PingRequestHandlerTest, TestSSLRandomization,= TestCoreAdminApis, TestConfigReload, DistributedTermsComponentTest, Distri= bCursorPagingTest, TestCloudPivotFacet, TestReRankQParserPlugin, Collection= TooManyReplicasTest, HdfsDirectoryFactoryTest, TestWordDelimiterFilterFacto= ry, LeaderInitiatedRecoveryOnCommitTest, DistribJoinFromCollectionTest, Ove= rseerModifyCollectionTest, DistributedFacetPivotLargeTest, CopyFieldTest, C= dcrVersionReplicationTest, RollingRestartTest, CdcrReplicationHandlerTest, = TestCSVLoader, TestConfigSets, MetricsHandlerTest, TemplateUpdateProcessorT= est, TestPushWriter, TestSolr4Spatial2, MBeansHandlerTest, TestConfigSetsAP= IExclusivity, TestZkChroot, TestLRUStatsCache, TestConfigSetImmutable, Test= StressInPlaceUpdates, DeleteShardTest, TestRandomCollapseQParserPlugin, Tes= tJsonRequest, TestUniqueKeyFieldResource, LukeRequestHandlerTest, BasicFunc= tionalityTest, TestFieldTypeResource, TestOverriddenPrefixQueryForCustomFie= ldType, StatsReloadRaceTest, TestDocSet, TestCustomStream, TestSQLHandlerNo= nCloud, TestSolrJ, DistributedMLTComponentTest, TestSolr4Spatial, TestFileD= ictionaryLookup, EchoParamsTest, TestReplicationHandlerBackup, TestGraphTer= msQParserPlugin, JvmMetricsTest, DOMUtilTest, SpellCheckCollatorTest, TestA= uthorizationFramework, MetricUtilsTest, UnloadDistributedZkTest, LeaderElec= tionIntegrationTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest, ZkSolrCl= ientTest, AutoCommitTest, TestFaceting, TermVectorComponentDistributedTest,= TestJoin, TestStressVersions, TestRangeQuery, TestGroupingSearch, PeerSync= Test, TestBadConfig, TestIndexSearcher, SignatureUpdateProcessorFactoryTest= , SolrRequestParserTest, TestStressLucene, TestTrie, WordBreakSolrSpellChec= kerTest, FieldMutatingUpdateProcessorTest, StatelessScriptUpdateProcessorFa= ctoryTest, TestSolrDeletionPolicy1, XsltUpdateRequestHandlerTest, IndexBase= dSpellCheckerTest, TestWriterPerf, TestOmitPositions, TermsComponentTest, R= equestHandlersTest, TermVectorComponentTest, RequiredFieldsTest, TestSolrQu= eryParser, LoggingHandlerTest, SolrPluginUtilsTest, ReturnFieldsTest, JsonL= oaderTest, SearchHandlerTest, TestSearchPerf, TestElisionMultitermQuery, Ou= tputWriterTest, TestPostingsSolrHighlighter, TestLuceneMatchVersion, SpellP= ossibilityIteratorTest, TestCharFilters, TestCodecSupport, TestDFRSimilarit= yFactory, TestIBSimilarityFactory, TestFastWriter, TestFastOutputStream, Pr= eAnalyzedFieldTest, DateFieldTest, TestUtils, ZkNodePropsTest, FileUtilsTes= t, SolrTestCaseJ4Test, TestHighlightDedupGrouping, TestEmbeddedSolrServerCo= nstructors, TestEmbeddedSolrServerSchemaAPI, TestJettySolrRunner, Connectio= nReuseTest, AsyncCallRequestStatusResponseTest, CdcrRequestHandlerTest, Clo= udExitableDirectoryReaderTest, CollectionReloadTest, CollectionStateFormat2= Test, CollectionsAPIAsyncDistributedZkTest, DeleteInactiveReplicaTest, Dele= teLastCustomShardedReplicaTest, DeleteNodeTest, DistribDocExpirationUpdateP= rocessorTest, DocValuesNotIndexedTest] [junit4] Completed [521/729 (2!)] on J1 in 5.92s, 4 tests, 1 failure <<<= FAILURES! [...truncated 44003 lines...] ------=_Part_138_216578160.1502653741813 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_138_216578160.1502653741813--