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 07804200D20 for ; Tue, 17 Oct 2017 18:12:52 +0200 (CEST) Received: by cust-asf.ponee.io (Postfix) id 059701609DE; Tue, 17 Oct 2017 16:12:52 +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 545C41609D7 for ; Tue, 17 Oct 2017 18:12:49 +0200 (CEST) Received: (qmail 1385 invoked by uid 500); 17 Oct 2017 16:12:48 -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 1375 invoked by uid 99); 17 Oct 2017 16:12:47 -0000 Received: from pnap-us-west-generic-nat.apache.org (HELO spamd2-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Tue, 17 Oct 2017 16:12:47 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd2-us-west.apache.org (ASF Mail Server at spamd2-us-west.apache.org) with ESMTP id D9B2C1A23E8 for ; Tue, 17 Oct 2017 16:12:46 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd2-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: -0.05 X-Spam-Level: X-Spam-Status: No, score=-0.05 tagged_above=-999 required=6.31 tests=[KAM_BADIPHTTP=2, KAM_LOTSOFHASH=0.25, 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 (spamd2-us-west.apache.org [10.40.0.9]) (amavisd-new, port 10024) with ESMTP id UX4Itytbzhpf for ; Tue, 17 Oct 2017 16:12:30 +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 5ADEE61103 for ; Tue, 17 Oct 2017 16:12:29 +0000 (UTC) Received: from serv1 (ip6-localhost [IPv6:::1]) by serv1.sd-datasolutions.de (Postfix) with ESMTP id A393910800E4 for ; Tue, 17 Oct 2017 16:12:28 +0000 (UTC) Date: Tue, 17 Oct 2017 16:11:01 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org Message-ID: <805745976.121.1508256748670.JavaMail.jenkins@serv1.sd-datasolutions.de> In-Reply-To: <285397792.109.1508234827766.JavaMail.jenkins@serv1.sd-datasolutions.de> References: <285397792.109.1508234827766.JavaMail.jenkins@serv1.sd-datasolutions.de> Subject: [JENKINS] Lucene-Solr-6.6-Linux (32bit/jdk1.8.0_144) - Build # 172 - Still Unstable! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_120_1282455359.1508256748632" X-Jenkins-Job: Lucene-Solr-6.6-Linux X-Jenkins-Result: UNSTABLE archived-at: Tue, 17 Oct 2017 16:12:52 -0000 ------=_Part_120_1282455359.1508256748632 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.6-Linux/172/ Java: 32bit/jdk1.8.0_144 -client -XX:+UseConcMarkSweepGC 2 tests failed. FAILED: org.apache.solr.update.AutoCommitTest.testMaxDocs Error Message: Exception during query Stack Trace: java.lang.RuntimeException: Exception during query =09at __randomizedtesting.SeedInfo.seed([E7BF4C35D486CF13:5E3E9AEAF86CCB99]= :0) =09at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:895) =09at org.apache.solr.update.AutoCommitTest.testMaxDocs(AutoCommitTest.java= :225) =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) Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=3D//result[@nu= mFound=3D1] =09xml response was: 00 =09request was:q=3Did:14&qt=3Dstandard&start=3D0&rows=3D20&version=3D2.2 =09at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:888) =09... 40 more FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollecti= onsAPI Error Message: Something is broken in the assert for no shards using the same indexDir - p= robably something was changed in the attributes published in the MBean of S= olrCore : {} Stack Trace: java.lang.AssertionError: Something is broken in the assert for no shards u= sing the same indexDir - probably something was changed in the attributes p= ublished in the MBean of SolrCore : {} =09at __randomizedtesting.SeedInfo.seed([E7BF4C35D486CF13:AFCA3881D2B5E086]= :0) =09at org.junit.Assert.fail(Assert.java:93) =09at org.junit.Assert.assertTrue(Assert.java:43) =09at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.checkNoTwoShard= sUseTheSameIndexDir(CollectionsAPIDistributedZkTest.java:646) =09at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollections= API(CollectionsAPIDistributedZkTest.java:524) =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 12085 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-6.6-= Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistribute= dZkTest_E7BF4C35D486CF13-001/init-core-data-001 [junit4] 2> 909501 WARN (SUITE-CollectionsAPIDistributedZkTest-seed#[= E7BF4C35D486CF13]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearcher= s: numOpens=3D2 numCloses=3D2 [junit4] 2> 909501 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[= E7BF4C35D486CF13]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields [junit4] 2> 909502 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[= E7BF4C35D486CF13]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false= ) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=3D, = ssl=3DNaN, value=3DNaN, clientAuth=3DNaN) [junit4] 2> 909503 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[= E7BF4C35D486CF13]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting clus= ter of 4 servers in /home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/buil= d/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_E7BF4C3= 5D486CF13-001/tempDir-001 [junit4] 2> 909503 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[= E7BF4C35D486CF13]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERV= ER [junit4] 2> 909504 INFO (Thread-3135) [ ] o.a.s.c.ZkTestServer cli= ent port:0.0.0.0/0.0.0.0:0 [junit4] 2> 909504 INFO (Thread-3135) [ ] o.a.s.c.ZkTestServer Sta= rting server [junit4] 2> 909509 ERROR (Thread-3135) [ ] 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> 909604 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[= E7BF4C35D486CF13]-worker) [ ] o.a.s.c.ZkTestServer start zk server on po= rt:44381 [junit4] 2> 909614 INFO (jetty-launcher-1029-thread-4) [ ] o.e.j.s= .Server jetty-9.3.14.v20161028 [junit4] 2> 909614 INFO (jetty-launcher-1029-thread-2) [ ] o.e.j.s= .Server jetty-9.3.14.v20161028 [junit4] 2> 909614 INFO (jetty-launcher-1029-thread-1) [ ] o.e.j.s= .Server jetty-9.3.14.v20161028 [junit4] 2> 909615 INFO (jetty-launcher-1029-thread-4) [ ] o.e.j.s= .h.ContextHandler Started o.e.j.s.ServletContextHandler@ed678a{/solr,null,A= VAILABLE} [junit4] 2> 909618 INFO (jetty-launcher-1029-thread-3) [ ] o.e.j.s= .Server jetty-9.3.14.v20161028 [junit4] 2> 909623 INFO (jetty-launcher-1029-thread-4) [ ] o.e.j.s= .AbstractConnector Started ServerConnector@491038{HTTP/1.1,[http/1.1]}{127.= 0.0.1:34087} [junit4] 2> 909623 INFO (jetty-launcher-1029-thread-4) [ ] o.e.j.s= .Server Started @910691ms [junit4] 2> 909623 INFO (jetty-launcher-1029-thread-4) [ ] o.a.s.c= .s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D340= 87} [junit4] 2> 909623 ERROR (jetty-launcher-1029-thread-4) [ ] o.a.s.s= .StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missi= ng or incomplete. [junit4] 2> 909624 INFO (jetty-launcher-1029-thread-4) [ ] o.a.s.s= .SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.6.2 [junit4] 2> 909624 INFO (jetty-launcher-1029-thread-4) [ ] o.a.s.s= .SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 909624 INFO (jetty-launcher-1029-thread-4) [ ] o.a.s.s= .SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 909624 INFO (jetty-launcher-1029-thread-4) [ ] o.a.s.s= .SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-17T15:36:29.402Z [junit4] 2> 909634 INFO (jetty-launcher-1029-thread-1) [ ] o.e.j.s= .h.ContextHandler Started o.e.j.s.ServletContextHandler@ecb4b3{/solr,null,A= VAILABLE} [junit4] 2> 909634 INFO (jetty-launcher-1029-thread-1) [ ] o.e.j.s= .AbstractConnector Started ServerConnector@ab0d5{HTTP/1.1,[http/1.1]}{127.0= .0.1:34669} [junit4] 2> 909634 INFO (jetty-launcher-1029-thread-1) [ ] o.e.j.s= .Server Started @910703ms [junit4] 2> 909634 INFO (jetty-launcher-1029-thread-1) [ ] o.a.s.c= .s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D346= 69} [junit4] 2> 909635 ERROR (jetty-launcher-1029-thread-1) [ ] o.a.s.s= .StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missi= ng or incomplete. [junit4] 2> 909635 INFO (jetty-launcher-1029-thread-1) [ ] o.a.s.s= .SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.6.2 [junit4] 2> 909635 INFO (jetty-launcher-1029-thread-1) [ ] o.a.s.s= .SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 909635 INFO (jetty-launcher-1029-thread-1) [ ] o.a.s.s= .SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 909635 INFO (jetty-launcher-1029-thread-1) [ ] o.a.s.s= .SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-17T15:36:29.413Z [junit4] 2> 909641 INFO (jetty-launcher-1029-thread-4) [ ] o.a.s.s= .SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 909643 INFO (jetty-launcher-1029-thread-2) [ ] o.e.j.s= .h.ContextHandler Started o.e.j.s.ServletContextHandler@189dfaa{/solr,null,= AVAILABLE} [junit4] 2> 909644 INFO (jetty-launcher-1029-thread-2) [ ] o.e.j.s= .AbstractConnector Started ServerConnector@78145{HTTP/1.1,[http/1.1]}{127.0= .0.1:37751} [junit4] 2> 909644 INFO (jetty-launcher-1029-thread-2) [ ] o.e.j.s= .Server Started @910713ms [junit4] 2> 909644 INFO (jetty-launcher-1029-thread-2) [ ] o.a.s.c= .s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D377= 51} [junit4] 2> 909645 ERROR (jetty-launcher-1029-thread-2) [ ] o.a.s.s= .StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missi= ng or incomplete. [junit4] 2> 909645 INFO (jetty-launcher-1029-thread-2) [ ] o.a.s.s= .SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.6.2 [junit4] 2> 909645 INFO (jetty-launcher-1029-thread-2) [ ] o.a.s.s= .SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 909645 INFO (jetty-launcher-1029-thread-2) [ ] o.a.s.s= .SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 909645 INFO (jetty-launcher-1029-thread-2) [ ] o.a.s.s= .SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-17T15:36:29.423Z [junit4] 2> 909649 INFO (jetty-launcher-1029-thread-1) [ ] o.a.s.s= .SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 909655 INFO (jetty-launcher-1029-thread-3) [ ] o.e.j.s= .h.ContextHandler Started o.e.j.s.ServletContextHandler@5055b4{/solr,null,A= VAILABLE} [junit4] 2> 909656 INFO (jetty-launcher-1029-thread-3) [ ] o.e.j.s= .AbstractConnector Started ServerConnector@b59e0c{HTTP/1.1,[http/1.1]}{127.= 0.0.1:34415} [junit4] 2> 909656 INFO (jetty-launcher-1029-thread-3) [ ] o.e.j.s= .Server Started @910724ms [junit4] 2> 909656 INFO (jetty-launcher-1029-thread-3) [ ] o.a.s.c= .s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D344= 15} [junit4] 2> 909656 ERROR (jetty-launcher-1029-thread-3) [ ] o.a.s.s= .StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missi= ng or incomplete. [junit4] 2> 909656 INFO (jetty-launcher-1029-thread-3) [ ] o.a.s.s= .SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.6.2 [junit4] 2> 909656 INFO (jetty-launcher-1029-thread-3) [ ] o.a.s.s= .SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 909656 INFO (jetty-launcher-1029-thread-3) [ ] o.a.s.s= .SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 909657 INFO (jetty-launcher-1029-thread-3) [ ] o.a.s.s= .SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-17T15:36:29.434Z [junit4] 2> 909665 INFO (jetty-launcher-1029-thread-3) [ ] o.a.s.s= .SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 909666 INFO (jetty-launcher-1029-thread-4) [ ] o.a.s.u= .UpdateShardHandler Creating UpdateShardHandler HTTP client with params: so= cketTimeout=3D340000&connTimeout=3D45000&retry=3Dtrue [junit4] 2> 909669 INFO (jetty-launcher-1029-thread-4) [ ] o.a.s.c= .ZkContainer Zookeeper client=3D127.0.0.1:44381/solr [junit4] 2> 909695 INFO (jetty-launcher-1029-thread-1) [ ] o.a.s.u= .UpdateShardHandler Creating UpdateShardHandler HTTP client with params: so= cketTimeout=3D340000&connTimeout=3D45000&retry=3Dtrue [junit4] 2> 909695 INFO (jetty-launcher-1029-thread-2) [ ] o.a.s.s= .SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 909698 INFO (jetty-launcher-1029-thread-1) [ ] o.a.s.c= .ZkContainer Zookeeper client=3D127.0.0.1:44381/solr [junit4] 2> 909700 INFO (jetty-launcher-1029-thread-3) [ ] o.a.s.u= .UpdateShardHandler Creating UpdateShardHandler HTTP client with params: so= cketTimeout=3D340000&connTimeout=3D45000&retry=3Dtrue [junit4] 2> 909703 INFO (jetty-launcher-1029-thread-3) [ ] o.a.s.c= .ZkContainer Zookeeper client=3D127.0.0.1:44381/solr [junit4] 2> 909712 INFO (jetty-launcher-1029-thread-2) [ ] o.a.s.u= .UpdateShardHandler Creating UpdateShardHandler HTTP client with params: so= cketTimeout=3D340000&connTimeout=3D45000&retry=3Dtrue [junit4] 2> 909713 INFO (jetty-launcher-1029-thread-2) [ ] o.a.s.c= .ZkContainer Zookeeper client=3D127.0.0.1:44381/solr [junit4] 2> 909717 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 0x15f2afaa163000b, 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> 909733 INFO (jetty-launcher-1029-thread-1) [n:127.0.0.1:3= 4669_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 909734 INFO (jetty-launcher-1029-thread-4) [n:127.0.0.1:3= 4087_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 909734 INFO (jetty-launcher-1029-thread-1) [n:127.0.0.1:3= 4669_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader = 127.0.0.1:34669_solr [junit4] 2> 909735 INFO (jetty-launcher-1029-thread-4) [n:127.0.0.1:3= 4087_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/liv= e_nodes/127.0.0.1:34087_solr [junit4] 2> 909736 INFO (jetty-launcher-1029-thread-1) [n:127.0.0.1:3= 4669_solr ] o.a.s.c.Overseer Overseer (id=3D98844972763250697-127.0.0.1:= 34669_solr-n_0000000000) starting [junit4] 2> 909736 INFO (zkCallback-1047-thread-1-processing-n:127.0.= 0.1:34415_solr) [n:127.0.0.1:34415_solr ] o.a.s.c.c.ZkStateReader Update= d live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 909754 INFO (zkCallback-1046-thread-1-processing-n:127.0.= 0.1:34669_solr) [n:127.0.0.1:34669_solr ] o.a.s.c.c.ZkStateReader Update= d live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 909755 INFO (jetty-launcher-1029-thread-3) [n:127.0.0.1:3= 4415_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 909759 INFO (zkCallback-1042-thread-1-processing-n:127.0.= 0.1:34087_solr) [n:127.0.0.1:34087_solr ] o.a.s.c.c.ZkStateReader Update= d live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 909765 INFO (zkCallback-1052-thread-1-processing-n:127.0.= 0.1:37751_solr) [n:127.0.0.1:37751_solr ] o.a.s.c.c.ZkStateReader Update= d live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 909767 INFO (jetty-launcher-1029-thread-3) [n:127.0.0.1:3= 4415_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/liv= e_nodes/127.0.0.1:34415_solr [junit4] 2> 909767 INFO (jetty-launcher-1029-thread-2) [n:127.0.0.1:3= 7751_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 909770 INFO (zkCallback-1042-thread-1-processing-n:127.0.= 0.1:34087_solr) [n:127.0.0.1:34087_solr ] o.a.s.c.c.ZkStateReader Update= d live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 909770 INFO (zkCallback-1047-thread-1-processing-n:127.0.= 0.1:34415_solr) [n:127.0.0.1:34415_solr ] o.a.s.c.c.ZkStateReader Update= d live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 909772 INFO (jetty-launcher-1029-thread-2) [n:127.0.0.1:3= 7751_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/liv= e_nodes/127.0.0.1:37751_solr [junit4] 2> 909772 INFO (zkCallback-1046-thread-1-processing-n:127.0.= 0.1:34669_solr) [n:127.0.0.1:34669_solr ] o.a.s.c.c.ZkStateReader Update= d live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 909772 INFO (zkCallback-1052-thread-1-processing-n:127.0.= 0.1:37751_solr) [n:127.0.0.1:37751_solr ] o.a.s.c.c.ZkStateReader Update= d live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 909773 INFO (zkCallback-1042-thread-1-processing-n:127.0.= 0.1:34087_solr) [n:127.0.0.1:34087_solr ] o.a.s.c.c.ZkStateReader Update= d live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 909774 INFO (zkCallback-1052-thread-1-processing-n:127.0.= 0.1:37751_solr) [n:127.0.0.1:37751_solr ] o.a.s.c.c.ZkStateReader Update= d live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 909780 INFO (zkCallback-1046-thread-1-processing-n:127.0.= 0.1:34669_solr) [n:127.0.0.1:34669_solr ] o.a.s.c.c.ZkStateReader Update= d live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 909781 INFO (zkCallback-1047-thread-1-processing-n:127.0.= 0.1:34415_solr) [n:127.0.0.1:34415_solr ] o.a.s.c.c.ZkStateReader Update= d live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 909784 INFO (jetty-launcher-1029-thread-1) [n:127.0.0.1:3= 4669_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/liv= e_nodes/127.0.0.1:34669_solr [junit4] 2> 909786 INFO (zkCallback-1047-thread-1-processing-n:127.0.= 0.1:34415_solr) [n:127.0.0.1:34415_solr ] o.a.s.c.c.ZkStateReader Update= d live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 909786 INFO (zkCallback-1042-thread-1-processing-n:127.0.= 0.1:34087_solr) [n:127.0.0.1:34087_solr ] o.a.s.c.c.ZkStateReader Update= d live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 909787 INFO (zkCallback-1052-thread-1-processing-n:127.0.= 0.1:37751_solr) [n:127.0.0.1:37751_solr ] o.a.s.c.c.ZkStateReader Update= d live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 909787 INFO (zkCallback-1046-thread-1-processing-n:127.0.= 0.1:34669_solr) [n:127.0.0.1:34669_solr ] o.a.s.c.c.ZkStateReader Update= d live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 909939 INFO (jetty-launcher-1029-thread-2) [n:127.0.0.1:3= 7751_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions under= neath /home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/te= st/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_E7BF4C35D486CF13-001/= tempDir-001/node1/. [junit4] 2> 909944 INFO (jetty-launcher-1029-thread-4) [n:127.0.0.1:3= 4087_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions under= neath /home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/te= st/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_E7BF4C35D486CF13-001/= tempDir-001/node3/. [junit4] 2> 909992 INFO (jetty-launcher-1029-thread-3) [n:127.0.0.1:3= 4415_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions under= neath /home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/te= st/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_E7BF4C35D486CF13-001/= tempDir-001/node2/. [junit4] 2> 910147 INFO (jetty-launcher-1029-thread-1) [n:127.0.0.1:3= 4669_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions under= neath /home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/te= st/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_E7BF4C35D486CF13-001/= tempDir-001/node4/. [junit4] 2> 910158 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[= E7BF4C35D486CF13]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes= from ZooKeeper... (0) -> (4) [junit4] 2> 910158 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[= E7BF4C35D486CF13]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider C= luster at 127.0.0.1:44381/solr ready [junit4] 2> 910187 INFO (TEST-CollectionsAPIDistributedZkTest.testNoC= onfigSetExist-seed#[E7BF4C35D486CF13]) [ ] o.a.s.SolrTestCaseJ4 ###Start= ing testNoConfigSetExist [junit4] 2> 910188 INFO (TEST-CollectionsAPIDistributedZkTest.testNoC= onfigSetExist-seed#[E7BF4C35D486CF13]) [ ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 910205 INFO (qtp30608233-7928) [n:127.0.0.1:34087_solr = ] o.a.s.h.a.CoreAdminOperation core create command dataDir=3D/home/jenkins= /workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.clo= ud.CollectionsAPIDistributedZkTest_E7BF4C35D486CF13-001/tempDir-002&collect= ion.configName=3Dconf123&name=3Dcorewithnocollection3&action=3DCREATE&numSh= ards=3D1&collection=3D&wt=3Djavabin&version=3D2 [junit4] 2> 910206 INFO (qtp30608233-7928) [n:127.0.0.1:34087_solr = ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 214= 7483647 transient cores [junit4] 2> 910208 INFO (OverseerStateUpdate-98844972763250697-127.0.= 0.1:34669_solr-n_0000000000) [n:127.0.0.1:34669_solr ] o.a.s.c.o.Replica= Mutator Assigning new node to shard shard=3Dshard1 [junit4] 2> 911210 ERROR (qtp30608233-7928) [n:127.0.0.1:34087_solr c:= corewithnocollection3 x:corewithnocollection3] o.a.s.c.c.ZkStateReader Sp= ecified config=3D[conf123] does not exist in ZooKeeper at location=3D[/conf= igs/conf123] [junit4] 2> 911211 ERROR (qtp30608233-7928) [n:127.0.0.1:34087_solr c:= corewithnocollection3 x:corewithnocollection3] o.a.s.h.RequestHandlerBase= org.apache.solr.common.SolrException: Error CREATEing SolrCore 'corewithno= collection3': Unable to create core [corewithnocollection3] Caused by: Spec= ified config does not exist in ZooKeeper: conf123 [junit4] 2> =09at org.apache.solr.core.CoreContainer.create(CoreContai= ner.java:898) [junit4] 2> =09at org.apache.solr.handler.admin.CoreAdminOperation.lam= bda$static$0(CoreAdminOperation.java:91) [junit4] 2> =09at org.apache.solr.handler.admin.CoreAdminOperation.exe= cute(CoreAdminOperation.java:384) [junit4] 2> =09at org.apache.solr.handler.admin.CoreAdminHandler$CallI= nfo.call(CoreAdminHandler.java:388) [junit4] 2> =09at org.apache.solr.handler.admin.CoreAdminHandler.handl= eRequestBody(CoreAdminHandler.java:174) [junit4] 2> =09at org.apache.solr.handler.RequestHandlerBase.handleReq= uest(RequestHandlerBase.java:173) [junit4] 2> =09at org.apache.solr.servlet.HttpSolrCall.handleAdmin(Htt= pSolrCall.java:748) [junit4] 2> =09at org.apache.solr.servlet.HttpSolrCall.handleAdminRequ= est(HttpSolrCall.java:729) [junit4] 2> =09at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCa= ll.java:510) [junit4] 2> =09at org.apache.solr.servlet.SolrDispatchFilter.doFilter(= SolrDispatchFilter.java:361) [junit4] 2> =09at org.apache.solr.servlet.SolrDispatchFilter.doFilter(= SolrDispatchFilter.java:305) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler$CachedChain= .doFilter(ServletHandler.java:1699) [junit4] 2> =09at org.apache.solr.client.solrj.embedded.JettySolrRunne= r$DebugFilter.doFilter(JettySolrRunner.java:136) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler$CachedChain= .doFilter(ServletHandler.java:1699) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler.doHandle(Se= rvletHandler.java:582) [junit4] 2> =09at org.eclipse.jetty.server.session.SessionHandler.doHa= ndle(SessionHandler.java:224) [junit4] 2> =09at org.eclipse.jetty.server.handler.ContextHandler.doHa= ndle(ContextHandler.java:1180) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler.doScope(Ser= vletHandler.java:512) [junit4] 2> =09at org.eclipse.jetty.server.session.SessionHandler.doSc= ope(SessionHandler.java:185) [junit4] 2> =09at org.eclipse.jetty.server.handler.ContextHandler.doSc= ope(ContextHandler.java:1112) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.handl= e(ScopedHandler.java:141) [junit4] 2> =09at org.eclipse.jetty.server.handler.gzip.GzipHandler.ha= ndle(GzipHandler.java:462) [junit4] 2> =09at org.eclipse.jetty.server.handler.HandlerWrapper.hand= le(HandlerWrapper.java:134) [junit4] 2> =09at org.eclipse.jetty.server.Server.handle(Server.java:5= 34) [junit4] 2> =09at org.eclipse.jetty.server.HttpChannel.handle(HttpChan= nel.java:320) [junit4] 2> =09at org.eclipse.jetty.server.HttpConnection.onFillable(H= ttpConnection.java:251) [junit4] 2> =09at org.eclipse.jetty.io.AbstractConnection$ReadCallback= .succeeded(AbstractConnection.java:273) [junit4] 2> =09at org.eclipse.jetty.io.FillInterest.fillable(FillInter= est.java:95) [junit4] 2> =09at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(Sel= ectChannelEndPoint.java:93) [junit4] 2> =09at org.eclipse.jetty.util.thread.strategy.ExecuteProduc= eConsume.executeProduceConsume(ExecuteProduceConsume.java:303) [junit4] 2> =09at org.eclipse.jetty.util.thread.strategy.ExecuteProduc= eConsume.produceConsume(ExecuteProduceConsume.java:148) [junit4] 2> =09at org.eclipse.jetty.util.thread.strategy.ExecuteProduc= eConsume.run(ExecuteProduceConsume.java:136) [junit4] 2> =09at org.eclipse.jetty.util.thread.QueuedThreadPool.runJo= b(QueuedThreadPool.java:671) [junit4] 2> =09at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run= (QueuedThreadPool.java:589) [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> Caused by: org.apache.solr.common.SolrException: Unable to= create core [corewithnocollection3] [junit4] 2> =09at org.apache.solr.core.CoreContainer.createFromDescrip= tor(CoreContainer.java:964) [junit4] 2> =09at org.apache.solr.core.CoreContainer.create(CoreContai= ner.java:862) [junit4] 2> =09... 34 more [junit4] 2> Caused by: org.apache.solr.common.cloud.ZooKeeperException= : Specified config does not exist in ZooKeeper: conf123 [junit4] 2> =09at org.apache.solr.common.cloud.ZkStateReader.readConfi= gName(ZkStateReader.java:189) [junit4] 2> =09at org.apache.solr.cloud.CloudConfigSetService.createCo= reResourceLoader(CloudConfigSetService.java:54) [junit4] 2> =09at org.apache.solr.core.ConfigSetService.getConfig(Conf= igSetService.java:75) [junit4] 2> =09at org.apache.solr.core.CoreContainer.createFromDescrip= tor(CoreContainer.java:944) [junit4] 2> =09... 35 more [junit4] 2>=20 [junit4] 2> 911212 INFO (qtp30608233-7928) [n:127.0.0.1:34087_solr c:= corewithnocollection3 x:corewithnocollection3] o.a.s.s.HttpSolrCall [admi= n] webapp=3Dnull path=3D/admin/cores params=3D{dataDir=3D/home/jenkins/work= space/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.Co= llectionsAPIDistributedZkTest_E7BF4C35D486CF13-001/tempDir-002&collection.c= onfigName=3Dconf123&name=3Dcorewithnocollection3&action=3DCREATE&numShards= =3D1&collection=3D&wt=3Djavabin&version=3D2} status=3D400 QTime=3D1007 [junit4] 2> 912216 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params= action=3DLIST&wt=3Djavabin&version=3D2 and sendToOCPQueue=3Dtrue [junit4] 2> 912216 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections par= ams=3D{action=3DLIST&wt=3Djavabin&version=3D2} status=3D0 QTime=3D0 [junit4] 2> 912217 INFO (TEST-CollectionsAPIDistributedZkTest.testNoC= onfigSetExist-seed#[E7BF4C35D486CF13]) [ ] o.a.s.SolrTestCaseJ4 ###Endin= g testNoConfigSetExist [junit4] 2> 912236 INFO (TEST-CollectionsAPIDistributedZkTest.deleteC= ollectionOnlyInZk-seed#[E7BF4C35D486CF13]) [ ] o.a.s.SolrTestCaseJ4 ###S= tarting deleteCollectionOnlyInZk [junit4] 2> 912237 INFO (TEST-CollectionsAPIDistributedZkTest.deleteC= ollectionOnlyInZk-seed#[E7BF4C35D486CF13]) [ ] o.a.s.c.c.ZkStateReader U= pdated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 912240 INFO (qtp30608233-7930) [n:127.0.0.1:34087_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with para= ms name=3Dcorewithnocollection3&action=3DDELETE&wt=3Djavabin&version=3D2 an= d sendToOCPQueue=3Dtrue [junit4] 2> 912244 INFO (OverseerThreadFactory-3000-thread-1-processi= ng-n:127.0.0.1:34669_solr) [n:127.0.0.1:34669_solr ] o.a.s.c.OverseerCol= lectionMessageHandler Executing Collection Cmd : action=3DUNLOAD&deleteInst= anceDir=3Dtrue&deleteDataDir=3Dtrue [junit4] 2> 912948 INFO (qtp30608233-7930) [n:127.0.0.1:34087_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections par= ams=3D{name=3Dcorewithnocollection3&action=3DDELETE&wt=3Djavabin&version=3D= 2} status=3D0 QTime=3D708 [junit4] 2> 912952 INFO (qtp30608233-7922) [n:127.0.0.1:34087_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with para= ms name=3Donlyinzk&action=3DDELETE&wt=3Djavabin&version=3D2 and sendToOCPQu= eue=3Dtrue [junit4] 2> 912953 INFO (OverseerCollectionConfigSetProcessor-9884497= 2763250697-127.0.0.1:34669_solr-n_0000000000) [n:127.0.0.1:34669_solr ] = o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work= /qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKee= per [junit4] 2> 912957 ERROR (OverseerCollectionConfigSetProcessor-9884497= 2763250697-127.0.0.1:34669_solr-n_0000000000) [n:127.0.0.1:34669_solr ] = o.a.s.c.OverseerTaskProcessor :org.apache.zookeeper.KeeperException$NoNodeE= xception: KeeperErrorCode =3D NoNode for /overseer/collection-queue-work/qn= r-0000000002 [junit4] 2> =09at org.apache.zookeeper.KeeperException.create(KeeperEx= ception.java:111) [junit4] 2> =09at org.apache.zookeeper.KeeperException.create(KeeperEx= ception.java:51) [junit4] 2> =09at org.apache.zookeeper.ZooKeeper.setData(ZooKeeper.jav= a:1327) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$8.execute(= SolrZkClient.java:374) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$8.execute(= SolrZkClient.java:371) [junit4] 2> =09at org.apache.solr.common.cloud.ZkCmdExecutor.retryOper= ation(ZkCmdExecutor.java:60) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient.setData(So= lrZkClient.java:371) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient.setData(So= lrZkClient.java:572) [junit4] 2> =09at org.apache.solr.cloud.OverseerTaskQueue.remove(Overs= eerTaskQueue.java:94) [junit4] 2> =09at org.apache.solr.cloud.OverseerTaskProcessor.cleanUpW= orkQueue(OverseerTaskProcessor.java:321) [junit4] 2> =09at org.apache.solr.cloud.OverseerTaskProcessor.run(Over= seerTaskProcessor.java:202) [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [junit4] 2>=20 [junit4] 2> 912957 INFO (qtp30608233-7922) [n:127.0.0.1:34087_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections par= ams=3D{name=3Donlyinzk&action=3DDELETE&wt=3Djavabin&version=3D2} status=3D0= QTime=3D5 [junit4] 2> 912958 INFO (OverseerCollectionConfigSetProcessor-9884497= 2763250697-127.0.0.1:34669_solr-n_0000000000) [n:127.0.0.1:34669_solr ] = o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work= /qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKee= per [junit4] 2> 912960 INFO (qtp30608233-7928) [n:127.0.0.1:34087_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params= action=3DLIST&wt=3Djavabin&version=3D2 and sendToOCPQueue=3Dtrue [junit4] 2> 912960 INFO (qtp30608233-7928) [n:127.0.0.1:34087_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections par= ams=3D{action=3DLIST&wt=3Djavabin&version=3D2} status=3D0 QTime=3D0 [junit4] 2> 912963 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with para= ms replicationFactor=3D1&collection.configName=3Dconf&name=3Donlyinzk&actio= n=3DCREATE&numShards=3D2&wt=3Djavabin&version=3D2 and sendToOCPQueue=3Dtrue [junit4] 2> 912964 INFO (OverseerThreadFactory-3000-thread-3-processi= ng-n:127.0.0.1:34669_solr) [n:127.0.0.1:34669_solr ] o.a.s.c.CreateColle= ctionCmd Create collection onlyinzk [junit4] 2> 913170 INFO (qtp30608233-7925) [n:127.0.0.1:34087_solr = ] o.a.s.h.a.CoreAdminOperation core create command qt=3D/admin/cores&colle= ction.configName=3Dconf&newCollection=3Dtrue&name=3Donlyinzk_shard1_replica= 1&action=3DCREATE&numShards=3D2&collection=3Donlyinzk&shard=3Dshard1&wt=3Dj= avabin&version=3D2 [junit4] 2> 913173 INFO (qtp28308953-7950) [n:127.0.0.1:34669_solr = ] o.a.s.h.a.CoreAdminOperation core create command qt=3D/admin/cores&colle= ction.configName=3Dconf&newCollection=3Dtrue&name=3Donlyinzk_shard2_replica= 1&action=3DCREATE&numShards=3D2&collection=3Donlyinzk&shard=3Dshard2&wt=3Dj= avabin&version=3D2 [junit4] 2> 913174 INFO (qtp28308953-7950) [n:127.0.0.1:34669_solr = ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 214= 7483647 transient cores [junit4] 2> 913277 INFO (zkCallback-1042-thread-1-processing-n:127.0.= 0.1:34087_solr) [n:127.0.0.1:34087_solr ] o.a.s.c.c.ZkStateReader A clus= ter state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pa= th:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred= - updating... (live nodes size: [4]) [junit4] 2> 913277 INFO (zkCallback-1046-thread-2-processing-n:127.0.= 0.1:34669_solr) [n:127.0.0.1:34669_solr ] o.a.s.c.c.ZkStateReader A clus= ter state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pa= th:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred= - updating... (live nodes size: [4]) [junit4] 2> 914181 INFO (qtp30608233-7925) [n:127.0.0.1:34087_solr c:= onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.c.SolrConfig Using Luc= ene MatchVersion: 6.6.2 [junit4] 2> 914184 INFO (qtp28308953-7950) [n:127.0.0.1:34669_solr c:= onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.c.SolrConfig Using Luc= ene MatchVersion: 6.6.2 [junit4] 2> 914190 INFO (qtp30608233-7925) [n:127.0.0.1:34087_solr c:= onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.s.IndexSchema [onlyinz= k_shard1_replica1] Schema name=3Dminimal [junit4] 2> 914193 INFO (qtp30608233-7925) [n:127.0.0.1:34087_solr c:= onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.s.IndexSchema Loaded s= chema minimal/1.1 with uniqueid field id [junit4] 2> 914193 INFO (qtp30608233-7925) [n:127.0.0.1:34087_solr c:= onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.c.CoreContainer Creati= ng SolrCore 'onlyinzk_shard1_replica1' using configuration from collection = onlyinzk, trusted=3Dtrue [junit4] 2> 914193 INFO (qtp30608233-7925) [n:127.0.0.1:34087_solr c:= onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.c.SolrCore solr.Recove= ryStrategy.Builder [junit4] 2> 914193 INFO (qtp30608233-7925) [n:127.0.0.1:34087_solr c:= onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.c.SolrCore [[onlyinzk_= shard1_replica1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-= Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPID= istributedZkTest_E7BF4C35D486CF13-001/tempDir-001/node3/onlyinzk_shard1_rep= lica1], dataDir=3D[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build= /solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_E7BF4C35= D486CF13-001/tempDir-001/node3/./onlyinzk_shard1_replica1/data/] [junit4] 2> 914195 INFO (qtp28308953-7950) [n:127.0.0.1:34669_solr c:= onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.s.IndexSchema [onlyinz= k_shard2_replica1] Schema name=3Dminimal [junit4] 2> 914204 INFO (qtp28308953-7950) [n:127.0.0.1:34669_solr c:= onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.s.IndexSchema Loaded s= chema minimal/1.1 with uniqueid field id [junit4] 2> 914204 INFO (qtp28308953-7950) [n:127.0.0.1:34669_solr c:= onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.c.CoreContainer Creati= ng SolrCore 'onlyinzk_shard2_replica1' using configuration from collection = onlyinzk, trusted=3Dtrue [junit4] 2> 914204 INFO (qtp28308953-7950) [n:127.0.0.1:34669_solr c:= onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.c.SolrCore solr.Recove= ryStrategy.Builder [junit4] 2> 914205 INFO (qtp28308953-7950) [n:127.0.0.1:34669_solr c:= onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.c.SolrCore [[onlyinzk_= shard2_replica1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-= Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPID= istributedZkTest_E7BF4C35D486CF13-001/tempDir-001/node4/onlyinzk_shard2_rep= lica1], dataDir=3D[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build= /solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_E7BF4C35= D486CF13-001/tempDir-001/node4/./onlyinzk_shard2_replica1/data/] [junit4] 2> 914238 INFO (qtp30608233-7925) [n:127.0.0.1:34087_solr c:= onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.u.UpdateHandler Using = UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 914238 INFO (qtp30608233-7925) [n:127.0.0.1:34087_solr c:= onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.u.UpdateLog Initializi= ng UpdateLog: dataDir=3Dnull defaultSyncLevel=3DFLUSH numRecordsToKeep=3D10= 0 maxNumLogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 914238 INFO (qtp30608233-7925) [n:127.0.0.1:34087_solr c:= onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.u.CommitTracker Hard A= utoCommit: disabled [junit4] 2> 914239 INFO (qtp30608233-7925) [n:127.0.0.1:34087_solr c:= onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.u.CommitTracker Soft A= utoCommit: disabled [junit4] 2> 914239 INFO (qtp30608233-7925) [n:127.0.0.1:34087_solr c:= onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.s.SolrIndexSearcher Op= ening [Searcher@e8975b[onlyinzk_shard1_replica1] main] [junit4] 2> 914240 INFO (qtp30608233-7925) [n:127.0.0.1:34087_solr c:= onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.r.ManagedResourceStora= ge Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 914240 INFO (qtp30608233-7925) [n:127.0.0.1:34087_solr c:= onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.r.ManagedResourceStora= ge Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=3D/= configs/conf [junit4] 2> 914241 INFO (qtp30608233-7925) [n:127.0.0.1:34087_solr c:= onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.h.ReplicationHandler C= ommits will be reserved for 10000 [junit4] 2> 914241 INFO (searcherExecutor-3009-thread-1-processing-n:= 127.0.0.1:34087_solr x:onlyinzk_shard1_replica1 s:shard1 c:onlyinzk) [n:127= .0.0.1:34087_solr c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.c.= SolrCore [onlyinzk_shard1_replica1] Registered new searcher Searcher@e8975b= [onlyinzk_shard1_replica1] main{ExitableDirectoryReader(UninvertingDirector= yReader())} [junit4] 2> 914241 INFO (qtp30608233-7925) [n:127.0.0.1:34087_solr c:= onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.u.UpdateLog Could not = find max version in index or recent updates, using new clock 15815195691780= 66944 [junit4] 2> 914245 INFO (qtp30608233-7925) [n:127.0.0.1:34087_solr c:= onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.c.ShardLeaderElectionC= ontext Enough replicas found to continue. [junit4] 2> 914246 INFO (qtp30608233-7925) [n:127.0.0.1:34087_solr c:= onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.c.ShardLeaderElectionC= ontext I may be the new leader - try and sync [junit4] 2> 914246 INFO (qtp30608233-7925) [n:127.0.0.1:34087_solr c:= onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.c.SyncStrategy Sync re= plicas to http://127.0.0.1:34087/solr/onlyinzk_shard1_replica1/ [junit4] 2> 914246 INFO (qtp30608233-7925) [n:127.0.0.1:34087_solr c:= onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.c.SyncStrategy Sync Su= ccess - now sync replicas to me [junit4] 2> 914246 INFO (qtp30608233-7925) [n:127.0.0.1:34087_solr c:= onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.c.SyncStrategy http://= 127.0.0.1:34087/solr/onlyinzk_shard1_replica1/ has no replicas [junit4] 2> 914246 INFO (qtp30608233-7925) [n:127.0.0.1:34087_solr c:= onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.c.ShardLeaderElectionC= ontext Found all replicas participating in election, clear LIR [junit4] 2> 914248 INFO (qtp30608233-7925) [n:127.0.0.1:34087_solr c:= onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.c.ShardLeaderElectionC= ontext I am the new leader: http://127.0.0.1:34087/solr/onlyinzk_shard1_rep= lica1/ shard1 [junit4] 2> 914249 INFO (qtp28308953-7950) [n:127.0.0.1:34669_solr c:= onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.u.UpdateHandler Using = UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 914250 INFO (qtp28308953-7950) [n:127.0.0.1:34669_solr c:= onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.u.UpdateLog Initializi= ng UpdateLog: dataDir=3Dnull defaultSyncLevel=3DFLUSH numRecordsToKeep=3D10= 0 maxNumLogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 914250 INFO (qtp28308953-7950) [n:127.0.0.1:34669_solr c:= onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.u.CommitTracker Hard A= utoCommit: disabled [junit4] 2> 914251 INFO (qtp28308953-7950) [n:127.0.0.1:34669_solr c:= onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.u.CommitTracker Soft A= utoCommit: disabled [junit4] 2> 914252 INFO (qtp28308953-7950) [n:127.0.0.1:34669_solr c:= onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.s.SolrIndexSearcher Op= ening [Searcher@b04caa[onlyinzk_shard2_replica1] main] [junit4] 2> 914253 INFO (qtp28308953-7950) [n:127.0.0.1:34669_solr c:= onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.r.ManagedResourceStora= ge Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 914253 INFO (qtp28308953-7950) [n:127.0.0.1:34669_solr c:= onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.r.ManagedResourceStora= ge Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=3D/= configs/conf [junit4] 2> 914253 INFO (qtp28308953-7950) [n:127.0.0.1:34669_solr c:= onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.h.ReplicationHandler C= ommits will be reserved for 10000 [junit4] 2> 914253 INFO (searcherExecutor-3010-thread-1-processing-n:= 127.0.0.1:34669_solr x:onlyinzk_shard2_replica1 s:shard2 c:onlyinzk) [n:127= .0.0.1:34669_solr c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.c.= SolrCore [onlyinzk_shard2_replica1] Registered new searcher Searcher@b04caa= [onlyinzk_shard2_replica1] main{ExitableDirectoryReader(UninvertingDirector= yReader())} [junit4] 2> 914253 INFO (qtp28308953-7950) [n:127.0.0.1:34669_solr c:= onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.u.UpdateLog Could not = find max version in index or recent updates, using new clock 15815195691906= 49856 [junit4] 2> 914257 INFO (qtp28308953-7950) [n:127.0.0.1:34669_solr c:= onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.c.ShardLeaderElectionC= ontext Enough replicas found to continue. [junit4] 2> 914257 INFO (qtp28308953-7950) [n:127.0.0.1:34669_solr c:= onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.c.ShardLeaderElectionC= ontext I may be the new leader - try and sync [junit4] 2> 914258 INFO (qtp28308953-7950) [n:127.0.0.1:34669_solr c:= onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.c.SyncStrategy Sync re= plicas to http://127.0.0.1:34669/solr/onlyinzk_shard2_replica1/ [junit4] 2> 914258 INFO (qtp28308953-7950) [n:127.0.0.1:34669_solr c:= onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.c.SyncStrategy Sync Su= ccess - now sync replicas to me [junit4] 2> 914258 INFO (qtp28308953-7950) [n:127.0.0.1:34669_solr c:= onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.c.SyncStrategy http://= 127.0.0.1:34669/solr/onlyinzk_shard2_replica1/ has no replicas [junit4] 2> 914258 INFO (qtp28308953-7950) [n:127.0.0.1:34669_solr c:= onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.c.ShardLeaderElectionC= ontext Found all replicas participating in election, clear LIR [junit4] 2> 914260 INFO (qtp28308953-7950) [n:127.0.0.1:34669_solr c:= onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.c.ShardLeaderElectionC= ontext I am the new leader: http://127.0.0.1:34669/solr/onlyinzk_shard2_rep= lica1/ shard2 [junit4] 2> 914363 INFO (zkCallback-1042-thread-1-processing-n:127.0.= 0.1:34087_solr) [n:127.0.0.1:34087_solr ] o.a.s.c.c.ZkStateReader A clus= ter state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pa= th:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred= - updating... (live nodes size: [4]) [junit4] 2> 914363 INFO (zkCallback-1046-thread-2-processing-n:127.0.= 0.1:34669_solr) [n:127.0.0.1:34669_solr ] o.a.s.c.c.ZkStateReader A clus= ter state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pa= th:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred= - updating... (live nodes size: [4]) [junit4] 2> 914399 INFO (qtp30608233-7925) [n:127.0.0.1:34087_solr c:= onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.c.ZkController I am th= e leader, no recovery necessary [junit4] 2> 914400 INFO (qtp30608233-7925) [n:127.0.0.1:34087_solr c:= onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.s.HttpSolrCall [admin]= webapp=3Dnull path=3D/admin/cores params=3D{qt=3D/admin/cores&collection.c= onfigName=3Dconf&newCollection=3Dtrue&name=3Donlyinzk_shard1_replica1&actio= n=3DCREATE&numShards=3D2&collection=3Donlyinzk&shard=3Dshard1&wt=3Djavabin&= version=3D2} status=3D0 QTime=3D1230 [junit4] 2> 914412 INFO (qtp28308953-7950) [n:127.0.0.1:34669_solr c:= onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.c.ZkController I am th= e leader, no recovery necessary [junit4] 2> 914414 INFO (qtp28308953-7950) [n:127.0.0.1:34669_solr c:= onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.s.HttpSolrCall [admin]= webapp=3Dnull path=3D/admin/cores params=3D{qt=3D/admin/cores&collection.c= onfigName=3Dconf&newCollection=3Dtrue&name=3Donlyinzk_shard2_replica1&actio= n=3DCREATE&numShards=3D2&collection=3Donlyinzk&shard=3Dshard2&wt=3Djavabin&= version=3D2} status=3D0 QTime=3D1240 [junit4] 2> 914418 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr = ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at= most 30 seconds. Check all shard replicas [junit4] 2> 914518 INFO (zkCallback-1046-thread-2-processing-n:127.0.= 0.1:34669_solr) [n:127.0.0.1:34669_solr ] o.a.s.c.c.ZkStateReader A clus= ter state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pa= th:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred= - updating... (live nodes size: [4]) [junit4] 2> 914518 INFO (zkCallback-1042-thread-1-processing-n:127.0.= 0.1:34087_solr) [n:127.0.0.1:34087_solr ] o.a.s.c.c.ZkStateReader A clus= ter state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pa= th:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred= - updating... (live nodes size: [4]) [junit4] 2> 914965 INFO (OverseerCollectionConfigSetProcessor-9884497= 2763250697-127.0.0.1:34669_solr-n_0000000000) [n:127.0.0.1:34669_solr ] = o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work= /qnr-0000000004 doesn't exist. Requestor may have disconnected from ZooKee= per [junit4] 2> 915418 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections par= ams=3D{replicationFactor=3D1&collection.configName=3Dconf&name=3Donlyinzk&a= ction=3DCREATE&numShards=3D2&wt=3Djavabin&version=3D2} status=3D0 QTime=3D2= 455 [junit4] 2> 915421 INFO (qtp30608233-7930) [n:127.0.0.1:34087_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params= action=3DLIST&wt=3Djavabin&version=3D2 and sendToOCPQueue=3Dtrue [junit4] 2> 915421 INFO (qtp30608233-7930) [n:127.0.0.1:34087_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections par= ams=3D{action=3DLIST&wt=3Djavabin&version=3D2} status=3D0 QTime=3D0 [junit4] 2> 915422 INFO (TEST-CollectionsAPIDistributedZkTest.deleteC= ollectionOnlyInZk-seed#[E7BF4C35D486CF13]) [ ] o.a.s.SolrTestCaseJ4 ###E= nding deleteCollectionOnlyInZk [junit4] 2> 915444 INFO (TEST-CollectionsAPIDistributedZkTest.testMis= singRequiredParameters-seed#[E7BF4C35D486CF13]) [ ] o.a.s.SolrTestCaseJ4= ###Starting testMissingRequiredParameters [junit4] 2> 915445 INFO (TEST-CollectionsAPIDistributedZkTest.testMis= singRequiredParameters-seed#[E7BF4C35D486CF13]) [ ] o.a.s.c.c.ZkStateRea= der Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 915448 INFO (qtp30608233-7922) [n:127.0.0.1:34087_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with para= ms name=3Donlyinzk&action=3DDELETE&wt=3Djavabin&version=3D2 and sendToOCPQu= eue=3Dtrue [junit4] 2> 915450 INFO (OverseerThreadFactory-3000-thread-4-processi= ng-n:127.0.0.1:34669_solr) [n:127.0.0.1:34669_solr ] o.a.s.c.OverseerCol= lectionMessageHandler Executing Collection Cmd : action=3DUNLOAD&deleteInst= anceDir=3Dtrue&deleteDataDir=3Dtrue [junit4] 2> 915452 INFO (qtp28308953-7951) [n:127.0.0.1:34669_solr = ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.onlyin= zk.shard2.replica1 [junit4] 2> 915452 INFO (qtp28308953-7951) [n:127.0.0.1:34669_solr = ] o.a.s.c.SolrCore [onlyinzk_shard2_replica1] CLOSING SolrCore org.apache= .solr.core.SolrCore@1f7d6dc [junit4] 2> 915454 INFO (qtp30608233-7928) [n:127.0.0.1:34087_solr = ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.onlyin= zk.shard1.replica1 [junit4] 2> 915454 INFO (qtp30608233-7928) [n:127.0.0.1:34087_solr = ] o.a.s.c.SolrCore [onlyinzk_shard1_replica1] CLOSING SolrCore org.apache= .solr.core.SolrCore@1dd55a1 [junit4] 2> 915455 INFO (qtp30608233-7928) [n:127.0.0.1:34087_solr = ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.onlyin= zk.shard1.replica1 [junit4] 2> 915456 INFO (qtp28308953-7951) [n:127.0.0.1:34669_solr = ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.onlyin= zk.shard2.replica1 [junit4] 2> 915457 INFO (qtp30608233-7928) [n:127.0.0.1:34087_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/cores params=3D= {deleteInstanceDir=3Dtrue&core=3Donlyinzk_shard1_replica1&qt=3D/admin/cores= &deleteDataDir=3Dtrue&action=3DUNLOAD&wt=3Djavabin&version=3D2} status=3D0 = QTime=3D3 [junit4] 2> 915459 INFO (qtp28308953-7951) [n:127.0.0.1:34669_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/cores params=3D= {deleteInstanceDir=3Dtrue&core=3Donlyinzk_shard2_replica1&qt=3D/admin/cores= &deleteDataDir=3Dtrue&action=3DUNLOAD&wt=3Djavabin&version=3D2} status=3D0 = QTime=3D6 [junit4] 2> 916166 INFO (qtp30608233-7922) [n:127.0.0.1:34087_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections par= ams=3D{name=3Donlyinzk&action=3DDELETE&wt=3Djavabin&version=3D2} status=3D0= QTime=3D718 [junit4] 2> 916169 INFO (qtp30608233-7925) [n:127.0.0.1:34087_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with para= ms action=3DCREATE&numShards=3D2&wt=3Djavabin&version=3D2 and sendToOCPQueu= e=3Dtrue [junit4] 2> 916169 ERROR (qtp30608233-7925) [n:127.0.0.1:34087_solr = ] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: Missing= required parameter: name [junit4] 2> =09at org.apache.solr.common.params.RequiredSolrParams.get= Params(RequiredSolrParams.java:90) [junit4] 2> =09at org.apache.solr.common.params.SolrParams.getAll(Solr= Params.java:462) [junit4] 2> =09at org.apache.solr.common.params.SolrParams.getAll(Solr= Params.java:479) [junit4] 2> =09at org.apache.solr.handler.admin.CollectionsHandler$Col= lectionOperation.lambda$static$0(CollectionsHandler.java:396) [junit4] 2> =09at org.apache.solr.handler.admin.CollectionsHandler$Col= lectionOperation.execute(CollectionsHandler.java:922) [junit4] 2> =09at org.apache.solr.handler.admin.CollectionsHandler.inv= okeAction(CollectionsHandler.java:228) [junit4] 2> =09at org.apache.solr.handler.admin.CollectionsHandler.han= dleRequestBody(CollectionsHandler.java:215) [junit4] 2> =09at org.apache.solr.handler.RequestHandlerBase.handleReq= uest(RequestHandlerBase.java:173) [junit4] 2> =09at org.apache.solr.servlet.HttpSolrCall.handleAdmin(Htt= pSolrCall.java:748) [junit4] 2> =09at org.apache.solr.servlet.HttpSolrCall.handleAdminRequ= est(HttpSolrCall.java:729) [junit4] 2> =09at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCa= ll.java:510) [junit4] 2> =09at org.apache.solr.servlet.SolrDispatchFilter.doFilter(= SolrDispatchFilter.java:361) [junit4] 2> =09at org.apache.solr.servlet.SolrDispatchFilter.doFilter(= SolrDispatchFilter.java:305) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler$CachedChain= .doFilter(ServletHandler.java:1699) [junit4] 2> =09at org.apache.solr.client.solrj.embedded.JettySolrRunne= r$DebugFilter.doFilter(JettySolrRunner.java:136) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler$CachedChain= .doFilter(ServletHandler.java:1699) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler.doHandle(Se= rvletHandler.java:582) [junit4] 2> =09at org.eclipse.jetty.server.session.SessionHandler.doHa= ndle(SessionHandler.java:224) [junit4] 2> =09at org.eclipse.jetty.server.handler.ContextHandler.doHa= ndle(ContextHandler.java:1180) [junit4] 2> =09at org.eclipse.jetty.servlet.ServletHandler.doScope(Ser= vletHandler.java:512) [junit4] 2> =09at org.eclipse.jetty.server.session.SessionHandler.doSc= ope(SessionHandler.java:185) [junit4] 2> =09at org.eclipse.jetty.server.handler.ContextHandler.doSc= ope(ContextHandler.java:1112) [junit4] 2> =09at org.eclipse.jetty.server.handler.ScopedHandler.handl= e(ScopedHandler.java:141) [junit4] 2> =09at org.eclipse.jetty.server.handler.gzip.GzipHandler.ha= ndle(GzipHandler.java:462) [junit4] 2> =09at org.eclipse.jetty.server.handler.HandlerWrapper.hand= le(HandlerWrapper.java:134) [junit4] 2> =09at org.eclipse.jetty.server.Server.handle(Server.java:5= 34) [junit4] 2> =09at org.eclipse.jetty.server.HttpChannel.handle(HttpChan= nel.java:320) [junit4] 2> =09at org.eclipse.jetty.server.HttpConnection.onFillable(H= ttpConnection.java:251) [junit4] 2> =09at org.eclipse.jetty.io.AbstractConnection$ReadCallback= .succeeded(AbstractConnection.java:273) [junit4] 2> =09at org.eclipse.jetty.io.FillInterest.fillable(FillInter= est.java:95) [junit4] 2> =09at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(Sel= ectChannelEndPoint.java:93) [junit4] 2> =09at org.eclipse.jetty.util.thread.strategy.ExecuteProduc= eConsume.executeProduceConsume(ExecuteProduceConsume.java:303) [junit4] 2> =09at org.eclipse.jetty.util.thread.strategy.ExecuteProduc= eConsume.produceConsume(ExecuteProduceConsume.java:148) [junit4] 2> =09at org.eclipse.jetty.util.thread.strategy.ExecuteProduc= eConsume.run(ExecuteProduceConsume.java:136) [junit4] 2> =09at org.eclipse.jetty.util.thread.QueuedThreadPool.runJo= b(QueuedThreadPool.java:671) [junit4] 2> =09at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run= (QueuedThreadPool.java:589) [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [junit4] 2>=20 [junit4] 2> 916170 INFO (qtp30608233-7925) [n:127.0.0.1:34087_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections par= ams=3D{action=3DCREATE&numShards=3D2&wt=3Djavabin&version=3D2} status=3D400= QTime=3D0 [junit4] 2> 916170 INFO (TEST-CollectionsAPIDistributedZkTest.testMis= singRequiredParameters-seed#[E7BF4C35D486CF13]) [ ] o.a.s.SolrTestCaseJ4= ###Ending testMissingRequiredParameters [junit4] 2> 916194 INFO (TEST-CollectionsAPIDistributedZkTest.testSpe= cificConfigsets-seed#[E7BF4C35D486CF13]) [ ] o.a.s.SolrTestCaseJ4 ###Sta= rting testSpecificConfigsets [junit4] 2> 916196 INFO (TEST-CollectionsAPIDistributedZkTest.testSpe= cificConfigsets-seed#[E7BF4C35D486CF13]) [ ] o.a.s.c.c.ZkStateReader Upd= ated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 916198 INFO (qtp30608233-7930) [n:127.0.0.1:34087_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with para= ms replicationFactor=3D1&collection.configName=3Dconf2&name=3Dwithconfigset= 2&action=3DCREATE&numShards=3D1&wt=3Djavabin&version=3D2 and sendToOCPQueue= =3Dtrue [junit4] 2> 916200 INFO (OverseerThreadFactory-3000-thread-5-processi= ng-n:127.0.0.1:34669_solr) [n:127.0.0.1:34669_solr ] o.a.s.c.CreateColle= ctionCmd Create collection withconfigset2 [junit4] 2> 916201 INFO (OverseerCollectionConfigSetProcessor-9884497= 2763250697-127.0.0.1:34669_solr-n_0000000000) [n:127.0.0.1:34669_solr ] = o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work= /qnr-0000000006 doesn't exist. Requestor may have disconnected from ZooKee= per [junit4] 2> 916407 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr = ] o.a.s.h.a.CoreAdminOperation core create command qt=3D/admin/cores&colle= ction.configName=3Dconf2&newCollection=3Dtrue&name=3Dwithconfigset2_shard1_= replica1&action=3DCREATE&numShards=3D1&collection=3Dwithconfigset2&shard=3D= shard1&wt=3Djavabin&version=3D2 [junit4] 2> 916512 INFO (zkCallback-1042-thread-1-processing-n:127.0.= 0.1:34087_solr) [n:127.0.0.1:34087_solr ] o.a.s.c.c.ZkStateReader A clus= ter state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pa= th:/collections/withconfigset2/state.json] for collection [withconfigset2] = has occurred - updating... (live nodes size: [4]) [junit4] 2> 917421 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr c:= withconfigset2 s:shard1 x:withconfigset2_shard1_replica1] o.a.s.c.SolrConf= ig Using Lucene MatchVersion: 6.6.2 [junit4] 2> 917432 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr c:= withconfigset2 s:shard1 x:withconfigset2_shard1_replica1] o.a.s.s.IndexSch= ema [withconfigset2_shard1_replica1] Schema name=3Dminimal [junit4] 2> 917449 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr c:= withconfigset2 s:shard1 x:withconfigset2_shard1_replica1] o.a.s.s.IndexSch= ema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 917449 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr c:= withconfigset2 s:shard1 x:withconfigset2_shard1_replica1] o.a.s.c.CoreCont= ainer Creating SolrCore 'withconfigset2_shard1_replica1' using configuratio= n from collection withconfigset2, trusted=3Dtrue [junit4] 2> 917449 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr c:= withconfigset2 s:shard1 x:withconfigset2_shard1_replica1] o.a.s.c.SolrCore= solr.RecoveryStrategy.Builder [junit4] 2> 917449 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr c:= withconfigset2 s:shard1 x:withconfigset2_shard1_replica1] o.a.s.c.SolrCore= [[withconfigset2_shard1_replica1] ] Opening new SolrCore at [/home/jenkins= /workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.clo= ud.CollectionsAPIDistributedZkTest_E7BF4C35D486CF13-001/tempDir-001/node3/w= ithconfigset2_shard1_replica1], dataDir=3D[/home/jenkins/workspace/Lucene-S= olr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDi= stributedZkTest_E7BF4C35D486CF13-001/tempDir-001/node3/./withconfigset2_sha= rd1_replica1/data/] [junit4] 2> 917486 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr c:= withconfigset2 s:shard1 x:withconfigset2_shard1_replica1] o.a.s.u.UpdateHa= ndler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 917486 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr c:= withconfigset2 s:shard1 x:withconfigset2_shard1_replica1] o.a.s.u.UpdateLo= g Initializing UpdateLog: dataDir=3Dnull defaultSyncLevel=3DFLUSH numRecord= sToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 917486 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr c:= withconfigset2 s:shard1 x:withconfigset2_shard1_replica1] o.a.s.u.CommitTr= acker Hard AutoCommit: disabled [junit4] 2> 917486 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr c:= withconfigset2 s:shard1 x:withconfigset2_shard1_replica1] o.a.s.u.CommitTr= acker Soft AutoCommit: disabled [junit4] 2> 917487 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr c:= withconfigset2 s:shard1 x:withconfigset2_shard1_replica1] o.a.s.s.SolrInde= xSearcher Opening [Searcher@463d33[withconfigset2_shard1_replica1] main] [junit4] 2> 917488 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr c:= withconfigset2 s:shard1 x:withconfigset2_shard1_replica1] o.a.s.r.ManagedR= esourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf2 [junit4] 2> 917489 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr c:= withconfigset2 s:shard1 x:withconfigset2_shard1_replica1] o.a.s.r.ManagedR= esourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorag= eIO:path=3D/configs/conf2 [junit4] 2> 917489 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr c:= withconfigset2 s:shard1 x:withconfigset2_shard1_replica1] o.a.s.h.Replicat= ionHandler Commits will be reserved for 10000 [junit4] 2> 917489 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr c:= withconfigset2 s:shard1 x:withconfigset2_shard1_replica1] o.a.s.u.UpdateLo= g Could not find max version in index or recent updates, using new clock 15= 81519572583841792 [junit4] 2> 917489 INFO (searcherExecutor-3019-thread-1-processing-n:= 127.0.0.1:34087_solr x:withconfigset2_shard1_replica1 s:shard1 c:withconfig= set2) [n:127.0.0.1:34087_solr c:withconfigset2 s:shard1 x:withconfigset2_s= hard1_replica1] o.a.s.c.SolrCore [withconfigset2_shard1_replica1] Registere= d new searcher Searcher@463d33[withconfigset2_shard1_replica1] main{Exitabl= eDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 917498 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr c:= withconfigset2 s:shard1 x:withconfigset2_shard1_replica1] o.a.s.c.ShardLea= derElectionContext Enough replicas found to continue. [junit4] 2> 917498 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr c:= withconfigset2 s:shard1 x:withconfigset2_shard1_replica1] o.a.s.c.ShardLea= derElectionContext I may be the new leader - try and sync [junit4] 2> 917498 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr c:= withconfigset2 s:shard1 x:withconfigset2_shard1_replica1] o.a.s.c.SyncStra= tegy Sync replicas to http://127.0.0.1:34087/solr/withconfigset2_shard1_rep= lica1/ [junit4] 2> 917498 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr c:= withconfigset2 s:shard1 x:withconfigset2_shard1_replica1] o.a.s.c.SyncStra= tegy Sync Success - now sync replicas to me [junit4] 2> 917498 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr c:= withconfigset2 s:shard1 x:withconfigset2_shard1_replica1] o.a.s.c.SyncStra= tegy http://127.0.0.1:34087/solr/withconfigset2_shard1_replica1/ has no rep= licas [junit4] 2> 917498 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr c:= withconfigset2 s:shard1 x:withconfigset2_shard1_replica1] o.a.s.c.ShardLea= derElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 917502 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr c:= withconfigset2 s:shard1 x:withconfigset2_shard1_replica1] o.a.s.c.ShardLea= derElectionContext I am the new leader: http://127.0.0.1:34087/solr/withcon= figset2_shard1_replica1/ shard1 [junit4] 2> 917605 INFO (zkCallback-1042-thread-1-processing-n:127.0.= 0.1:34087_solr) [n:127.0.0.1:34087_solr ] o.a.s.c.c.ZkStateReader A clus= ter state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pa= th:/collections/withconfigset2/state.json] for collection [withconfigset2] = has occurred - updating... (live nodes size: [4]) [junit4] 2> 917653 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr c:= withconfigset2 s:shard1 x:withconfigset2_shard1_replica1] o.a.s.c.ZkContro= ller I am the leader, no recovery necessary [junit4] 2> 917654 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr c:= withconfigset2 s:shard1 x:withconfigset2_shard1_replica1] o.a.s.s.HttpSolr= Call [admin] webapp=3Dnull path=3D/admin/cores params=3D{qt=3D/admin/cores&= collection.configName=3Dconf2&newCollection=3Dtrue&name=3Dwithconfigset2_sh= ard1_replica1&action=3DCREATE&numShards=3D1&collection=3Dwithconfigset2&sha= rd=3Dshard1&wt=3Djavabin&version=3D2} status=3D0 QTime=3D1247 [junit4] 2> 917656 INFO (qtp30608233-7930) [n:127.0.0.1:34087_solr = ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at= most 30 seconds. Check all shard replicas [junit4] 2> 917756 INFO (zkCallback-1042-thread-1-processing-n:127.0.= 0.1:34087_solr) [n:127.0.0.1:34087_solr ] o.a.s.c.c.ZkStateReader A clus= ter state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pa= th:/collections/withconfigset2/state.json] for collection [withconfigset2] = has occurred - updating... (live nodes size: [4]) [junit4] 2> 918203 INFO (OverseerCollectionConfigSetProcessor-9884497= 2763250697-127.0.0.1:34669_solr-n_0000000000) [n:127.0.0.1:34669_solr ] = o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work= /qnr-0000000008 doesn't exist. Requestor may have disconnected from ZooKee= per [junit4] 2> 918656 INFO (qtp30608233-7930) [n:127.0.0.1:34087_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections par= ams=3D{replicationFactor=3D1&collection.configName=3Dconf2&name=3Dwithconfi= gset2&action=3DCREATE&numShards=3D1&wt=3Djavabin&version=3D2} status=3D0 QT= ime=3D2458 [junit4] 2> 918657 INFO (TEST-CollectionsAPIDistributedZkTest.testSpe= cificConfigsets-seed#[E7BF4C35D486CF13]) [ ] o.a.s.SolrTestCaseJ4 ###End= ing testSpecificConfigsets [junit4] 2> 918682 INFO (TEST-CollectionsAPIDistributedZkTest.testCol= lectionsAPI-seed#[E7BF4C35D486CF13]) [ ] o.a.s.SolrTestCaseJ4 ###Startin= g testCollectionsAPI [junit4] 2> 918683 INFO (TEST-CollectionsAPIDistributedZkTest.testCol= lectionsAPI-seed#[E7BF4C35D486CF13]) [ ] o.a.s.c.c.ZkStateReader Updated= live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 918685 INFO (qtp30608233-7928) [n:127.0.0.1:34087_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with para= ms name=3Dwithconfigset2&action=3DDELETE&wt=3Djavabin&version=3D2 and sendT= oOCPQueue=3Dtrue [junit4] 2> 918686 INFO (OverseerThreadFactory-3000-thread-5-processi= ng-n:127.0.0.1:34669_solr) [n:127.0.0.1:34669_solr ] o.a.s.c.OverseerCol= lectionMessageHandler Executing Collection Cmd : action=3DUNLOAD&deleteInst= anceDir=3Dtrue&deleteDataDir=3Dtrue [junit4] 2> 918689 INFO (qtp30608233-7922) [n:127.0.0.1:34087_solr = ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.withco= nfigset2.shard1.replica1 [junit4] 2> 918689 INFO (qtp30608233-7922) [n:127.0.0.1:34087_solr = ] o.a.s.c.SolrCore [withconfigset2_shard1_replica1] CLOSING SolrCore org.= apache.solr.core.SolrCore@ecf179 [junit4] 2> 918690 INFO (qtp30608233-7922) [n:127.0.0.1:34087_solr = ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.withco= nfigset2.shard1.replica1 [junit4] 2> 918691 INFO (qtp30608233-7922) [n:127.0.0.1:34087_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/cores params=3D= {deleteInstanceDir=3Dtrue&core=3Dwithconfigset2_shard1_replica1&qt=3D/admin= /cores&deleteDataDir=3Dtrue&action=3DUNLOAD&wt=3Djavabin&version=3D2} statu= s=3D0 QTime=3D2 [junit4] 2> 919396 INFO (qtp30608233-7928) [n:127.0.0.1:34087_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections par= ams=3D{name=3Dwithconfigset2&action=3DDELETE&wt=3Djavabin&version=3D2} stat= us=3D0 QTime=3D711 [junit4] 2> 919399 INFO (qtp30608233-7925) [n:127.0.0.1:34087_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with para= ms async=3D1fa45880-6acf-40ba-a04a-ceeca1677467&replicationFactor=3D2&colle= ction.configName=3Dconf2&maxShardsPerNode=3D2&name=3Dawhollynewcollection_0= &action=3DCREATE&numShards=3D2&wt=3Djavabin&version=3D2 and sendToOCPQueue= =3Dtrue [junit4] 2> 919401 INFO (qtp30608233-7925) [n:127.0.0.1:34087_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections par= ams=3D{async=3D1fa45880-6acf-40ba-a04a-ceeca1677467&replicationFactor=3D2&c= ollection.configName=3Dconf2&maxShardsPerNode=3D2&name=3Dawhollynewcollecti= on_0&action=3DCREATE&numShards=3D2&wt=3Djavabin&version=3D2} status=3D0 QTi= me=3D1 [junit4] 2> 919403 INFO (OverseerThreadFactory-3000-thread-5-processi= ng-n:127.0.0.1:34669_solr) [n:127.0.0.1:34669_solr ] o.a.s.c.CreateColle= ctionCmd Create collection awhollynewcollection_0 [junit4] 2> 919411 INFO (OverseerCollectionConfigSetProcessor-9884497= 2763250697-127.0.0.1:34669_solr-n_0000000000) [n:127.0.0.1:34669_solr ] = o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work= /qnr-0000000010 doesn't exist. Requestor may have disconnected from ZooKee= per [junit4] 2> 919514 INFO (zkCallback-1059-thread-1) [ ] o.a.s.c.c.Z= kStateReader A cluster state change: [WatchedEvent state:SyncConnected type= :NodeCreated path:/collections/awhollynewcollection_0/state.json] for colle= ction [awhollynewcollection_0] has occurred - updating... (live nodes size:= [4]) [junit4] 2> 919616 INFO (qtp1803554-7946) [n:127.0.0.1:34415_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/cores params=3D{= async=3D1fa45880-6acf-40ba-a04a-ceeca16774674173448709179106&qt=3D/admin/co= res&collection.configName=3Dconf2&newCollection=3Dtrue&name=3Dawhollynewcol= lection_0_shard2_replica2&action=3DCREATE&numShards=3D2&collection=3Dawholl= ynewcollection_0&shard=3Dshard2&wt=3Djavabin&version=3D2} status=3D0 QTime= =3D0 [junit4] 2> 919617 INFO (qtp18709063-7960) [n:127.0.0.1:37751_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/cores params=3D= {async=3D1fa45880-6acf-40ba-a04a-ceeca16774674173448709083027&qt=3D/admin/c= ores&collection.configName=3Dconf2&newCollection=3Dtrue&name=3Dawhollynewco= llection_0_shard1_replica1&action=3DCREATE&numShards=3D2&collection=3Dawhol= lynewcollection_0&shard=3Dshard1&wt=3Djavabin&version=3D2} status=3D0 QTime= =3D1 [junit4] 2> 919617 INFO (parallelCoreAdminExecutor-3005-thread-1-proc= essing-n:127.0.0.1:34415_solr 1fa45880-6acf-40ba-a04a-ceeca1677467417344870= 9179106 CREATE) [n:127.0.0.1:34415_solr ] o.a.s.h.a.CoreAdminOperation c= ore create command async=3D1fa45880-6acf-40ba-a04a-ceeca1677467417344870917= 9106&qt=3D/admin/cores&collection.configName=3Dconf2&newCollection=3Dtrue&n= ame=3Dawhollynewcollection_0_shard2_replica2&action=3DCREATE&numShards=3D2&= collection=3Dawhollynewcollection_0&shard=3Dshard2&wt=3Djavabin&version=3D2 [junit4] 2> 919618 INFO (parallelCoreAdminExecutor-3005-thread-1-proc= essing-n:127.0.0.1:34415_solr 1fa45880-6acf-40ba-a04a-ceeca1677467417344870= 9179106 CREATE) [n:127.0.0.1:34415_solr ] o.a.s.c.TransientSolrCoreCache= Default Allocating transient cache for 2147483647 transient cores [junit4] 2> 919618 INFO (parallelCoreAdminExecutor-3001-thread-1-proc= essing-n:127.0.0.1:37751_solr 1fa45880-6acf-40ba-a04a-ceeca1677467417344870= 9083027 CREATE) [n:127.0.0.1:37751_solr ] o.a.s.h.a.CoreAdminOperation c= ore create command async=3D1fa45880-6acf-40ba-a04a-ceeca1677467417344870908= 3027&qt=3D/admin/cores&collection.configName=3Dconf2&newCollection=3Dtrue&n= ame=3Dawhollynewcollection_0_shard1_replica1&action=3DCREATE&numShards=3D2&= collection=3Dawhollynewcollection_0&shard=3Dshard1&wt=3Djavabin&version=3D2 [junit4] 2> 919618 INFO (parallelCoreAdminExecutor-3001-thread-1-proc= essing-n:127.0.0.1:37751_solr 1fa45880-6acf-40ba-a04a-ceeca1677467417344870= 9083027 CREATE) [n:127.0.0.1:37751_solr ] o.a.s.c.TransientSolrCoreCache= Default Allocating transient cache for 2147483647 transient cores [junit4] 2> 919619 INFO (qtp28308953-7941) [n:127.0.0.1:34669_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/cores params=3D= {async=3D1fa45880-6acf-40ba-a04a-ceeca16774674173448710859517&qt=3D/admin/c= ores&collection.configName=3Dconf2&newCollection=3Dtrue&name=3Dawhollynewco= llection_0_shard1_replica2&action=3DCREATE&numShards=3D2&collection=3Dawhol= lynewcollection_0&shard=3Dshard1&wt=3Djavabin&version=3D2} status=3D0 QTime= =3D0 [junit4] 2> 919619 INFO (parallelCoreAdminExecutor-3007-thread-1-proc= essing-n:127.0.0.1:34669_solr 1fa45880-6acf-40ba-a04a-ceeca1677467417344871= 0859517 CREATE) [n:127.0.0.1:34669_solr ] o.a.s.h.a.CoreAdminOperation c= ore create command async=3D1fa45880-6acf-40ba-a04a-ceeca1677467417344871085= 9517&qt=3D/admin/cores&collection.configName=3Dconf2&newCollection=3Dtrue&n= ame=3Dawhollynewcollection_0_shard1_replica2&action=3DCREATE&numShards=3D2&= collection=3Dawhollynewcollection_0&shard=3Dshard1&wt=3Djavabin&version=3D2 [junit4] 2> 919620 INFO (qtp30608233-7929) [n:127.0.0.1:34087_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/cores params=3D= {async=3D1fa45880-6acf-40ba-a04a-ceeca16774674173448709227401&qt=3D/admin/c= ores&collection.configName=3Dconf2&newCollection=3Dtrue&name=3Dawhollynewco= llection_0_shard2_replica1&action=3DCREATE&numShards=3D2&collection=3Dawhol= lynewcollection_0&shard=3Dshard2&wt=3Djavabin&version=3D2} status=3D0 QTime= =3D1 [junit4] 2> 919622 INFO (parallelCoreAdminExecutor-3002-thread-1-proc= essing-n:127.0.0.1:34087_solr 1fa45880-6acf-40ba-a04a-ceeca1677467417344870= 9227401 CREATE) [n:127.0.0.1:34087_solr ] o.a.s.h.a.CoreAdminOperation c= ore create command async=3D1fa45880-6acf-40ba-a04a-ceeca1677467417344870922= 7401&qt=3D/admin/cores&collection.configName=3Dconf2&newCollection=3Dtrue&n= ame=3Dawhollynewcollection_0_shard2_replica1&action=3DCREATE&numShards=3D2&= collection=3Dawhollynewcollection_0&shard=3Dshard2&wt=3Djavabin&version=3D2 [junit4] 2> 919626 INFO (qtp30608233-7922) [n:127.0.0.1:34087_solr = ] o.a.s.h.a.CoreAdminOperation Checking request status for : 1fa45880-6acf= -40ba-a04a-ceeca16774674173448709227401 [junit4] 2> 919626 INFO (qtp30608233-7922) [n:127.0.0.1:34087_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/cores params=3D= {qt=3D/admin/cores&requestid=3D1fa45880-6acf-40ba-a04a-ceeca167746741734487= 09227401&action=3DREQUESTSTATUS&wt=3Djavabin&version=3D2} status=3D0 QTime= =3D0 [junit4] 2> 919729 INFO (zkCallback-1052-thread-1-processing-n:127.0.= 0.1:37751_solr) [n:127.0.0.1:37751_solr ] o.a.s.c.c.ZkStateReader A clus= ter state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pa= th:/collections/awhollynewcollection_0/state.json] for collection [awhollyn= ewcollection_0] has occurred - updating... (live nodes size: [4]) [junit4] 2> 919729 INFO (zkCallback-1047-thread-1-processing-n:127.0.= 0.1:34415_solr) [n:127.0.0.1:34415_solr ] o.a.s.c.c.ZkStateReader A clus= ter state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pa= th:/collections/awhollynewcollection_0/state.json] for collection [awhollyn= ewcollection_0] has occurred - updating... (live nodes size: [4]) [junit4] 2> 919729 INFO (zkCallback-1042-thread-1-processing-n:127.0.= 0.1:34087_solr) [n:127.0.0.1:34087_solr ] o.a.s.c.c.ZkStateReader A clus= ter state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pa= th:/collections/awhollynewcollection_0/state.json] for collection [awhollyn= ewcollection_0] has occurred - updating... (live nodes size: [4]) [junit4] 2> 919729 INFO (zkCallback-1059-thread-1) [ ] o.a.s.c.c.Z= kStateReader A cluster state change: [WatchedEvent state:SyncConnected type= :NodeDataChanged path:/collections/awhollynewcollection_0/state.json] for c= ollection [awhollynewcollection_0] has occurred - updating... (live nodes s= ize: [4]) [junit4] 2> 919729 INFO (zkCallback-1046-thread-2-processing-n:127.0.= 0.1:34669_solr) [n:127.0.0.1:34669_solr ] o.a.s.c.c.ZkStateReader A clus= ter state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pa= th:/collections/awhollynewcollection_0/state.json] for collection [awhollyn= ewcollection_0] has occurred - updating... (live nodes size: [4]) [junit4] 2> 920632 INFO (qtp30608233-7928) [n:127.0.0.1:34087_solr = ] o.a.s.h.a.CoreAdminOperation Checking request status for : 1fa45880-6acf= -40ba-a04a-ceeca16774674173448709227401 [junit4] 2> 920632 INFO (qtp30608233-7928) [n:127.0.0.1:34087_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/cores params=3D= {qt=3D/admin/cores&requestid=3D1fa45880-6acf-40ba-a04a-ceeca167746741734487= 09227401&action=3DREQUESTSTATUS&wt=3Djavabin&version=3D2} status=3D0 QTime= =3D0 [junit4] 2> 920634 INFO (parallelCoreAdminExecutor-3001-thread-1-proc= essing-n:127.0.0.1:37751_solr 1fa45880-6acf-40ba-a04a-ceeca1677467417344870= 9083027 CREATE) [n:127.0.0.1:37751_solr c:awhollynewcollection_0 s:shard1 = x:awhollynewcollection_0_shard1_replica1] o.a.s.c.SolrConfig Using Lucene M= atchVersion: 6.6.2 [junit4] 2> 920634 INFO (parallelCoreAdminExecutor-3002-thread-1-proc= essing-n:127.0.0.1:34087_solr 1fa45880-6acf-40ba-a04a-ceeca1677467417344870= 9227401 CREATE) [n:127.0.0.1:34087_solr c:awhollynewcollection_0 s:shard2 = x:awhollynewcollection_0_shard2_replica1] o.a.s.c.SolrConfig Using Lucene M= atchVersion: 6.6.2 [junit4] 2> 920650 INFO (parallelCoreAdminExecutor-3007-thread-1-proc= essing-n:127.0.0.1:34669_solr 1fa45880-6acf-40ba-a04a-ceeca1677467417344871= 0859517 CREATE) [n:127.0.0.1:34669_solr c:awhollynewcollection_0 s:shard1 = x:awhollynewcollection_0_shard1_replica2] o.a.s.c.SolrConfig Using Lucene M= atchVersion: 6.6.2 [junit4] 2> 920651 INFO (parallelCoreAdminExecutor-3005-thread-1-proc= essing-n:127.0.0.1:34415_solr 1fa45880-6acf-40ba-a04a-ceeca1677467417344870= 9179106 CREATE) [n:127.0.0.1:34415_solr c:awhollynewcollection_0 s:shard2 = x:awhollynewcollection_0_shard2_replica2] o.a.s.c.SolrConfig Using Lucene M= atchVersion: 6.6.2 [junit4] 2> 920653 INFO (parallelCoreAdminExecutor-3002-thread-1-proc= essing-n:127.0.0.1:34087_solr 1fa45880-6acf-40ba-a04a-ceeca1677467417344870= 9227401 CREATE) [n:127.0.0.1:34087_solr c:awhollynewcollection_0 s:shard2 = x:awhollynewcollection_0_shard2_replica1] o.a.s.s.IndexSchema [awhollynewco= llection_0_shard2_replica1] Schema name=3Dminimal [junit4] 2> 920654 INFO (parallelCoreAdminExecutor-3001-thread-1-proc= essing-n:127.0.0.1:37751_solr 1fa45880-6acf-40ba-a04a-ceeca1677467417344870= 9083027 CREATE) [n:127.0.0.1:37751_solr c:awhollynewcollection_0 s:shard1 = x:awhollynewcollection_0_shard1_replica1] o.a.s.s.IndexSchema [awhollynewco= llection_0_shard1_replica1] Schema name=3Dminimal [junit4] 2> 920662 INFO (parallelCoreAdminExecutor-3001-thread-1-proc= essing-n:127.0.0.1:37751_solr 1fa45880-6acf-40ba-a04a-ceeca1677467417344870= 9083027 CREATE) [n:127.0.0.1:37751_solr c:awhollynewcollection_0 s:shard1 = x:awhollynewcollection_0_shard1_replica1] o.a.s.s.IndexSchema Loaded schema= minimal/1.1 with uniqueid field id [junit4] 2> 920663 INFO (parallelCoreAdminExecutor-3002-thread-1-proc= essing-n:127.0.0.1:34087_solr 1fa45880-6acf-40ba-a04a-ceeca1677467417344870= 9227401 CREATE) [n:127.0.0.1:34087_solr c:awhollynewcollection_0 s:shard2 = x:awhollynewcollection_0_shard2_replica1] o.a.s.s.IndexSchema Loaded schema= minimal/1.1 with uniqueid field id [junit4] 2> 920663 INFO (parallelCoreAdminExecutor-3001-thread-1-proc= essing-n:127.0.0.1:37751_solr 1fa45880-6acf-40ba-a04a-ceeca1677467417344870= 9083027 CREATE) [n:127.0.0.1:37751_solr c:awhollynewcollection_0 s:shard1 = x:awhollynewcollection_0_shard1_replica1] o.a.s.c.CoreContainer Creating So= lrCore 'awhollynewcollection_0_shard1_replica1' using configuration from co= llection awhollynewcollection_0, trusted=3Dtrue [junit4] 2> 920663 INFO (parallelCoreAdminExecutor-3002-thread-1-proc= essing-n:127.0.0.1:34087_solr 1fa45880-6acf-40ba-a04a-ceeca1677467417344870= 9227401 CREATE) [n:127.0.0.1:34087_solr c:awhollynewcollection_0 s:shard2 = x:awhollynewcollection_0_shard2_replica1] o.a.s.c.CoreContainer Creating So= lrCore 'awhollynewcollection_0_shard2_replica1' using configuration from co= llection awhollynewcollection_0, trusted=3Dtrue [junit4] 2> 920663 INFO (parallelCoreAdminExecutor-3002-thread-1-proc= essing-n:127.0.0.1:34087_solr 1fa45880-6acf-40ba-a04a-ceeca1677467417344870= 9227401 CREATE) [n:127.0.0.1:34087_solr c:awhollynewcollection_0 s:shard2 = x:awhollynewcollection_0_shard2_replica1] o.a.s.c.SolrCore solr.RecoveryStr= ategy.Builder [junit4] 2> 920663 INFO (parallelCoreAdminExecutor-3002-thread-1-proc= essing-n:127.0.0.1:34087_solr 1fa45880-6acf-40ba-a04a-ceeca1677467417344870= 9227401 CREATE) [n:127.0.0.1:34087_solr c:awhollynewcollection_0 s:shard2 = x:awhollynewcollection_0_shard2_replica1] o.a.s.c.SolrCore [[awhollynewcoll= ection_0_shard2_replica1] ] Opening new SolrCore at [/home/jenkins/workspac= e/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.Collec= tionsAPIDistributedZkTest_E7BF4C35D486CF13-001/tempDir-001/node3/awhollynew= collection_0_shard2_replica1], dataDir=3D[/home/jenkins/workspace/Lucene-So= lr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDis= tributedZkTest_E7BF4C35D486CF13-001/tempDir-001/node3/./awhollynewcollectio= n_0_shard2_replica1/data/] [junit4] 2> 920663 INFO (parallelCoreAdminExecutor-3001-thread-1-proc= essing-n:127.0.0.1:37751_solr 1fa45880-6acf-40ba-a04a-ceeca1677467417344870= 9083027 CREATE) [n:127.0.0.1:37751_solr c:awhollynewcollection_0 s:shard1 = x:awhollynewcollection_0_shard1_replica1] o.a.s.c.SolrCore solr.RecoveryStr= ategy.Builder [junit4] 2> 920664 INFO (parallelCoreAdminExecutor-3001-thread-1-proc= essing-n:127.0.0.1:37751_solr 1fa45880-6acf-40ba-a04a-ceeca1677467417344870= 9083027 CREATE) [n:127.0.0.1:37751_solr c:awhollynewcollection_0 s:shard1 = x:awhollynewcollection_0_shard1_replica1] o.a.s.c.SolrCore [[awhollynewcoll= ection_0_shard1_replica1] ] Opening new SolrCore at [/home/jenkins/workspac= e/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.Collec= tionsAPIDistributedZkTest_E7BF4C35D486CF13-001/tempDir-001/node1/awhollynew= collection_0_shard1_replica1], dataDir=3D[/home/jenkins/workspace/Lucene-So= lr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDis= tributedZkTest_E7BF4C35D486CF13-001/tempDir-001/node1/./awhollynewcollectio= n_0_shard1_replica1/data/] [junit4] 2> 920667 INFO (parallelCoreAdminExecutor-3007-thread-1-proc= essing-n:127.0.0.1:34669_solr 1fa45880-6acf-40ba-a04a-ceeca1677467417344871= 0859517 CREATE) [n:127.0.0.1:34669_solr c:awhollynewcollection_0 s:shard1 = x:awhollynewcollection_0_shard1_replica2] o.a.s.s.IndexSchema [awhollynewco= llection_0_shard1_replica2] Schema name=3Dminimal [junit4] 2> 920667 INFO (parallelCoreAdminExecutor-3005-thread-1-proc= essing-n:127.0.0.1:34415_solr 1fa45880-6acf-40ba-a04a-ceeca1677467417344870= 9179106 CREATE) [n:127.0.0.1:34415_solr c:awhollynewcollection_0 s:shard2 = x:awhollynewcollection_0_shard2_replica2] o.a.s.s.IndexSchema [awhollynewco= llection_0_shard2_replica2] Schema name=3Dminimal [junit4] 2> 920669 INFO (parallelCoreAdminExecutor-3007-thread-1-proc= essing-n:127.0.0.1:34669_solr 1fa45880-6acf-40ba-a04a-ceeca1677467417344871= 0859517 CREATE) [n:127.0.0.1:34669_solr c:awhollynewcollection_0 s:shard1 = x:awhollynewcollection_0_shard1_replica2] o.a.s.s.IndexSchema Loaded schema= minimal/1.1 with uniqueid field id [junit4] 2> 920669 INFO (parallelCoreAdminExecutor-3007-thread-1-proc= essing-n:127.0.0.1:34669_solr 1fa45880-6acf-40ba-a04a-ceeca1677467417344871= 0859517 CREATE) [n:127.0.0.1:34669_solr c:awhollynewcollection_0 s:shard1 = x:awhollynewcollection_0_shard1_replica2] o.a.s.c.CoreContainer Creating So= lrCore 'awhollynewcollection_0_shard1_replica2' using configuration from co= llection awhollynewcollection_0, trusted=3Dtrue [junit4] 2> 920670 INFO (parallelCoreAdminExecutor-3007-thread-1-proc= essing-n:127.0.0.1:34669_solr 1fa45880-6acf-40ba-a04a-ceeca1677467417344871= 0859517 CREATE) [n:127.0.0.1:34669_solr c:awhollynewcollection_0 s:shard1 = x:awhollynewcollection_0_shard1_replica2] o.a.s.c.SolrCore solr.RecoveryStr= ategy.Builder [junit4] 2> 920670 INFO (parallelCoreAdminExecutor-3007-thread-1-proc= essing-n:127.0.0.1:34669_solr 1fa45880-6acf-40ba-a04a-ceeca1677467417344871= 0859517 CREATE) [n:127.0.0.1:34669_solr c:awhollynewcollection_0 s:shard1 = x:awhollynewcollection_0_shard1_replica2] o.a.s.c.SolrCore [[awhollynewcoll= ection_0_shard1_replica2] ] Opening new SolrCore at [/home/jenkins/workspac= e/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.Collec= tionsAPIDistributedZkTest_E7BF4C35D486CF13-001/tempDir-001/node4/awhollynew= collection_0_shard1_replica2], dataDir=3D[/home/jenkins/workspace/Lucene-So= lr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDis= tributedZkTest_E7BF4C35D486CF13-001/tempDir-001/node4/./awhollynewcollectio= n_0_shard1_replica2/data/] [junit4] 2> 920671 INFO (parallelCoreAdminExecutor-3005-thread-1-proc= essing-n:127.0.0.1:34415_solr 1fa45880-6acf-40ba-a04a-ceeca1677467417344870= 9179106 CREATE) [n:127.0.0.1:34415_solr c:awhollynewcollection_0 s:shard2 = x:awhollynewcollection_0_shard2_replica2] o.a.s.s.IndexSchema Loaded schema= minimal/1.1 with uniqueid field id [junit4] 2> 920671 INFO (parallelCoreAdminExecutor-3005-thread-1-proc= essing-n:127.0.0.1:34415_solr 1fa45880-6acf-40ba-a04a-ceeca1677467417344870= 9179106 CREATE) [n:127.0.0.1:34415_solr c:awhollynewcollection_0 s:shard2 = x:awhollynewcollection_0_shard2_replica2] o.a.s.c.CoreContainer Creating So= lrCore 'awhollynewcollection_0_shard2_replica2' using configuration from co= llection awhollynewcollection_0, trusted=3Dtrue [junit4] 2> 920671 INFO (parallelCoreAdminExecutor-3005-thread-1-proc= essing-n:127.0.0.1:34415_solr 1fa45880-6acf-40ba-a04a-ceeca1677467417344870= 9179106 C [...truncated too long message...] ertionsRequired.java:53) [junit4] 2> =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluat= e(TestRuleMarkFailure.java:47) [junit4] 2> =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailure= s$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) [junit4] 2> =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.ev= aluate(TestRuleIgnoreTestSuites.java:54) [junit4] 2> =09at com.carrotsearch.randomizedtesting.rules.StatementAd= apter.evaluate(StatementAdapter.java:36) [junit4] 2> =09at com.carrotsearch.randomizedtesting.ThreadLeakControl= $StatementRunner.run(ThreadLeakControl.java:368) [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [junit4] 2>=20 [junit4] 2> 1201277 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[E7BF4= C35D486CF13]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testMaxDoc= s [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DAutoCommitTes= t -Dtests.method=3DtestMaxDocs -Dtests.seed=3DE7BF4C35D486CF13 -Dtests.mult= iplier=3D3 -Dtests.slow=3Dtrue -Dtests.locale=3Duk -Dtests.timezone=3DVST -= Dtests.asserts=3Dtrue -Dtests.file.encoding=3DUS-ASCII [junit4] ERROR 0.24s J1 | AutoCommitTest.testMaxDocs <<< [junit4] > Throwable #1: java.lang.RuntimeException: Exception during= query [junit4] > =09at __randomizedtesting.SeedInfo.seed([E7BF4C35D486CF13:= 5E3E9AEAF86CCB99]:0) [junit4] > =09at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ= 4.java:895) [junit4] > =09at org.apache.solr.update.AutoCommitTest.testMaxDocs(Au= toCommitTest.java:225) [junit4] > =09at java.lang.Thread.run(Thread.java:748) [junit4] > Caused by: java.lang.RuntimeException: REQUEST FAILED: xpa= th=3D//result[@numFound=3D1] [junit4] > =09xml response was: [junit4] > [junit4] > 0<= int name=3D"QTime">0 [junit4] > [junit4] > =09request was:q=3Did:14&qt=3Dstandard&start=3D0&rows=3D20= &version=3D2.2 [junit4] > =09at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ= 4.java:888) [junit4] > =09... 40 more [junit4] 2> 1201278 INFO (SUITE-AutoCommitTest-seed#[E7BF4C35D486CF13= ]-worker) [ x:collection1] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> 1201278 INFO (SUITE-AutoCommitTest-seed#[E7BF4C35D486CF13= ]-worker) [ x:collection1] o.a.s.c.CoreContainer Shutting down CoreConta= iner instance=3D13141843 [junit4] 2> 1201278 INFO (SUITE-AutoCommitTest-seed#[E7BF4C35D486CF13= ]-worker) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric repo= rters for: solr.node [junit4] 2> 1201279 INFO (SUITE-AutoCommitTest-seed#[E7BF4C35D486CF13= ]-worker) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [o= rg.apache.solr.metrics.reporters.SolrJmxReporter@1a39405: rootName =3D null= , domain =3D solr.node, service url =3D null, agent id =3D null] for regist= ry solr.node / null [junit4] 2> 1201279 INFO (SUITE-AutoCommitTest-seed#[E7BF4C35D486CF13= ]-worker) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric repo= rters for: solr.jvm [junit4] 2> 1201279 INFO (SUITE-AutoCommitTest-seed#[E7BF4C35D486CF13= ]-worker) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [o= rg.apache.solr.metrics.reporters.SolrJmxReporter@397a4b: rootName =3D null,= domain =3D solr.jvm, service url =3D null, agent id =3D null] for registry= solr.jvm / null [junit4] 2> 1201279 INFO (SUITE-AutoCommitTest-seed#[E7BF4C35D486CF13= ]-worker) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric repo= rters for: solr.jetty [junit4] 2> 1201279 INFO (SUITE-AutoCommitTest-seed#[E7BF4C35D486CF13= ]-worker) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [o= rg.apache.solr.metrics.reporters.SolrJmxReporter@1070d19: rootName =3D null= , domain =3D solr.jetty, service url =3D null, agent id =3D null] for regis= try solr.jetty / null [junit4] 2> 1201279 INFO (coreCloseExecutor-4417-thread-1-processing-= x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING = SolrCore org.apache.solr.core.SolrCore@97806c [junit4] 2> 1201280 INFO (coreCloseExecutor-4417-thread-1-processing-= x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Committing = on IndexWriter close. [junit4] 2> 1201281 INFO (commitScheduler-4416-thread-1) [ x:colle= ction1] o.a.s.s.SolrIndexSearcher Opening [Searcher@635b95[collection1] mai= n] [junit4] 2> 1201282 ERROR (commitScheduler-4416-thread-1) [ x:colle= ction1] o.a.s.u.CommitTracker auto commit error...:org.apache.solr.common.S= olrException: Error opening new searcher [junit4] 2> =09at org.apache.solr.core.SolrCore.openNewSearcher(SolrCo= re.java:2069) [junit4] 2> =09at org.apache.solr.core.SolrCore.getSearcher(SolrCore.j= ava:2189) [junit4] 2> =09at org.apache.solr.update.DirectUpdateHandler2.commit(D= irectUpdateHandler2.java:667) [junit4] 2> =09at org.apache.solr.update.CommitTracker.run(CommitTrack= er.java:217) [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 java.util.concurrent.ScheduledThreadPoolExecutor$Sch= eduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [junit4] 2> =09at java.util.concurrent.ScheduledThreadPoolExecutor$Sch= eduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [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.solr.common.SolrException: openNewSe= archer called on closed core [junit4] 2> =09at org.apache.solr.core.SolrCore.openNewSearcher(SolrCo= re.java:2056) [junit4] 2> =09... 10 more [junit4] 2>=20 [junit4] 2> 1201282 INFO (coreCloseExecutor-4417-thread-1-processing-= x:collection1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommi= tData with IW:org.apache.solr.update.SolrIndexWriter@1a2d9b4 [junit4] 2> 1201294 INFO (coreCloseExecutor-4417-thread-1-processing-= x:collection1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric= reporters for: solr.core.collection1 [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/wo= rkspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.update= .AutoCommitTest_E7BF4C35D486CF13-001 [junit4] 2> NOTE: test params are: codec=3DAsserting(Lucene62), sim=3D= RandomSimilarity(queryNorm=3Dfalse,coord=3Dno): {}, locale=3Duk, timezone= =3DVST [junit4] 2> NOTE: Linux 4.10.0-33-generic i386/Oracle Corporation 1.8.= 0_144 (32-bit)/cpus=3D8,threads=3D1,free=3D134232984,total=3D277082112 [junit4] 2> NOTE: All tests run in this JVM: [TestCollectionAPIs, Test= InitParams, CollectionStateFormat2Test, TestSQLHandlerNonCloud, SampleTest,= HdfsCollectionsAPIDistributedZkTest, SpellCheckCollatorWithCollapseTest, D= istributedTermsComponentTest, TestRandomRequestDistribution, TestUpdate, Te= stInPlaceUpdatesStandalone, TestConfigSetsAPIExclusivity, MoveReplicaTest, = EchoParamsTest, MoreLikeThisHandlerTest, ConnectionManagerTest, TestJsonFac= etRefinement, TestTestInjection, MultiTermTest, TestSolrFieldCacheMBean, Re= startWhileUpdatingTest, CoreAdminRequestStatusTest, TestConfigSets, TestCur= sorMarkWithoutUniqueKey, SolrJmxReporterCloudTest, TestDeleteCollectionOnDo= wnNodes, ZkCLITest, DistanceFunctionTest, TestRequestForwarding, TestSchema= Manager, SolrIndexConfigTest, TestSubQueryTransformerDistrib, CoreSorterTes= t, TestDistribIDF, SpellingQueryConverterTest, TestDistributedStatsComponen= tCardinality, SolrInfoMBeanTest, ConnectionReuseTest, LeaderInitiatedRecove= ryOnShardRestartTest, ZkStateReaderTest, SliceStateTest, TestPerFieldSimila= rityWithDefaultOverride, TestLRUStatsCache, TestCoreContainer, OpenCloseCor= eStressTest, WordBreakSolrSpellCheckerTest, DistributedQueryComponentOptimi= zationTest, URLClassifyProcessorTest, TestFieldCollectionResource, TestSolr= QueryParserDefaultOperatorResource, IndexSchemaRuntimeFieldTest, TestShardH= andlerFactory, TestNoOpRegenerator, CheckHdfsIndexTest, SortByFunctionTest,= TestSuggestSpellingConverter, TestQuerySenderListener, SimplePostToolTest,= IndexSchemaTest, DirectSolrConnectionTest, AssignTest, ParsingFieldUpdateP= rocessorsTest, TestTrackingShardHandlerFactory, TestElisionMultitermQuery, = IndexBasedSpellCheckerTest, TestSchemaSimilarityResource, TestFuzzyAnalyzed= Suggestions, TestExceedMaxTermLength, TestCloudManagedSchema, TestLockTree,= TestStressVersions, DocumentBuilderTest, TestUseDocValuesAsStored2, SoftAu= toCommitTest, RemoteQueryErrorTest, TestNumericTerms64, SpatialHeatmapFacet= sTest, CloneFieldUpdateProcessorFactoryTest, DistributedFacetPivotSmallTest= , UpdateRequestProcessorFactoryTest, HighlighterTest, TestPartialUpdateDedu= plication, TermsComponentTest, OverseerTaskQueueTest, TestTrieFacet, CopyFi= eldTest, SpatialFilterTest, CleanupOldIndexTest, ShardRoutingTest, Distribu= tedDebugComponentTest, TestLuceneMatchVersion, DeleteReplicaTest, TestBM25S= imilarityFactory, TestSurroundQueryParser, HdfsWriteToMultipleCollectionsTe= st, TestWordDelimiterFilterFactory, TermVectorComponentTest, DocValuesMissi= ngTest, DistributedQueueTest, TestFieldTypeResource, SaslZkACLProviderTest,= TestEmbeddedSolrServerConstructors, TestFastOutputStream, CollectionsAPIDi= stributedZkTest, SolrIndexSplitterTest, HdfsThreadLeakTest, CSVRequestHandl= erTest, TestSimpleTrackingShardHandler, TestIntervalFaceting, AnalysisError= HandlingTest, SegmentsInfoRequestHandlerTest, ChaosMonkeySafeLeaderTest, Sh= aredFSAutoReplicaFailoverUtilsTest, SystemInfoHandlerTest, DistributedInter= valFacetingTest, SolrMetricsIntegrationTest, SpellCheckCollatorTest, BasicZ= kTest, RecoveryZkTest, TestReplicationHandler, ShardRoutingCustomTest, Auto= CommitTest] [junit4] Completed [366/713 (2!)] on J1 in 13.31s, 3 tests, 1 error <<< = FAILURES! [...truncated 40620 lines...] ------=_Part_120_1282455359.1508256748632 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_120_1282455359.1508256748632--