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 095CE200B87 for ; Mon, 19 Sep 2016 10:27:21 +0200 (CEST) Received: by cust-asf.ponee.io (Postfix) id 07E5F160ACC; Mon, 19 Sep 2016 08:27:21 +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 4AEB0160ABC for ; Mon, 19 Sep 2016 10:27:18 +0200 (CEST) Received: (qmail 69007 invoked by uid 500); 19 Sep 2016 08:27:16 -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 68997 invoked by uid 99); 19 Sep 2016 08:27:16 -0000 Received: from crius.apache.org (HELO crius.apache.org) (140.211.11.14) by apache.org (qpsmtpd/0.29) with ESMTP; Mon, 19 Sep 2016 08:27:16 +0000 Received: from crius.apache.org (localhost [127.0.0.1]) by crius.apache.org (ASF Mail Server at crius.apache.org) with ESMTP id DA9AD9C00D1 for ; Mon, 19 Sep 2016 08:27:14 +0000 (UTC) Date: Mon, 19 Sep 2016 08:24:09 +0000 (UTC) From: Apache Jenkins Server To: dev@lucene.apache.org Message-ID: <81854998.3239.1474273634896.JavaMail.jenkins@crius> Subject: [JENKINS] Lucene-Solr-Tests-6.x - Build # 453 - Unstable MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_3238_149039949.1474273634685" X-Jenkins-Job: Lucene-Solr-Tests-6.x X-Jenkins-Result: UNSTABLE archived-at: Mon, 19 Sep 2016 08:27:21 -0000 ------=_Part_3238_149039949.1474273634685 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: https://builds.apache.org/job/Lucene-Solr-Tests-6.x/453/ 1 tests failed. FAILED: org.apache.solr.cloud.LeaderFailoverAfterPartitionTest.test Error Message: org.apache.solr.client.solrj.SolrServerException: No live SolrServers avail= able to handle this request:[http://127.0.0.1:34292/c8n_1x3_lf_shard1_repli= ca3] Stack Trace: org.apache.solr.client.solrj.SolrServerException: org.apache.solr.client.so= lrj.SolrServerException: No live SolrServers available to handle this reque= st:[http://127.0.0.1:34292/c8n_1x3_lf_shard1_replica3] =09at __randomizedtesting.SeedInfo.seed([272CC8FD1EF4722F:AF78F727B0081FD7]= :0) =09at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudS= olrClient.java:769) =09at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSo= lrClient.java:1161) =09at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnS= taleState(CloudSolrClient.java:1050) =09at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrCl= ient.java:992) =09at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) =09at org.apache.solr.cloud.HttpPartitionTest.sendDoc(HttpPartitionTest.jav= a:592) =09at org.apache.solr.cloud.HttpPartitionTest.sendDoc(HttpPartitionTest.jav= a:578) =09at org.apache.solr.cloud.LeaderFailoverAfterPartitionTest.testRf3WithLea= derFailover(LeaderFailoverAfterPartitionTest.java:174) =09at org.apache.solr.cloud.LeaderFailoverAfterPartitionTest.test(LeaderFai= loverAfterPartitionTest.java:55) =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:1764) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:871) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Random= izedRunner.java:907) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rando= mizedRunner.java:921) =09at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= FixedStatement.callStatement(BaseDistributedSearchTestCase.java:992) =09at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= Statement.evaluate(BaseDistributedSearchTestCase.java:967) =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:367) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:809) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:460) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:880) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:781) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:816) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:827) =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:367) =09at java.lang.Thread.run(Thread.java:745) Caused by: org.apache.solr.client.solrj.SolrServerException: No live SolrSe= rvers available to handle this request:[http://127.0.0.1:34292/c8n_1x3_lf_s= hard1_replica3] =09at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolr= Client.java:384) =09at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudS= olrClient.java:763) =09... 49 more Caused by: org.apache.solr.client.solrj.SolrServerException: Server refused= connection at: http://127.0.0.1:34292/c8n_1x3_lf_shard1_replica3 =09at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSo= lrClient.java:599) =09at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClie= nt.java:261) =09at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClie= nt.java:250) =09at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSo= lrClient.java:405) =09at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolr= Client.java:357) =09... 50 more Caused by: java.net.ConnectException: Connection refused =09at java.net.PlainSocketImpl.socketConnect(Native Method) =09at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.ja= va:350) =09at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocket= Impl.java:206) =09at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java= :188) =09at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392) =09at java.net.Socket.connect(Socket.java:589) =09at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(PlainSoc= ketFactory.java:117) =09at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnect= ion(DefaultClientConnectionOperator.java:177) =09at org.apache.http.impl.conn.ManagedClientConnectionImpl.open(ManagedCli= entConnectionImpl.java:304) =09at org.apache.http.impl.client.DefaultRequestDirector.tryConnect(Default= RequestDirector.java:611) =09at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultReq= uestDirector.java:446) =09at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttp= Client.java:882) =09at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttp= Client.java:82) =09at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttp= Client.java:107) =09at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttp= Client.java:55) =09at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSo= lrClient.java:497) =09... 54 more Build Log: [...truncated 11265 lines...] [junit4] Suite: org.apache.solr.cloud.LeaderFailoverAfterPartitionTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Luce= ne-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailov= erAfterPartitionTest_272CC8FD1EF4722F-001/init-core-data-001 [junit4] 2> 1022241 INFO (SUITE-LeaderFailoverAfterPartitionTest-seed= #[272CC8FD1EF4722F]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (fal= se) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL= (bugUrl=3Dhttps://issues.apache.org/jira/browse/SOLR-5776) [junit4] 2> 1022241 INFO (SUITE-LeaderFailoverAfterPartitionTest-seed= #[272CC8FD1EF4722F]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Sett= ing hostContext system property: / [junit4] 2> 1022242 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVE= R [junit4] 2> 1022243 INFO (Thread-1009) [ ] o.a.s.c.ZkTestServer cl= ient port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1022243 INFO (Thread-1009) [ ] o.a.s.c.ZkTestServer St= arting server [junit4] 2> 1022344 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.ZkTestServer start zk server on por= t:60817 [junit4] 2> 1022344 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCred= entialsProvider [junit4] 2> 1022348 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.ConnectionManager Waiting for cli= ent to connect to ZooKeeper [junit4] 2> 1022350 INFO (zkCallback-824-thread-1) [ ] o.a.s.c.c.C= onnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6d0= d1597 name:ZooKeeperConnection Watcher:127.0.0.1:60817 got event WatchedEve= nt state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1022351 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.ConnectionManager Client is conne= cted to ZooKeeper [junit4] 2> 1022351 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLP= rovider [junit4] 2> 1022351 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1022362 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCred= entialsProvider [junit4] 2> 1022364 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.ConnectionManager Waiting for cli= ent to connect to ZooKeeper [junit4] 2> 1022364 INFO (zkCallback-825-thread-1) [ ] o.a.s.c.c.C= onnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3be= 55f9e name:ZooKeeperConnection Watcher:127.0.0.1:60817/solr got event Watch= edEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1022364 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.ConnectionManager Client is conne= cted to ZooKeeper [junit4] 2> 1022364 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLP= rovider [junit4] 2> 1022364 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collectio= ns/collection1 [junit4] 2> 1022366 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collectio= ns/collection1/shards [junit4] 2> 1022367 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collectio= ns/control_collection [junit4] 2> 1022372 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collectio= ns/control_collection/shards [junit4] 2> 1022373 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/= jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr= /collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 1022373 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/c= onf1/solrconfig.xml [junit4] 2> 1022375 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/= jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr= /collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 1022375 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/c= onf1/schema.xml [junit4] 2> 1022376 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/= jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr= /collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf= 1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1022377 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/c= onf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1022378 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/= jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr= /collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 1022378 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/c= onf1/stopwords.txt [junit4] 2> 1022379 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/= jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr= /collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 1022379 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/c= onf1/protwords.txt [junit4] 2> 1022380 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/= jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr= /collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 1022380 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/c= onf1/currency.xml [junit4] 2> 1022381 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/= jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr= /collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1022381 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/c= onf1/enumsConfig.xml [junit4] 2> 1022382 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/= jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr= /collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-= rates.json [junit4] 2> 1022382 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/c= onf1/open-exchange-rates.json [junit4] 2> 1022435 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/= jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr= /collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISO= Latin1Accent.txt [junit4] 2> 1022435 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/c= onf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1022559 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/= jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr= /collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1022559 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/c= onf1/old_synonyms.txt [junit4] 2> 1022588 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/= jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr= /collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 1022588 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/c= onf1/synonyms.txt [junit4] 2> 1022701 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.SolrTestCaseJ4 Writing core.propertie= s file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/bu= ild/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_272C= C8FD1EF4722F-001/control-001/cores/collection1 [junit4] 2> 1022704 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 1022720 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.Se= rvletContextHandler@371cc3{/,null,AVAILABLE} [junit4] 2> 1022721 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.e.j.s.ServerConnector Started ServerConne= ctor@c7dd9e9{HTTP/1.1,[http/1.1]}{127.0.0.1:45497} [junit4] 2> 1022721 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.e.j.s.Server Started @1028455ms [junit4] 2> 1022721 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty propertie= s: {solr.data.dir=3D/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6= .x/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitio= nTest_272CC8FD1EF4722F-001/tempDir-001/control/data, hostContext=3D/, hostP= ort=3D56720, coreRootDirectory=3D/x1/jenkins/jenkins-slave/workspace/Lucene= -Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailover= AfterPartitionTest_272CC8FD1EF4722F-001/control-001/cores} [junit4] 2> 1022721 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilt= er.init(): sun.misc.Launcher$AppClassLoader@73d16e93 [junit4] 2> 1022721 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.SolrResourceLoader new SolrResource= Loader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Test= s-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterParti= tionTest_272CC8FD1EF4722F-001/control-001' [junit4] 2> 1022722 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.SolrResourceLoader JNDI not configu= red for solr (NoInitialContextEx) [junit4] 2> 1022722 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.SolrResourceLoader solr home defaul= ted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1022722 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCred= entialsProvider [junit4] 2> 1022723 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.ConnectionManager Waiting for cli= ent to connect to ZooKeeper [junit4] 2> 1022731 INFO (zkCallback-826-thread-1) [ ] o.a.s.c.c.C= onnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@40a= 42978 name:ZooKeeperConnection Watcher:127.0.0.1:60817/solr got event Watch= edEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1022731 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.ConnectionManager Client is conne= cted to ZooKeeper [junit4] 2> 1022731 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLP= rovider [junit4] 2> 1022783 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml= from SolrHome (not found in ZooKeeper) [junit4] 2> 1022783 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.SolrXmlConfig Loading container con= figuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/s= olr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTes= t_272CC8FD1EF4722F-001/control-001/solr.xml [junit4] 2> 1022788 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.CorePropertiesLocator Config-define= d core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Test= s-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterParti= tionTest_272CC8FD1EF4722F-001/control-001/cores [junit4] 2> 1022788 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.CoreContainer New CoreContainer 117= 8822948 [junit4] 2> 1022788 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.CoreContainer Loading cores into Co= reContainer [instanceDir=3D/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-= Tests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterP= artitionTest_272CC8FD1EF4722F-001/control-001] [junit4] 2> 1022789 WARN (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.CoreContainer Couldn't add files fr= om /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/sol= r-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_272CC8FD1EF= 4722F-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace= /Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderF= ailoverAfterPartitionTest_272CC8FD1EF4722F-001/control-001/lib [junit4] 2> 1022789 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.h.c.HttpShardHandlerFactory created w= ith socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPe= rHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 214748= 3647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetri= es : false,connectionsEvictorSleepDelay : 5000,maxConnectionIdleTime : 4000= 0, [junit4] 2> 1022791 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateS= hardHandler HTTP client with params: socketTimeout=3D340000&connTimeout=3D4= 5000&retry=3Dtrue [junit4] 2> 1022792 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.= impl.Log4jLoggerFactory [junit4] 2> 1022792 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.l.LogWatcher Registering Log Listener= [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1022792 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.ZkContainer Zookeeper client=3D127.= 0.0.1:60817/solr [junit4] 2> 1022792 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1022793 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCred= entialsProvider [junit4] 2> 1022794 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.ConnectionManager Waiting for cli= ent to connect to ZooKeeper [junit4] 2> 1022794 INFO (zkCallback-829-thread-1) [ ] o.a.s.c.c.C= onnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@19d= 8347b name:ZooKeeperConnection Watcher:127.0.0.1:60817 got event WatchedEve= nt state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1022799 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.ConnectionManager Client is conne= cted to ZooKeeper [junit4] 2> 1022799 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLP= rovider [junit4] 2> 1022802 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.ZkController Adde= d new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$23/10= 1308563@5cf9d86e [junit4] 2> 1022807 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.c.ConnectionManag= er Waiting for client to connect to ZooKeeper [junit4] 2> 1022817 INFO (zkCallback-830-thread-1-processing-n:127.0.= 0.1:56720_) [n:127.0.0.1:56720_ ] o.a.s.c.c.ConnectionManager Watcher or= g.apache.solr.common.cloud.ConnectionManager@7861a429 name:ZooKeeperConnect= ion Watcher:127.0.0.1:60817/solr got event WatchedEvent state:SyncConnected= type:None path:null path:null type:None [junit4] 2> 1022817 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.c.ConnectionManag= er Client is connected to ZooKeeper [junit4] 2> 1022819 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.c.SolrZkClient ma= kePath: /overseer/queue [junit4] 2> 1022821 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.c.SolrZkClient ma= kePath: /overseer/collection-queue-work [junit4] 2> 1022823 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.c.SolrZkClient ma= kePath: /overseer/collection-map-running [junit4] 2> 1022827 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.c.SolrZkClient ma= kePath: /overseer/collection-map-completed [junit4] 2> 1022829 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.c.SolrZkClient ma= kePath: /overseer/collection-map-failure [junit4] 2> 1022830 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.c.SolrZkClient ma= kePath: /live_nodes [junit4] 2> 1022831 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.c.SolrZkClient ma= kePath: /aliases.json [junit4] 2> 1022840 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.c.SolrZkClient ma= kePath: /clusterstate.json [junit4] 2> 1022841 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.c.SolrZkClient ma= kePath: /security.json [junit4] 2> 1022841 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.c.ZkStateReader U= pdating cluster state from ZooKeeper...=20 [junit4] 2> 1022842 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.c.ZkStateReader L= oaded empty cluster properties [junit4] 2> 1022843 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.c.ZkStateReader U= pdated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1022844 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.c.SolrZkClient ma= kePath: /overseer_elect [junit4] 2> 1022845 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.c.SolrZkClient ma= kePath: /overseer_elect/election [junit4] 2> 1022846 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.Overseer Overseer= (id=3Dnull) closing [junit4] 2> 1022846 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.LeaderElector Joi= ned leadership election with path: /overseer_elect/election/966176838257868= 84-127.0.0.1:56720_-n_0000000000 [junit4] 2> 1022847 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.OverseerElectionC= ontext I am going to be the leader 127.0.0.1:56720_ [junit4] 2> 1022847 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.c.SolrZkClient ma= kePath: /overseer_elect/leader [junit4] 2> 1022848 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.Overseer Overseer= (id=3D96617683825786884-127.0.0.1:56720_-n_0000000000) starting [junit4] 2> 1022849 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.c.SolrZkClient ma= kePath: /overseer/queue-work [junit4] 2> 1022955 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.OverseerAutoRepli= caFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFail= overWorkLoopDelay=3D10000 autoReplicaFailoverWaitAfterExpiration=3D10000 au= toReplicaFailoverBadNodeExpiration=3D60000 [junit4] 2> 1022956 INFO (OverseerCollectionConfigSetProcessor-966176= 83825786884-127.0.0.1:56720_-n_0000000000) [n:127.0.0.1:56720_ ] o.a.s.c= .OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 1022959 INFO (OverseerStateUpdate-96617683825786884-127.0= .0.1:56720_-n_0000000000) [n:127.0.0.1:56720_ ] o.a.s.c.Overseer Startin= g to work on the main queue [junit4] 2> 1022960 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.ZkController Regi= ster node as live in ZooKeeper:/live_nodes/127.0.0.1:56720_ [junit4] 2> 1022960 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.c.SolrZkClient ma= kePath: /live_nodes/127.0.0.1:56720_ [junit4] 2> 1022972 INFO (zkCallback-830-thread-1-processing-n:127.0.= 0.1:56720_) [n:127.0.0.1:56720_ ] o.a.s.c.c.ZkStateReader A live node ch= ange: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live= _nodes], has occurred - updating... (live nodes size: [0]) [junit4] 2> 1022982 INFO (OverseerStateUpdate-96617683825786884-127.0= .0.1:56720_-n_0000000000) [n:127.0.0.1:56720_ ] o.a.s.c.c.ZkStateReader = Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1023035 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.CoreContainer Sec= urity conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1023035 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.CoreContainer No = authentication plugin used. [junit4] 2> 1023036 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.CorePropertiesLoc= ator Looking for core definitions underneath /x1/jenkins/jenkins-slave/work= space/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.Le= aderFailoverAfterPartitionTest_272CC8FD1EF4722F-001/control-001/cores [junit4] 2> 1023037 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.CoreDescriptor Cr= eated CoreDescriptor: {name=3Dcollection1, config=3Dsolrconfig.xml, transie= nt=3Dfalse, schema=3Dschema.xml, loadOnStartup=3Dtrue, configSetProperties= =3Dconfigsetprops.json, collection=3Dcontrol_collection, coreNodeName=3D, d= ataDir=3Ddata/, shard=3D} [junit4] 2> 1023037 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.CorePropertiesLoc= ator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-S= olr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAf= terPartitionTest_272CC8FD1EF4722F-001/control-001/cores/collection1 [junit4] 2> 1023037 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.c.CorePropertiesLoc= ator Found 1 core definitions [junit4] 2> 1023059 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.s.SolrDispatchFilte= r user.dir=3D/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr= /build/solr-core/test/J1 [junit4] 2> 1023059 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection x:collection1= ] o.a.s.c.ZkController publishing state=3Ddown [junit4] 2> 1023059 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:56720_ ] o.a.s.s.SolrDispatchFilte= r SolrDispatchFilter.init() done [junit4] 2> 1023059 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection x:collection1= ] o.a.s.c.ZkController numShards not found on descriptor - reading it from = system property [junit4] 2> 1023080 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection x:collection1= ] o.a.s.c.ZkController Collection control_collection not visible yet, but f= lagging it so a watch is registered when it becomes visible [junit4] 2> 1023093 INFO (OverseerStateUpdate-96617683825786884-127.0= .0.1:56720_-n_0000000000) [n:127.0.0.1:56720_ ] o.a.s.c.Overseer process= Message: queueSize: 1, message =3D { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:56720", [junit4] 2> "node_name":"127.0.0.1:56720_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 1023093 INFO (OverseerStateUpdate-96617683825786884-127.0= .0.1:56720_-n_0000000000) [n:127.0.0.1:56720_ ] o.a.s.c.o.ReplicaMutator= Update state numShards=3D1 message=3D{ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:56720", [junit4] 2> "node_name":"127.0.0.1:56720_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1023099 INFO (OverseerStateUpdate-96617683825786884-127.0= .0.1:56720_-n_0000000000) [n:127.0.0.1:56720_ ] o.a.s.c.o.ClusterStateMu= tator building a new cName: control_collection [junit4] 2> 1023100 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection x:collection1= ] o.a.s.c.c.ZkStateReader Deleting data for [control_collection] [junit4] 2> 1023103 INFO (OverseerStateUpdate-96617683825786884-127.0= .0.1:56720_-n_0000000000) [n:127.0.0.1:56720_ ] o.a.s.c.o.ReplicaMutator= Assigning new node to shard shard=3Dshard1 [junit4] 2> 1023127 INFO (zkCallback-830-thread-1-processing-n:127.0.= 0.1:56720_) [n:127.0.0.1:56720_ ] o.a.s.c.c.ZkStateReader A cluster stat= e change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json], has occurred - updating... (live nodes size: [1]) [junit4] 2> 1023134 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection x:collection1= ] o.a.s.c.ZkController look for our core node name [junit4] 2> 1023134 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection x:collection1= ] o.a.s.c.ZkController waiting to find shard id in clusterstate for collect= ion1 [junit4] 2> 1023134 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection x:collection1= ] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 1023135 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection x:collection1= ] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1023151 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection x:collection1= ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jen= kins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/tes= t/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_272CC8FD1EF4722F-001/= control-001/cores/collection1' [junit4] 2> 1023152 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection x:collection1= ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContext= Ex) [junit4] 2> 1023152 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection x:collection1= ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find= system property or JNDI) [junit4] 2> 1023155 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection x:collection1= ] o.a.s.c.Config loaded config solrconfig.xml with version 0=20 [junit4] 2> 1023166 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection x:collection1= ] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1023173 WARN (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection x:collection1= ] o.a.s.c.Config Beginning with Solr 5.5, is deprecated, use = instead. [junit4] 2> 1023174 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection x:collection1= ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0 [junit4] 2> 1023183 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection x:collection1= ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 1023199 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection x:collection1= ] o.a.s.s.IndexSchema [collection1] Schema name=3Dtest [junit4] 2> 1023387 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection x:collection1= ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=3Dopen-exchan= ge-rates.json, refreshInterval=3D1440. [junit4] 2> 1023401 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection x:collection1= ] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1023402 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection x:collection1= ] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1023410 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection x:collection1= ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file curre= ncy.xml [junit4] 2> 1023422 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection x:collection1= ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file curre= ncy.xml [junit4] 2> 1023428 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection x:collection1= ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-e= xchange-rates.json [junit4] 2> 1023429 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection x:collection1= ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-e= xchange-rates.json [junit4] 2> 1023430 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection x:collection1= ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming d= efault properties: Can't find resource 'configsetprops.json' in classpath o= r '/configs/conf1', cwd=3D/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-T= ests-6.x/solr/build/solr-core/test/J1 [junit4] 2> 1023430 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection x:collection1= ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration= from collection control_collection [junit4] 2> 1023430 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFa= ctory [junit4] 2> 1023431 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCor= e at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/= solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_272CC8FD= 1EF4722F-001/control-001/cores/collection1], dataDir=3D[/x1/jenkins/jenkins= -slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/so= lr.cloud.LeaderFailoverAfterPartitionTest_272CC8FD1EF4722F-001/control-001/= cores/collection1/data/] [junit4] 2> 1023431 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Ad= ding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5692= 0ecb [junit4] 2> 1023431 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory = for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/so= lr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_272CC8FD1E= F4722F-001/control-001/cores/collection1/data/snapshot_metadata [junit4] 2> 1023431 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.s.SolrSnapshotMetaDataManager Loading from sn= apshot metadata file... [junit4] 2> 1023431 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory = for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/so= lr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_272CC8FD1E= F4722F-001/control-001/cores/collection1/data [junit4] 2> 1023431 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=3D= null new=3D/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/b= uild/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_272= CC8FD1EF4722F-001/control-001/cores/collection1/data/index/ [junit4] 2> 1023431 WARN (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '= /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-c= ore/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_272CC8FD1EF472= 2F-001/control-001/cores/collection1/data/index' doesn't exist. Creating ne= w index... [junit4] 2> 1023432 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory = for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/so= lr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_272CC8FD1E= F4722F-001/control-001/cores/collection1/data/index [junit4] 2> 1023432 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping = class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMer= geAtOnce=3D23, maxMergeAtOnceExplicit=3D35, maxMergedSegmentMB=3D59.4990234= 375, floorSegmentMB=3D0.8837890625, forceMergeDeletesPctAllowed=3D12.588537= 490389468, segmentsPerTier=3D24.0, maxCFSSegmentSizeMB=3D8.796093022207999E= 12, noCFSRatio=3D0.6407551818436906 [junit4] 2> 1023439 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommi= t: commits: num=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@77ce88b0= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@b396e56),s= egFN=3Dsegments_1,generation=3D1} [junit4] 2> 1023439 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = =3D 1 [junit4] 2> 1023446 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating update= RequestProcessorChain "nodistrib" [junit4] 2> 1023446 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating update= RequestProcessorChain "dedupe" [junit4] 2> 1023446 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting Distr= ibutedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1023446 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating update= RequestProcessorChain "stored_sig" [junit4] 2> 1023446 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting Distr= ibutedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1023446 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating update= RequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1023446 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating update= RequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1023446 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting Distr= ibutedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-= test-chain-implicit" [junit4] 2> 1023446 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defin= ed as default, creating implicit default [junit4] 2> 1023447 WARN (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in request= Handler {type =3D requestHandler,name =3D /dump,class =3D DumpRequestHandle= r,args =3D {defaults=3D{a=3DA,b=3DB}}} [junit4] 2> 1023448 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1023449 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1023450 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1023451 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1023455 INFO (OldIndexDirectoryCleanupThreadForCore-colle= ction1) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core_node1 x:co= llection1] o.a.s.c.SolrCore Looking for old index directories to cleanup fo= r core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests= -6.x/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartit= ionTest_272CC8FD1EF4722F-001/control-001/cores/collection1/data/ [junit4] 2> 1023455 WARN (OldIndexDirectoryCleanupThreadForCore-colle= ction1) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core_node1 x:co= llection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Luc= ene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailo= verAfterPartitionTest_272CC8FD1EF4722F-001/control-001/cores/collection1/da= ta/ does not point to a valid data directory; skipping clean-up of old inde= x directories. [junit4] 2> 1023457 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbea= ns,standard,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/u= pdate/json,/admin/threads,/dump,/update/csv,/sql,/graph,/admin/segments,/ad= min/system,/replication,/config,/stream,/schema,/admin/plugins,/admin/loggi= ng,/admin/ping,/update,/admin/file,/terms [junit4] 2> 1023459 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.= apache.solr.search.stats.LocalStatsCache [junit4] 2> 1023460 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation:= org.apache.solr.update.UpdateLog [junit4] 2> 1023460 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=3D = defaultSyncLevel=3DFLUSH numRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVe= rsionBuckets=3D65536 [junit4] 2> 1023460 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1023460 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1023461 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping = class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMer= geAtOnce=3D39, maxMergeAtOnceExplicit=3D30, maxMergedSegmentMB=3D72.8144531= 25, floorSegmentMB=3D1.638671875, forceMergeDeletesPctAllowed=3D7.783332526= 596141, segmentsPerTier=3D16.0, maxCFSSegmentSizeMB=3D8.796093022207999E12,= noCFSRatio=3D0.555249351548314 [junit4] 2> 1023463 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit:= commits: num=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@77ce88b0= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@b396e56),s= egFN=3Dsegments_1,generation=3D1} [junit4] 2> 1023463 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = =3D 1 [junit4] 2> 1023463 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@55714c34[= collection1] main] [junit4] 2> 1023479 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-b= ased storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 1023574 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperSt= orageIO with znodeBase: /configs/conf1 [junit4] 2> 1023574 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with ini= tArgs: {} [junit4] 2> 1023575 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.= json using ZooKeeperStorageIO:path=3D/configs/conf1 [junit4] 2> 1023579 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.r.ManagedResourceStorage No data found for znod= e /configs/conf1/_rest_managed.json [junit4] 2> 1023579 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _r= est_managed.json using ZooKeeperStorageIO:path=3D/configs/conf1 [junit4] 2> 1023579 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered Managed= Resources [junit4] 2> 1023579 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved f= or 10000 [junit4] 2> 1023580 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.UpdateLog Looking up max value of version fie= ld to seed version buckets [junit4] 2> 1023580 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _vers= ion_ for 65536 version buckets from index [junit4] 2> 1023580 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.VersionInfo No terms found for _version_, can= not seed version bucket highest value from index [junit4] 2> 1023580 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.UpdateLog Could not find max version in index= or recent updates, using new clock 1545882942610341888 [junit4] 2> 1023582 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets = with highest version 1545882942610341888 [junit4] 2> 1023582 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1023582 INFO (searcherExecutor-2090-thread-1-processing-n= :127.0.0.1:56720_ x:collection1 s:shard1 c:control_collection r:core_node1)= [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core_node1 x:collectio= n1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@55714c3= 4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1023587 INFO (coreLoadExecutor-2089-thread-1-processing-n= :127.0.0.1:56720_) [n:127.0.0.1:56720_ c:control_collection s:shard1 r:core= _node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 1023587 INFO (coreZkRegister-2082-thread-1-processing-n:1= 27.0.0.1:56720_ x:collection1 s:shard1 c:control_collection r:core_node1) [= n:127.0.0.1:56720_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.ZkController Register replica - core:collection1 address:http://1= 27.0.0.1:56720 collection:control_collection shard:shard1 [junit4] 2> 1023588 INFO (coreZkRegister-2082-thread-1-processing-n:1= 27.0.0.1:56720_ x:collection1 s:shard1 c:control_collection r:core_node1) [= n:127.0.0.1:56720_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leader_e= lect/shard1/election [junit4] 2> 1023592 INFO (coreZkRegister-2082-thread-1-processing-n:1= 27.0.0.1:56720_ x:collection1 s:shard1 c:control_collection r:core_node1) [= n:127.0.0.1:56720_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.LeaderElector Joined leadership election with path: /collections/= control_collection/leader_elect/shard1/election/96617683825786884-core_node= 1-n_0000000000 [junit4] 2> 1023592 INFO (coreZkRegister-2082-thread-1-processing-n:1= 27.0.0.1:56720_ x:collection1 s:shard1 c:control_collection r:core_node1) [= n:127.0.0.1:56720_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard= =3Dshard1 and weAreReplacement=3Dfalse and leaderVoteWait=3D10000 [junit4] 2> 1023594 INFO (OverseerStateUpdate-96617683825786884-127.0= .0.1:56720_-n_0000000000) [n:127.0.0.1:56720_ ] o.a.s.c.Overseer process= Message: queueSize: 1, message =3D { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection"} current state version= : 1 [junit4] 2> 1023595 INFO (coreZkRegister-2082-thread-1-processing-n:1= 27.0.0.1:56720_ x:collection1 s:shard1 c:control_collection r:core_node1) [= n:127.0.0.1:56720_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1023595 INFO (coreZkRegister-2082-thread-1-processing-n:1= 27.0.0.1:56720_ x:collection1 s:shard1 c:control_collection r:core_node1) [= n:127.0.0.1:56720_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1023595 INFO (coreZkRegister-2082-thread-1-processing-n:1= 27.0.0.1:56720_ x:collection1 s:shard1 c:control_collection r:core_node1) [= n:127.0.0.1:56720_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:56720/collection1/ [junit4] 2> 1023595 INFO (coreZkRegister-2082-thread-1-processing-n:1= 27.0.0.1:56720_ x:collection1 s:shard1 c:control_collection r:core_node1) [= n:127.0.0.1:56720_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1023596 INFO (coreZkRegister-2082-thread-1-processing-n:1= 27.0.0.1:56720_ x:collection1 s:shard1 c:control_collection r:core_node1) [= n:127.0.0.1:56720_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.SyncStrategy http://127.0.0.1:56720/collection1/ has no replicas [junit4] 2> 1023596 INFO (coreZkRegister-2082-thread-1-processing-n:1= 27.0.0.1:56720_ x:collection1 s:shard1 c:control_collection r:core_node1) [= n:127.0.0.1:56720_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/= shard1 [junit4] 2> 1023598 INFO (coreZkRegister-2082-thread-1-processing-n:1= 27.0.0.1:56720_ x:collection1 s:shard1 c:control_collection r:core_node1) [= n:127.0.0.1:56720_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node = /collections/control_collection/leaders/shard1/leader after winning as /col= lections/control_collection/leader_elect/shard1/election/96617683825786884-= core_node1-n_0000000000 [junit4] 2> 1023608 INFO (coreZkRegister-2082-thread-1-processing-n:1= 27.0.0.1:56720_ x:collection1 s:shard1 c:control_collection r:core_node1) [= n:127.0.0.1:56720_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:= 56720/collection1/ shard1 [junit4] 2> 1023609 INFO (OverseerStateUpdate-96617683825786884-127.0= .0.1:56720_-n_0000000000) [n:127.0.0.1:56720_ ] o.a.s.c.Overseer process= Message: queueSize: 1, message =3D { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:56720", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 1023721 INFO (zkCallback-830-thread-4-processing-n:127.0.= 0.1:56720_) [n:127.0.0.1:56720_ ] o.a.s.c.c.ZkStateReader A cluster stat= e change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json], has occurred - updating... (live nodes size: [1]) [junit4] 2> 1023763 INFO (coreZkRegister-2082-thread-1-processing-n:1= 27.0.0.1:56720_ x:collection1 s:shard1 c:control_collection r:core_node1) [= n:127.0.0.1:56720_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.ZkController We are http://127.0.0.1:56720/collection1/ and leade= r is http://127.0.0.1:56720/collection1/ [junit4] 2> 1023763 INFO (coreZkRegister-2082-thread-1-processing-n:1= 27.0.0.1:56720_ x:collection1 s:shard1 c:control_collection r:core_node1) [= n:127.0.0.1:56720_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.ZkController No LogReplay needed for core=3Dcollection1 baseURL= =3Dhttp://127.0.0.1:56720 [junit4] 2> 1023763 INFO (coreZkRegister-2082-thread-1-processing-n:1= 27.0.0.1:56720_ x:collection1 s:shard1 c:control_collection r:core_node1) [= n:127.0.0.1:56720_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1023763 INFO (coreZkRegister-2082-thread-1-processing-n:1= 27.0.0.1:56720_ x:collection1 s:shard1 c:control_collection r:core_node1) [= n:127.0.0.1:56720_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.ZkController publishing state=3Dactive [junit4] 2> 1023763 INFO (coreZkRegister-2082-thread-1-processing-n:1= 27.0.0.1:56720_ x:collection1 s:shard1 c:control_collection r:core_node1) [= n:127.0.0.1:56720_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.ZkController numShards not found on descriptor - reading it from = system property [junit4] 2> 1023765 INFO (coreZkRegister-2082-thread-1-processing-n:1= 27.0.0.1:56720_ x:collection1 s:shard1 c:control_collection r:core_node1) [= n:127.0.0.1:56720_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.c.ZkStateReader Checking legacy cluster state for collection cont= rol_collection [junit4] 2> 1023765 INFO (OverseerStateUpdate-96617683825786884-127.0= .0.1:56720_-n_0000000000) [n:127.0.0.1:56720_ ] o.a.s.c.Overseer process= Message: queueSize: 1, message =3D { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:56720", [junit4] 2> "node_name":"127.0.0.1:56720_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 1023765 INFO (OverseerStateUpdate-96617683825786884-127.0= .0.1:56720_-n_0000000000) [n:127.0.0.1:56720_ ] o.a.s.c.o.ReplicaMutator= Update state numShards=3D1 message=3D{ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:56720", [junit4] 2> "node_name":"127.0.0.1:56720_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1023871 INFO (zkCallback-830-thread-5-processing-n:127.0.= 0.1:56720_) [n:127.0.0.1:56720_ ] o.a.s.c.c.ZkStateReader A cluster stat= e change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json], has occurred - updating... (live nodes size: [1]) [junit4] 2> 1024097 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCred= entialsProvider [junit4] 2> 1024098 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.ConnectionManager Waiting for cli= ent to connect to ZooKeeper [junit4] 2> 1024119 INFO (zkCallback-834-thread-1) [ ] o.a.s.c.c.C= onnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6a8= d28ab name:ZooKeeperConnection Watcher:127.0.0.1:60817/solr got event Watch= edEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1024119 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.ConnectionManager Client is conne= cted to ZooKeeper [junit4] 2> 1024119 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLP= rovider [junit4] 2> 1024119 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.ZkStateReader Updating cluster st= ate from ZooKeeper...=20 [junit4] 2> 1024120 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluste= r properties [junit4] 2> 1024121 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes = from ZooKeeper... (0) -> (1) [junit4] 2> 1024121 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire s= essions:false cause connection loss:false [junit4] 2> 1024122 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creat= ing collection1 with stateFormat=3D2 [junit4] 2> 1024122 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCred= entialsProvider [junit4] 2> 1024143 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.ConnectionManager Waiting for cli= ent to connect to ZooKeeper [junit4] 2> 1024143 INFO (zkCallback-835-thread-1) [ ] o.a.s.c.c.C= onnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@75f= e6690 name:ZooKeeperConnection Watcher:127.0.0.1:60817/solr got event Watch= edEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1024143 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.ConnectionManager Client is conne= cted to ZooKeeper [junit4] 2> 1024143 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLP= rovider [junit4] 2> 1024145 INFO (OverseerStateUpdate-96617683825786884-127.0= .0.1:56720_-n_0000000000) [n:127.0.0.1:56720_ ] o.a.s.c.Overseer process= Message: queueSize: 1, message =3D { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "stateFormat":"2"} current state version: 3 [junit4] 2> 1024145 INFO (OverseerStateUpdate-96617683825786884-127.0= .0.1:56720_-n_0000000000) [n:127.0.0.1:56720_ ] o.a.s.c.o.ClusterStateMu= tator building a new cName: collection1 [junit4] 2> 1024251 INFO (OverseerStateUpdate-96617683825786884-127.0= .0.1:56720_-n_0000000000) [n:127.0.0.1:56720_ ] o.a.s.c.o.ZkStateWriter = going to create_collection /collections/collection1/state.json [junit4] 2> 1024287 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.SolrTestCaseJ4 Writing core.propertie= s file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/bu= ild/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_272C= C8FD1EF4722F-001/shard-1-001/cores/collection1 [junit4] 2> 1024288 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.AbstractFullDistribZkTestBase creat= e jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Test= s-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterParti= tionTest_272CC8FD1EF4722F-001/shard-1-001 [junit4] 2> 1024289 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 1024294 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.Se= rvletContextHandler@15e85115{/,null,AVAILABLE} [junit4] 2> 1024295 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.e.j.s.ServerConnector Started ServerConne= ctor@421958ad{HTTP/1.1,[http/1.1]}{127.0.0.1:55354} [junit4] 2> 1024295 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.e.j.s.Server Started @1030028ms [junit4] 2> 1024295 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty propertie= s: {solr.data.dir=3D/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6= .x/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitio= nTest_272CC8FD1EF4722F-001/tempDir-001/jetty1, solrconfig=3Dsolrconfig.xml,= hostContext=3D/, hostPort=3D46646, coreRootDirectory=3D/x1/jenkins/jenkins= -slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/so= lr.cloud.LeaderFailoverAfterPartitionTest_272CC8FD1EF4722F-001/shard-1-001/= cores} [junit4] 2> 1024295 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilt= er.init(): sun.misc.Launcher$AppClassLoader@73d16e93 [junit4] 2> 1024295 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.SolrResourceLoader new SolrResource= Loader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Test= s-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterParti= tionTest_272CC8FD1EF4722F-001/shard-1-001' [junit4] 2> 1024295 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.SolrResourceLoader JNDI not configu= red for solr (NoInitialContextEx) [junit4] 2> 1024295 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.SolrResourceLoader solr home defaul= ted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1024295 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCred= entialsProvider [junit4] 2> 1024297 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.ConnectionManager Waiting for cli= ent to connect to ZooKeeper [junit4] 2> 1024297 INFO (zkCallback-836-thread-1) [ ] o.a.s.c.c.C= onnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@317= 601cc name:ZooKeeperConnection Watcher:127.0.0.1:60817/solr got event Watch= edEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1024297 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.ConnectionManager Client is conne= cted to ZooKeeper [junit4] 2> 1024298 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLP= rovider [junit4] 2> 1024299 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml= from SolrHome (not found in ZooKeeper) [junit4] 2> 1024299 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.SolrXmlConfig Loading container con= figuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/s= olr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTes= t_272CC8FD1EF4722F-001/shard-1-001/solr.xml [junit4] 2> 1024308 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.CorePropertiesLocator Config-define= d core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Test= s-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterParti= tionTest_272CC8FD1EF4722F-001/shard-1-001/cores [junit4] 2> 1024308 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.CoreContainer New CoreContainer 320= 215391 [junit4] 2> 1024308 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.CoreContainer Loading cores into Co= reContainer [instanceDir=3D/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-= Tests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterP= artitionTest_272CC8FD1EF4722F-001/shard-1-001] [junit4] 2> 1024309 WARN (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.CoreContainer Couldn't add files fr= om /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/sol= r-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_272CC8FD1EF= 4722F-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace= /Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderF= ailoverAfterPartitionTest_272CC8FD1EF4722F-001/shard-1-001/lib [junit4] 2> 1024309 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.h.c.HttpShardHandlerFactory created w= ith socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPe= rHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 214748= 3647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetri= es : false,connectionsEvictorSleepDelay : 5000,maxConnectionIdleTime : 4000= 0, [junit4] 2> 1024310 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateS= hardHandler HTTP client with params: socketTimeout=3D340000&connTimeout=3D4= 5000&retry=3Dtrue [junit4] 2> 1024313 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.= impl.Log4jLoggerFactory [junit4] 2> 1024313 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.l.LogWatcher Registering Log Listener= [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1024313 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.ZkContainer Zookeeper client=3D127.= 0.0.1:60817/solr [junit4] 2> 1024313 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1024313 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCred= entialsProvider [junit4] 2> 1024315 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.ConnectionManager Waiting for cli= ent to connect to ZooKeeper [junit4] 2> 1024335 INFO (zkCallback-839-thread-1) [ ] o.a.s.c.c.C= onnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@182= 24d56 name:ZooKeeperConnection Watcher:127.0.0.1:60817 got event WatchedEve= nt state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1024347 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.ConnectionManager Client is conne= cted to ZooKeeper [junit4] 2> 1024347 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLP= rovider [junit4] 2> 1024364 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:46646_ ] o.a.s.c.ZkController Adde= d new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$23/10= 1308563@5392162a [junit4] 2> 1024365 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:46646_ ] o.a.s.c.c.ConnectionManag= er Waiting for client to connect to ZooKeeper [junit4] 2> 1024368 INFO (zkCallback-840-thread-1-processing-n:127.0.= 0.1:46646_) [n:127.0.0.1:46646_ ] o.a.s.c.c.ConnectionManager Watcher or= g.apache.solr.common.cloud.ConnectionManager@147cfe09 name:ZooKeeperConnect= ion Watcher:127.0.0.1:60817/solr got event WatchedEvent state:SyncConnected= type:None path:null path:null type:None [junit4] 2> 1024368 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:46646_ ] o.a.s.c.c.ConnectionManag= er Client is connected to ZooKeeper [junit4] 2> 1024378 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:46646_ ] o.a.s.c.c.ZkStateReader U= pdating cluster state from ZooKeeper...=20 [junit4] 2> 1024379 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:46646_ ] o.a.s.c.c.ZkStateReader L= oaded empty cluster properties [junit4] 2> 1024379 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:46646_ ] o.a.s.c.c.ZkStateReader U= pdated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1024381 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:46646_ ] o.a.s.c.Overseer Overseer= (id=3Dnull) closing [junit4] 2> 1024382 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:46646_ ] o.a.s.c.LeaderElector Joi= ned leadership election with path: /overseer_elect/election/966176838257868= 89-127.0.0.1:46646_-n_0000000001 [junit4] 2> 1024382 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:46646_ ] o.a.s.c.LeaderElector Wat= ching path /overseer_elect/election/96617683825786884-127.0.0.1:56720_-n_00= 00000000 to know if I could be the leader [junit4] 2> 1024383 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:46646_ ] o.a.s.c.ZkController Publ= ish node=3D127.0.0.1:46646_ as DOWN [junit4] 2> 1024384 INFO (OverseerStateUpdate-96617683825786884-127.0= .0.1:56720_-n_0000000000) [n:127.0.0.1:56720_ ] o.a.s.c.Overseer process= Message: queueSize: 1, message =3D { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:46646_"} current state version: 3 [junit4] 2> 1024384 INFO (OverseerStateUpdate-96617683825786884-127.0= .0.1:56720_-n_0000000000) [n:127.0.0.1:56720_ ] o.a.s.c.o.NodeMutator Do= wnNode state invoked for node: 127.0.0.1:46646_ [junit4] 2> 1024385 INFO (zkCallback-834-thread-1) [ ] o.a.s.c.c.Z= kStateReader A cluster state change: [WatchedEvent state:SyncConnected type= :NodeDataChanged path:/clusterstate.json], has occurred - updating... (live= nodes size: [1]) [junit4] 2> 1024385 INFO (zkCallback-830-thread-3-processing-n:127.0.= 0.1:56720_) [n:127.0.0.1:56720_ ] o.a.s.c.c.ZkStateReader A cluster stat= e change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json], has occurred - updating... (live nodes size: [1]) [junit4] 2> 1024386 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:46646_ ] o.a.s.c.ZkController Regi= ster node as live in ZooKeeper:/live_nodes/127.0.0.1:46646_ [junit4] 2> 1024386 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:46646_ ] o.a.s.c.c.SolrZkClient ma= kePath: /live_nodes/127.0.0.1:46646_ [junit4] 2> 1024386 INFO (zkCallback-840-thread-1-processing-n:127.0.= 0.1:46646_) [n:127.0.0.1:46646_ ] o.a.s.c.c.ZkStateReader A cluster stat= e change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json], has occurred - updating... (live nodes size: [1]) [junit4] 2> 1024396 INFO (zkCallback-830-thread-2-processing-n:127.0.= 0.1:56720_) [n:127.0.0.1:56720_ ] o.a.s.c.c.ZkStateReader A live node ch= ange: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live= _nodes], has occurred - updating... (live nodes size: [1]) [junit4] 2> 1024396 INFO (zkCallback-834-thread-1) [ ] o.a.s.c.c.Z= kStateReader A live node change: [WatchedEvent state:SyncConnected type:Nod= eChildrenChanged path:/live_nodes], has occurred - updating... (live nodes = size: [1]) [junit4] 2> 1024396 INFO (zkCallback-830-thread-2-processing-n:127.0.= 0.1:56720_) [n:127.0.0.1:56720_ ] o.a.s.c.c.ZkStateReader Updated live n= odes from ZooKeeper... (1) -> (2) [junit4] 2> 1024397 INFO (zkCallback-834-thread-1) [ ] o.a.s.c.c.Z= kStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1024436 INFO (zkCallback-840-thread-2-processing-n:127.0.= 0.1:46646_) [n:127.0.0.1:46646_ ] o.a.s.c.c.ZkStateReader A live node ch= ange: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live= _nodes], has occurred - updating... (live nodes size: [1]) [junit4] 2> 1024439 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:46646_ ] o.a.s.c.CoreContainer Sec= urity conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1024439 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:46646_ ] o.a.s.c.CoreContainer No = authentication plugin used. [junit4] 2> 1024439 INFO (zkCallback-840-thread-2-processing-n:127.0.= 0.1:46646_) [n:127.0.0.1:46646_ ] o.a.s.c.c.ZkStateReader Updated live n= odes from ZooKeeper... (1) -> (2) [junit4] 2> 1024440 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:46646_ ] o.a.s.c.CorePropertiesLoc= ator Looking for core definitions underneath /x1/jenkins/jenkins-slave/work= space/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.Le= aderFailoverAfterPartitionTest_272CC8FD1EF4722F-001/shard-1-001/cores [junit4] 2> 1024441 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:46646_ ] o.a.s.c.CoreDescriptor Cr= eated CoreDescriptor: {name=3Dcollection1, config=3Dsolrconfig.xml, transie= nt=3Dfalse, schema=3Dschema.xml, loadOnStartup=3Dtrue, configSetProperties= =3Dconfigsetprops.json, collection=3Dcollection1, coreNodeName=3D, dataDir= =3Ddata/, shard=3D} [junit4] 2> 1024441 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:46646_ ] o.a.s.c.CorePropertiesLoc= ator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-S= olr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAf= terPartitionTest_272CC8FD1EF4722F-001/shard-1-001/cores/collection1 [junit4] 2> 1024441 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:46646_ ] o.a.s.c.CorePropertiesLoc= ator Found 1 core definitions [junit4] 2> 1024451 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:46646_ ] o.a.s.s.SolrDispatchFilte= r user.dir=3D/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr= /build/solr-core/test/J1 [junit4] 2> 1024451 INFO (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [n:127.0.0.1:46646_ ] o.a.s.s.SolrDispatchFilte= r SolrDispatchFilter.init() done [junit4] 2> 1024459 INFO (coreLoadExecutor-2100-thread-1-processing-n= :127.0.0.1:46646_) [n:127.0.0.1:46646_ c:collection1 x:collection1] o.a.s= .c.ZkController publishing state=3Ddown [junit4] 2> 1024459 INFO (coreLoadExecutor-2100-thread-1-processing-n= :127.0.0.1:46646_) [n:127.0.0.1:46646_ c:collection1 x:collection1] o.a.s= .c.ZkController numShards not found on descriptor - reading it from system = property [junit4] 2> 1024475 INFO (coreLoadExecutor-2100-thread-1-processing-n= :127.0.0.1:46646_) [n:127.0.0.1:46646_ c:collection1 x:collection1] o.a.s= .c.ZkController Registering watch for collection collection1 [junit4] 2> 1024483 INFO (coreLoadExecutor-2100-thread-1-processing-n= :127.0.0.1:46646_) [n:127.0.0.1:46646_ c:collection1 x:collection1] o.a.s= .c.c.ZkStateReader Add data for [collection1] ver [0] [junit4] 2> 1024483 INFO (OverseerStateUpdate-96617683825786884-127.0= .0.1:56720_-n_0000000000) [n:127.0.0.1:56720_ ] o.a.s.c.Overseer process= Message: queueSize: 1, message =3D { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:46646", [junit4] 2> "node_name":"127.0.0.1:46646_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 4 [junit4] 2> 1024483 INFO (OverseerStateUpdate-96617683825786884-127.0= .0.1:56720_-n_0000000000) [n:127.0.0.1:56720_ ] o.a.s.c.o.ReplicaMutator= Update state numShards=3D2 message=3D{ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:46646", [junit4] 2> "node_name":"127.0.0.1:46646_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 1024483 INFO (OverseerStateUpdate-96617683825786884-127.0= .0.1:56720_-n_0000000000) [n:127.0.0.1:56720_ ] o.a.s.c.o.ReplicaMutator= Collection already exists with numShards=3D2 [junit4] 2> 1024483 INFO (OverseerStateUpdate-96617683825786884-127.0= .0.1:56720_-n_0000000000) [n:127.0.0.1:56720_ ] o.a.s.c.o.ReplicaMutator= Assigning new node to shard shard=3Dshard2 [junit4] 2> 1024483 INFO (coreLoadExecutor-2100-thread-1-processing-n= :127.0.0.1:46646_) [n:127.0.0.1:46646_ c:collection1 x:collection1] o.a.s= .c.ZkController look for our core node name [junit4] 2> 1024619 INFO (OverseerStateUpdate-96617683825786884-127.0= .0.1:56720_-n_0000000000) [n:127.0.0.1:56720_ ] o.a.s.c.o.ZkStateWriter = going to update_collection /collections/collection1/state.json version: 0 [junit4] 2> 1024623 INFO (zkCallback-840-thread-2-processing-n:127.0.= 0.1:46646_) [n:127.0.0.1:46646_ ] o.a.s.c.c.ZkStateReader A cluster stat= e change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/coll= ections/collection1/state.json] for collection [collection1] has occurred -= updating... (live nodes size: [2]) [junit4] 2> 1024631 INFO (zkCallback-840-thread-2-processing-n:127.0.= 0.1:46646_) [n:127.0.0.1:46646_ ] o.a.s.c.c.ZkStateReader Updating data = for [collection1] from [0] to [1] [junit4] 2> 1025483 INFO (coreLoadExecutor-2100-thread-1-processing-n= :127.0.0.1:46646_) [n:127.0.0.1:46646_ c:collection1 x:collection1] o.a.s= .c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1025484 INFO (coreLoadExecutor-2100-thread-1-processing-n= :127.0.0.1:46646_) [n:127.0.0.1:46646_ c:collection1 x:collection1] o.a.s= .c.ZkController Check for collection zkNode:collection1 [junit4] 2> 1025484 INFO (coreLoadExecutor-2100-thread-1-processing-n= :127.0.0.1:46646_) [n:127.0.0.1:46646_ c:collection1 x:collection1] o.a.s= .c.ZkController Collection zkNode exists [j [...truncated too long message...] ches above limit: [junit4] 2>=20 [junit4] 2> =095=09/solr/clusterstate.json [junit4] 2> =093=09/solr/collections/c8n_1x3_lf/state.json [junit4] 2> =093=09/solr/collections/collection1/state.json [junit4] 2>=20 [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2>=20 [junit4] 2> =0957=09/solr/overseer/collection-queue-work [junit4] 2> =0952=09/solr/overseer/queue [junit4] 2> =0925=09/solr/overseer/queue-work [junit4] 2> =095=09/solr/live_nodes [junit4] 2> =095=09/solr/collections [junit4] 2>=20 [junit4] 2> 1086804 WARN (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.SocketProxy Closing 6 connections t= o: http://127.0.0.1:46646/, target: http://127.0.0.1:55354/ [junit4] 2> 1086804 WARN (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.SocketProxy Closing 0 connections t= o: http://127.0.0.1:40895/, target: http://127.0.0.1:60258/ [junit4] 2> 1086804 WARN (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.SocketProxy Closing 7 connections t= o: http://127.0.0.1:56720/, target: http://127.0.0.1:45497/ [junit4] 2> 1086804 WARN (TEST-LeaderFailoverAfterPartitionTest.test-= seed#[272CC8FD1EF4722F]) [ ] o.a.s.c.SocketProxy Closing 0 connections t= o: http://127.0.0.1:34292/, target: http://127.0.0.1:34577/ [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DLeaderFailove= rAfterPartitionTest -Dtests.method=3Dtest -Dtests.seed=3D272CC8FD1EF4722F -= Dtests.multiplier=3D2 -Dtests.slow=3Dtrue -Dtests.locale=3Den-GB -Dtests.ti= mezone=3DAsia/Baku -Dtests.asserts=3Dtrue -Dtests.file.encoding=3DUTF-8 [junit4] ERROR 64.6s J1 | LeaderFailoverAfterPartitionTest.test <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerExcep= tion: org.apache.solr.client.solrj.SolrServerException: No live SolrServers= available to handle this request:[http://127.0.0.1:34292/c8n_1x3_lf_shard1= _replica3] [junit4] > =09at __randomizedtesting.SeedInfo.seed([272CC8FD1EF4722F:= AF78F727B0081FD7]:0) [junit4] > =09at org.apache.solr.client.solrj.impl.CloudSolrClient.di= rectUpdate(CloudSolrClient.java:769) [junit4] > =09at org.apache.solr.client.solrj.impl.CloudSolrClient.se= ndRequest(CloudSolrClient.java:1161) [junit4] > =09at org.apache.solr.client.solrj.impl.CloudSolrClient.re= questWithRetryOnStaleState(CloudSolrClient.java:1050) [junit4] > =09at org.apache.solr.client.solrj.impl.CloudSolrClient.re= quest(CloudSolrClient.java:992) [junit4] > =09at org.apache.solr.client.solrj.SolrClient.request(Solr= Client.java:1219) [junit4] > =09at org.apache.solr.cloud.HttpPartitionTest.sendDoc(Http= PartitionTest.java:592) [junit4] > =09at org.apache.solr.cloud.HttpPartitionTest.sendDoc(Http= PartitionTest.java:578) [junit4] > =09at org.apache.solr.cloud.LeaderFailoverAfterPartitionTe= st.testRf3WithLeaderFailover(LeaderFailoverAfterPartitionTest.java:174) [junit4] > =09at org.apache.solr.cloud.LeaderFailoverAfterPartitionTe= st.test(LeaderFailoverAfterPartitionTest.java:55) [junit4] > =09at org.apache.solr.BaseDistributedSearchTestCase$Shards= RepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase= .java:992) [junit4] > =09at org.apache.solr.BaseDistributedSearchTestCase$Shards= RepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967) [junit4] > =09at java.lang.Thread.run(Thread.java:745) [junit4] > Caused by: org.apache.solr.client.solrj.SolrServerExceptio= n: No live SolrServers available to handle this request:[http://127.0.0.1:3= 4292/c8n_1x3_lf_shard1_replica3] [junit4] > =09at org.apache.solr.client.solrj.impl.LBHttpSolrClient.r= equest(LBHttpSolrClient.java:384) [junit4] > =09at org.apache.solr.client.solrj.impl.CloudSolrClient.di= rectUpdate(CloudSolrClient.java:763) [junit4] > =09... 49 more [junit4] > Caused by: org.apache.solr.client.solrj.SolrServerExceptio= n: Server refused connection at: http://127.0.0.1:34292/c8n_1x3_lf_shard1_r= eplica3 [junit4] > =09at org.apache.solr.client.solrj.impl.HttpSolrClient.exe= cuteMethod(HttpSolrClient.java:599) [junit4] > =09at org.apache.solr.client.solrj.impl.HttpSolrClient.req= uest(HttpSolrClient.java:261) [junit4] > =09at org.apache.solr.client.solrj.impl.HttpSolrClient.req= uest(HttpSolrClient.java:250) [junit4] > =09at org.apache.solr.client.solrj.impl.LBHttpSolrClient.d= oRequest(LBHttpSolrClient.java:405) [junit4] > =09at org.apache.solr.client.solrj.impl.LBHttpSolrClient.r= equest(LBHttpSolrClient.java:357) [junit4] > =09... 50 more [junit4] > Caused by: java.net.ConnectException: Connection refused [junit4] > =09at java.net.PlainSocketImpl.socketConnect(Native Method= ) [junit4] > =09at java.net.AbstractPlainSocketImpl.doConnect(AbstractP= lainSocketImpl.java:350) [junit4] > =09at java.net.AbstractPlainSocketImpl.connectToAddress(Ab= stractPlainSocketImpl.java:206) [junit4] > =09at java.net.AbstractPlainSocketImpl.connect(AbstractPla= inSocketImpl.java:188) [junit4] > =09at java.net.SocksSocketImpl.connect(SocksSocketImpl.jav= a:392) [junit4] > =09at java.net.Socket.connect(Socket.java:589) [junit4] > =09at org.apache.http.conn.scheme.PlainSocketFactory.conne= ctSocket(PlainSocketFactory.java:117) [junit4] > =09at org.apache.http.impl.conn.DefaultClientConnectionOpe= rator.openConnection(DefaultClientConnectionOperator.java:177) [junit4] > =09at org.apache.http.impl.conn.ManagedClientConnectionImp= l.open(ManagedClientConnectionImpl.java:304) [junit4] > =09at org.apache.http.impl.client.DefaultRequestDirector.t= ryConnect(DefaultRequestDirector.java:611) [junit4] > =09at org.apache.http.impl.client.DefaultRequestDirector.e= xecute(DefaultRequestDirector.java:446) [junit4] > =09at org.apache.http.impl.client.AbstractHttpClient.doExe= cute(AbstractHttpClient.java:882) [junit4] > =09at org.apache.http.impl.client.CloseableHttpClient.exec= ute(CloseableHttpClient.java:82) [junit4] > =09at org.apache.http.impl.client.CloseableHttpClient.exec= ute(CloseableHttpClient.java:107) [junit4] > =09at org.apache.http.impl.client.CloseableHttpClient.exec= ute(CloseableHttpClient.java:55) [junit4] > =09at org.apache.solr.client.solrj.impl.HttpSolrClient.exe= cuteMethod(HttpSolrClient.java:497) [junit4] > =09... 54 more [junit4] 2> 1086812 INFO (SUITE-LeaderFailoverAfterPartitionTest-seed= #[272CC8FD1EF4722F]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenk= ins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp= /solr.cloud.LeaderFailoverAfterPartitionTest_272CC8FD1EF4722F-001 [junit4] 2> Sep 19, 2016 7:08:41 AM com.carrotsearch.randomizedtesting= .ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thre= ad(s). [junit4] 2> NOTE: test params are: codec=3DAsserting(Lucene62): {range= _facet_l_dv=3DPostingsFormat(name=3DLuceneVarGapDocFreqInterval), _version_= =3DPostingsFormat(name=3DLuceneFixedGap), multiDefault=3DPostingsFormat(nam= e=3DMemory doPackFST=3D true), a_t=3DPostingsFormat(name=3DMemory doPackFST= =3D true), intDefault=3DPostingsFormat(name=3DLuceneFixedGap), id=3DPosting= sFormat(name=3DLuceneVarGapDocFreqInterval), range_facet_i_dv=3DPostingsFor= mat(name=3DMemory doPackFST=3D true), text=3DPostingsFormat(name=3DDirect),= range_facet_l=3DPostingsFormat(name=3DMemory doPackFST=3D true), timestamp= =3DPostingsFormat(name=3DMemory doPackFST=3D true)}, docValues:{range_facet= _l_dv=3DDocValuesFormat(name=3DLucene54), range_facet_i_dv=3DDocValuesForma= t(name=3DAsserting), timestamp=3DDocValuesFormat(name=3DAsserting)}, maxPoi= ntsInLeafNode=3D1548, maxMBSortInHeap=3D7.313161575986047, sim=3DClassicSim= ilarity, locale=3Den-GB, timezone=3DAsia/Baku [junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8= .0_102 (64-bit)/cpus=3D4,threads=3D1,free=3D65173640,total=3D523239424 [junit4] 2> NOTE: All tests run in this JVM: [TestLeaderElectionZkExpi= ry, CollectionTooManyReplicasTest, TestSearchPerf, TestSystemIdResolver, Te= stNoOpRegenerator, TestSchemaVersionResource, DateMathParserTest, TestReque= stForwarding, SpellCheckComponentTest, HdfsCollectionsAPIDistributedZkTest,= TestSchemaNameResource, TestUninvertingReader, EnumFieldTest, TestStressVe= rsions, UpdateRequestProcessorFactoryTest, TolerantUpdateProcessorTest, Sol= rInfoMBeanTest, TestJsonFacets, SolrCmdDistributorTest, SampleTest, HdfsBas= icDistributedZkTest, HdfsRecoveryZkTest, IndexBasedSpellCheckerTest, TestFi= eldResource, TermVectorComponentTest, ChangedSchemaMergeTest, CloudExitable= DirectoryReaderTest, SpellingQueryConverterTest, InfoHandlerTest, HdfsLockF= actoryTest, TestLRUCache, TestQueryTypes, TestRTGBase, TestFastOutputStream= , CopyFieldTest, TestTrieFacet, SpatialFilterTest, TestDynamicFieldCollecti= onResource, TestConfigSetImmutable, SolrXmlInZkTest, DeleteLastCustomSharde= dReplicaTest, TestFilteredDocIdSet, TestSurroundQueryParser, TestCloudInspe= ctUtil, TestSortingResponseWriter, TestSortByMinMaxFunction, TestFuzzyAnaly= zedSuggestions, OverriddenZkACLAndCredentialsProvidersTest, TestIntervalFac= eting, HLLSerializationTest, RollingRestartTest, MigrateRouteKeyTest, Clust= erStateUpdateTest, CoreAdminCreateDiscoverTest, CloudMLTQParserTest, AddSch= emaFieldsUpdateProcessorFactoryTest, TestWordDelimiterFilterFactory, Collec= tionsAPIDistributedZkTest, TestSQLHandler, DistributedIntervalFacetingTest,= DocValuesTest, TestTestInjection, SliceStateTest, TestRawResponseWriter, T= estFiltering, ZkCLITest, DistributedMLTComponentTest, CdcrUpdateLogTest, Te= stInitQParser, URLClassifyProcessorTest, HighlighterTest, TestSolrXml, File= UtilsTest, TestUpdate, TestCSVResponseWriter, HdfsWriteToMultipleCollection= sTest, EchoParamsTest, TestManagedSynonymFilterFactory, TestFreeTextSuggest= ions, SimpleCollectionCreateDeleteTest, IndexSchemaTest, SearchHandlerTest,= LeaderFailoverAfterPartitionTest] [junit4] Completed [188/630 (1!)] on J1 in 64.87s, 1 test, 1 error <<< F= AILURES! [...truncated 61672 lines...] ------=_Part_3238_149039949.1474273634685 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_3238_149039949.1474273634685--