Return-Path: X-Original-To: apmail-lucene-dev-archive@www.apache.org Delivered-To: apmail-lucene-dev-archive@www.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id 994ED18779 for ; Mon, 15 Feb 2016 19:34:13 +0000 (UTC) Received: (qmail 98857 invoked by uid 500); 15 Feb 2016 19:34:12 -0000 Delivered-To: apmail-lucene-dev-archive@lucene.apache.org Received: (qmail 98793 invoked by uid 500); 15 Feb 2016 19:34:12 -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 98783 invoked by uid 99); 15 Feb 2016 19:34:12 -0000 Received: from Unknown (HELO spamd1-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Mon, 15 Feb 2016 19:34:12 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd1-us-west.apache.org (ASF Mail Server at spamd1-us-west.apache.org) with ESMTP id B2E3EC0053 for ; Mon, 15 Feb 2016 19:34:11 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd1-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: 2.001 X-Spam-Level: ** X-Spam-Status: No, score=2.001 tagged_above=-999 required=6.31 tests=[FSL_HELO_NON_FQDN_1=0.001, KAM_BADIPHTTP=2, SPF_PASS=-0.001, WEIRD_PORT=0.001] autolearn=disabled Received: from mx2-lw-us.apache.org ([10.40.0.8]) by localhost (spamd1-us-west.apache.org [10.40.0.7]) (amavisd-new, port 10024) with ESMTP id 3cKm3r-7Rzcn for ; Mon, 15 Feb 2016 19:34:04 +0000 (UTC) Received: from serv1 (serv1.sd-datasolutions.de [188.138.57.78]) by mx2-lw-us.apache.org (ASF Mail Server at mx2-lw-us.apache.org) with ESMTPS id E26D05FAD8 for ; Mon, 15 Feb 2016 19:34:03 +0000 (UTC) Received: from localhost.localdomain ([127.0.0.1] helo=serv1) by serv1 with esmtp (Exim 4.82) (envelope-from ) id 1aVOuH-0003bj-Tn for dev@lucene.apache.org; Mon, 15 Feb 2016 19:34:02 +0000 Date: Mon, 15 Feb 2016 19:33:45 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org Message-ID: <492638789.1.1455564841991.JavaMail.jenkins@serv1> Subject: [JENKINS] Lucene-Solr-trunk-Linux (64bit/jdk1.8.0_72) - Build # 15892 - Failure! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_0_697846512.1455564841676" X-Jenkins-Job: Lucene-Solr-trunk-Linux X-Jenkins-Result: FAILURE ------=_Part_0_697846512.1455564841676 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/15892/ Java: 64bit/jdk1.8.0_72 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC 2 tests failed. FAILED: org.apache.solr.client.solrj.ConnectionReuseTest.test Error Message: We expected all communication via streaming client to use one connection! e= xpected=3D128 got=3D125 Stack Trace: java.lang.AssertionError: We expected all communication via streaming clien= t to use one connection! expected=3D128 got=3D125 =09at __randomizedtesting.SeedInfo.seed([E9A53D57A79CF6B8:61F1028D09609B40]= :0) =09at org.junit.Assert.fail(Assert.java:93) =09at org.junit.Assert.assertTrue(Assert.java:43) =09at org.apache.solr.client.solrj.ConnectionReuseTest.test(ConnectionReuse= Test.java:150) =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:964) =09at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= Statement.evaluate(BaseDistributedSearchTestCase.java:939) =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) FAILED: org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest.test Error Message: Error from server at http://127.0.0.1:35258: collection already exists: tes= tcollection Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error= from server at http://127.0.0.1:35258: collection already exists: testcoll= ection =09at __randomizedtesting.SeedInfo.seed([E9A53D57A79CF6B8:61F1028D09609B40]= :0) =09at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSo= lrClient.java:577) =09at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClie= nt.java:241) =09at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClie= nt.java:230) =09at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSo= lrClient.java:372) =09at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolr= Client.java:325) =09at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSo= lrClient.java:1099) =09at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnS= taleState(CloudSolrClient.java:870) =09at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrCl= ient.java:806) =09at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) =09at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(= AbstractFullDistribZkTestBase.java:1593) =09at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(= AbstractFullDistribZkTestBase.java:1614) =09at org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest.test(ChaosMonkeyNo= thingIsSafeTest.java:249) =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:964) =09at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= Statement.evaluate(BaseDistributedSearchTestCase.java:939) =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) Build Log: [...truncated 10858 lines...] [junit4] Suite: org.apache.solr.client.solrj.ConnectionReuseTest [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-trun= k-Linux/solr/build/solr-core/test/J0/temp/solr.client.solrj.ConnectionReuse= Test_E9A53D57A79CF6B8-001/init-core-data-001 [junit4] 2> 621511 INFO (SUITE-ConnectionReuseTest-seed#[E9A53D57A79C= F6B8]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContex= t system property: / [junit4] 2> 621513 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 621513 INFO (Thread-988) [ ] o.a.s.c.ZkTestServer clie= nt port:0.0.0.0/0.0.0.0:0 [junit4] 2> 621513 INFO (Thread-988) [ ] o.a.s.c.ZkTestServer Star= ting server [junit4] 2> 621613 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.ZkTestServer start zk server on port:35539 [junit4] 2> 621613 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvide= r [junit4] 2> 621613 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect= to ZooKeeper [junit4] 2> 621615 INFO (zkCallback-318-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5ff8= 4ad7 name:ZooKeeperConnection Watcher:127.0.0.1:35539 got event WatchedEven= t state:SyncConnected type:None path:null path:null type:None [junit4] 2> 621615 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKee= per [junit4] 2> 621616 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 621616 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 621617 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvide= r [junit4] 2> 621617 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect= to ZooKeeper [junit4] 2> 621618 INFO (zkCallback-319-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4b95= e1ca name:ZooKeeperConnection Watcher:127.0.0.1:35539/solr got event Watche= dEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 621618 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKee= per [junit4] 2> 621618 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 621618 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 621619 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1= /shards [junit4] 2> 621619 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_col= lection [junit4] 2> 621619 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_col= lection/shards [junit4] 2> 621620 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lu= cene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrco= nfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 621620 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfi= g.xml [junit4] 2> 621620 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lu= cene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema= .xml to /configs/conf1/schema.xml [junit4] 2> 621620 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xm= l [junit4] 2> 621621 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lu= cene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrco= nfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.ran= domindexconfig.xml [junit4] 2> 621621 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfi= g.snippet.randomindexconfig.xml [junit4] 2> 621622 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lu= cene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/stopwo= rds.txt to /configs/conf1/stopwords.txt [junit4] 2> 621622 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords= .txt [junit4] 2> 621622 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lu= cene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/protwo= rds.txt to /configs/conf1/protwords.txt [junit4] 2> 621622 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords= .txt [junit4] 2> 621623 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lu= cene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/curren= cy.xml to /configs/conf1/currency.xml [junit4] 2> 621623 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.= xml [junit4] 2> 621623 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lu= cene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/enumsC= onfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 621623 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConf= ig.xml [junit4] 2> 621624 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lu= cene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/open-e= xchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 621624 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exch= ange-rates.json [junit4] 2> 621624 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lu= cene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/mappin= g-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 621624 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-I= SOLatin1Accent.txt [junit4] 2> 621625 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lu= cene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/old_sy= nonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 621625 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synon= yms.txt [junit4] 2> 621625 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lu= cene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/synony= ms.txt to /configs/conf1/synonyms.txt [junit4] 2> 621625 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.= txt [junit4] 2> 621665 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /hom= e/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/te= mp/solr.client.solrj.ConnectionReuseTest_E9A53D57A79CF6B8-001/control-001/c= ores/collection1 [junit4] 2> 621665 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 621667 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHa= ndler@17c09d0f{/,null,AVAILABLE} [junit4] 2> 621668 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.e.j.s.ServerConnector Started ServerConnector@bd857f8{H= TTP/1.1}{127.0.0.1:34271} [junit4] 2> 621669 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.e.j.s.Server Started @623078ms [junit4] 2> 621669 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.= dir=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/= test/J0/temp/solr.client.solrj.ConnectionReuseTest_E9A53D57A79CF6B8-001/tem= pDir-001/control/data, hostContext=3D/, hostPort=3D34271, coreRootDirectory= =3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/tes= t/J0/temp/solr.client.solrj.ConnectionReuseTest_E9A53D57A79CF6B8-001/contro= l-001/cores} [junit4] 2> 621669 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun= .misc.Launcher$AppClassLoader@73d16e93 [junit4] 2> 621669 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for dir= ectory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-co= re/test/J0/temp/solr.client.solrj.ConnectionReuseTest_E9A53D57A79CF6B8-001/= control-001' [junit4] 2> 621669 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (= NoInitialContextEx) [junit4] 2> 621669 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/'= (could not find system property or JNDI) [junit4] 2> 621669 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvide= r [junit4] 2> 621669 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect= to ZooKeeper [junit4] 2> 621670 INFO (zkCallback-320-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2064= 9c96 name:ZooKeeperConnection Watcher:127.0.0.1:35539/solr got event Watche= dEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 621670 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKee= per [junit4] 2> 621670 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 621671 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome= (not found in ZooKeeper) [junit4] 2> 621671 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration fro= m /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test= /J0/temp/solr.client.solrj.ConnectionReuseTest_E9A53D57A79CF6B8-001/control= -001/solr.xml [junit4] 2> 621674 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root di= rectory: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-co= re/test/J0/temp/solr.client.solrj.ConnectionReuseTest_E9A53D57A79CF6B8-001/= control-001/cores [junit4] 2> 621674 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.CoreContainer New CoreContainer 1612590653 [junit4] 2> 621674 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [i= nstanceDir=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/sol= r-core/test/J0/temp/solr.client.solrj.ConnectionReuseTest_E9A53D57A79CF6B8-= 001/control-001] [junit4] 2> 621674 WARN (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenki= ns/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr= .client.solrj.ConnectionReuseTest_E9A53D57A79CF6B8-001/control-001/lib to c= lasspath: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-c= ore/test/J0/temp/solr.client.solrj.ConnectionReuseTest_E9A53D57A79CF6B8-001= /control-001/lib [junit4] 2> 621674 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTime= out : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,max= Connections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThread= IdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 621675 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HT= TP client with params: socketTimeout=3D340000&connTimeout=3D45000&retry=3Dt= rue [junit4] 2> 621675 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLogg= erFactory [junit4] 2> 621675 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.s= lf4j.impl.Log4jLoggerFactory)] [junit4] 2> 621675 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.ZkContainer Zookeeper client=3D127.0.0.1:35539/so= lr [junit4] 2> 621675 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 621675 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvide= r [junit4] 2> 621675 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect= to ZooKeeper [junit4] 2> 621676 INFO (zkCallback-323-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6efa= d04f name:ZooKeeperConnection Watcher:127.0.0.1:35539 got event WatchedEven= t state:SyncConnected type:None path:null path:null type:None [junit4] 2> 621677 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKee= per [junit4] 2> 621677 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 621678 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.c.c.ConnectionManager Waiting for= client to connect to ZooKeeper [junit4] 2> 621678 INFO (zkCallback-324-thread-1-processing-n:127.0.0= .1:34271_) [n:127.0.0.1:34271_ ] o.a.s.c.c.ConnectionManager Watcher org= .apache.solr.common.cloud.ConnectionManager@5f9c51ef name:ZooKeeperConnecti= on Watcher:127.0.0.1:35539/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4] 2> 621678 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.c.c.ConnectionManager Client is c= onnected to ZooKeeper [junit4] 2> 621679 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.c.c.SolrZkClient makePath: /overs= eer/queue [junit4] 2> 621679 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.c.c.SolrZkClient makePath: /overs= eer/collection-queue-work [junit4] 2> 621680 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.c.c.SolrZkClient makePath: /overs= eer/collection-map-running [junit4] 2> 621681 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.c.c.SolrZkClient makePath: /overs= eer/collection-map-completed [junit4] 2> 621681 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.c.c.SolrZkClient makePath: /overs= eer/collection-map-failure [junit4] 2> 621682 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.c.c.SolrZkClient makePath: /live_= nodes [junit4] 2> 621682 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.c.c.SolrZkClient makePath: /alias= es.json [junit4] 2> 621682 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.c.c.SolrZkClient makePath: /clust= erstate.json [junit4] 2> 621683 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.c.c.SolrZkClient makePath: /secur= ity.json [junit4] 2> 621683 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.c.ZkController Register node as l= ive in ZooKeeper:/live_nodes/127.0.0.1:34271_ [junit4] 2> 621683 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.c.c.SolrZkClient makePath: /live_= nodes/127.0.0.1:34271_ [junit4] 2> 621684 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.c.c.SolrZkClient makePath: /overs= eer_elect [junit4] 2> 621684 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.c.c.SolrZkClient makePath: /overs= eer_elect/election [junit4] 2> 621684 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.c.Overseer Overseer (id=3Dnull) c= losing [junit4] 2> 621684 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.c.LeaderElector Joined leadership= election with path: /overseer_elect/election/95391788484132868-127.0.0.1:3= 4271_-n_0000000000 [junit4] 2> 621684 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.c.OverseerElectionContext I am go= ing to be the leader 127.0.0.1:34271_ [junit4] 2> 621684 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.c.c.SolrZkClient makePath: /overs= eer_elect/leader [junit4] 2> 621685 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.c.Overseer Overseer (id=3D9539178= 8484132868-127.0.0.1:34271_-n_0000000000) starting [junit4] 2> 621685 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.c.c.SolrZkClient makePath: /overs= eer/queue-work [junit4] 2> 621687 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.c.OverseerAutoReplicaFailoverThre= ad Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDe= lay=3D10000 autoReplicaFailoverWaitAfterExpiration=3D30000 autoReplicaFailo= verBadNodeExpiration=3D60000 [junit4] 2> 621687 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.c.c.ZkStateReader Updating cluste= r state from ZooKeeper...=20 [junit4] 2> 621687 INFO (OverseerCollectionConfigSetProcessor-9539178= 8484132868-127.0.0.1:34271_-n_0000000000) [n:127.0.0.1:34271_ ] o.a.s.c.= OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 621688 INFO (OverseerStateUpdate-95391788484132868-127.0.= 0.1:34271_-n_0000000000) [n:127.0.0.1:34271_ ] o.a.s.c.Overseer Starting= to work on the main queue [junit4] 2> 621702 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.c.CoreContainer Security conf doe= sn't exist. Skipping setup for authorization module. [junit4] 2> 621702 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.c.CoreContainer No authentication= plugin used. [junit4] 2> 621702 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.c.CorePropertiesLocator Looking f= or core definitions underneath /home/jenkins/workspace/Lucene-Solr-trunk-Li= nux/solr/build/solr-core/test/J0/temp/solr.client.solrj.ConnectionReuseTest= _E9A53D57A79CF6B8-001/control-001/cores [junit4] 2> 621703 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.c.CoreDescriptor Created CoreDesc= riptor: {name=3Dcollection1, config=3Dsolrconfig.xml, transient=3Dfalse, sc= hema=3Dschema.xml, loadOnStartup=3Dtrue, configSetProperties=3Dconfigsetpro= ps.json, collection=3Dcontrol_collection, coreNodeName=3D, dataDir=3Ddata/,= shard=3D} [junit4] 2> 621703 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.c.CorePropertiesLocator Found cor= e collection1 in /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build= /solr-core/test/J0/temp/solr.client.solrj.ConnectionReuseTest_E9A53D57A79CF= 6B8-001/control-001/cores/collection1 [junit4] 2> 621703 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.c.CorePropertiesLocator Found 1 c= ore definitions [junit4] 2> 621703 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.s.SolrDispatchFilter user.dir=3D/= home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0 [junit4] 2> 621703 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:34271_ ] o.a.s.s.SolrDispatchFilter SolrDispatch= Filter.init() done [junit4] 2> 621704 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection x:collection1]= o.a.s.c.ZkController publishing state=3Ddown [junit4] 2> 621704 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection x:collection1]= o.a.s.c.ZkController numShards not found on descriptor - reading it from s= ystem property [junit4] 2> 621704 INFO (OverseerStateUpdate-95391788484132868-127.0.= 0.1:34271_-n_0000000000) [n:127.0.0.1:34271_ ] o.a.s.c.Overseer processM= essage: queueSize: 1, message =3D { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:34271", [junit4] 2> "node_name":"127.0.0.1:34271_", [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> 621704 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection x:collection1]= o.a.s.c.ZkController look for our core node name [junit4] 2> 621704 INFO (OverseerStateUpdate-95391788484132868-127.0.= 0.1:34271_-n_0000000000) [n:127.0.0.1:34271_ ] 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:34271", [junit4] 2> "node_name":"127.0.0.1:34271_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 621704 INFO (OverseerStateUpdate-95391788484132868-127.0.= 0.1:34271_-n_0000000000) [n:127.0.0.1:34271_ ] o.a.s.c.o.ClusterStateMut= ator building a new cName: control_collection [junit4] 2> 621705 INFO (OverseerStateUpdate-95391788484132868-127.0.= 0.1:34271_-n_0000000000) [n:127.0.0.1:34271_ ] o.a.s.c.o.ReplicaMutator = Assigning new node to shard shard=3Dshard1 [junit4] 2> 621705 INFO (zkCallback-324-thread-1-processing-n:127.0.0= .1:34271_) [n:127.0.0.1:34271_ ] o.a.s.c.c.ZkStateReader A cluster state= change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json], has occurred - updating... (live nodes size: [1]) [junit4] 2> 622704 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection x:collection1]= o.a.s.c.ZkController waiting to find shard id in clusterstate for collecti= on1 [junit4] 2> 622705 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection x:collection1]= o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 622705 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection x:collection1]= o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 622705 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection x:collection1]= o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control= _collection] [junit4] 2> 622705 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection x:collection1]= o.a.s.c.c.ZkStateReader path=3D[/collections/control_collection] [configNa= me]=3D[conf1] specified config exists in ZooKeeper [junit4] 2> 622705 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection x:collection1]= o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/je= nkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/s= olr.client.solrj.ConnectionReuseTest_E9A53D57A79CF6B8-001/control-001/cores= /collection1' [junit4] 2> 622705 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection x:collection1]= o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextE= x) [junit4] 2> 622705 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection x:collection1]= o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find = system property or JNDI) [junit4] 2> 622706 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection x:collection1]= o.a.s.c.Config loaded config solrconfig.xml with version 0=20 [junit4] 2> 622710 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection x:collection1]= o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 622713 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection x:collection1]= o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 622717 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection x:collection1]= o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 622721 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection x:collection1]= o.a.s.s.IndexSchema [collection1] Schema name=3Dtest [junit4] 2> 622773 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection x:collection1]= o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=3Dopen-exchang= e-rates.json, refreshInterval=3D1440. [junit4] 2> 622776 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection x:collection1]= o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 622777 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection x:collection1]= o.a.s.s.IndexSchema unique key field: id [junit4] 2> 622781 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection x:collection1]= o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file curren= cy.xml [junit4] 2> 622782 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection x:collection1]= o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file curren= cy.xml [junit4] 2> 622783 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection x:collection1]= o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-ex= change-rates.json [junit4] 2> 622783 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection x:collection1]= o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-ex= change-rates.json [junit4] 2> 622784 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection x:collection1]= o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming de= fault properties: Can't find resource 'configsetprops.json' in classpath or= '/configs/conf1', cwd=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/so= lr/build/solr-core/test/J0 [junit4] 2> 622784 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection x:collection1]= o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration = from collection control_collection [junit4] 2> 622784 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFac= tory [junit4] 2> 622784 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore= at [/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/t= est/J0/temp/solr.client.solrj.ConnectionReuseTest_E9A53D57A79CF6B8-001/cont= rol-001/cores/collection1], dataDir=3D[/home/jenkins/workspace/Lucene-Solr-= trunk-Linux/solr/build/solr-core/test/J0/temp/solr.client.solrj.ConnectionR= euseTest_E9A53D57A79CF6B8-001/control-001/cores/collection1/data/] [junit4] 2> 622784 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Add= ing Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7bd83= f3a [junit4] 2> 622784 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory f= or /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/tes= t/J0/temp/solr.client.solrj.ConnectionReuseTest_E9A53D57A79CF6B8-001/contro= l-001/cores/collection1/data [junit4] 2> 622784 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=3Dn= ull new=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-c= ore/test/J0/temp/solr.client.solrj.ConnectionReuseTest_E9A53D57A79CF6B8-001= /control-001/cores/collection1/data/index/ [junit4] 2> 622784 WARN (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/= home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0= /temp/solr.client.solrj.ConnectionReuseTest_E9A53D57A79CF6B8-001/control-00= 1/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 622784 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory f= or /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/tes= t/J0/temp/solr.client.solrj.ConnectionReuseTest_E9A53D57A79CF6B8-001/contro= l-001/cores/collection1/data/index [junit4] 2> 622785 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping c= lass org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMerg= eAtOnce=3D28, maxMergeAtOnceExplicit=3D21, maxMergedSegmentMB=3D81.75390625= , floorSegmentMB=3D1.05078125, forceMergeDeletesPctAllowed=3D8.259929496761= 862, segmentsPerTier=3D39.0, maxCFSSegmentSizeMB=3D8.796093022207999E12, no= CFSRatio=3D0.0 [junit4] 2> 622785 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit= : commits: num=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@36690d37= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@6ce93d91),= segFN=3Dsegments_1,generation=3D1} [junit4] 2> 622785 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = =3D 1 [junit4] 2> 622785 INFO (OldIndexDirectoryCleanupThreadForCore-collec= tion1) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_node1 x:col= lection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for= core collection1 in /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/b= uild/solr-core/test/J0/temp/solr.client.solrj.ConnectionReuseTest_E9A53D57A= 79CF6B8-001/control-001/cores/collection1/data/ [junit4] 2> 622785 WARN (OldIndexDirectoryCleanupThreadForCore-collec= tion1) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_node1 x:col= lection1] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr-trun= k-Linux/solr/build/solr-core/test/J0/temp/solr.client.solrj.ConnectionReuse= Test_E9A53D57A79CF6B8-001/control-001/cores/collection1/data/ does not poin= t to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 622787 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateR= equestProcessorChain "nodistrib" [junit4] 2> 622787 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateR= equestProcessorChain "dedupe" [junit4] 2> 622787 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting Distri= butedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 622787 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateR= equestProcessorChain "stored_sig" [junit4] 2> 622788 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting Distri= butedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 622788 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateR= equestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 622788 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateR= equestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 622788 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting Distri= butedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-t= est-chain-implicit" [junit4] 2> 622788 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain define= d as default, creating implicit default [junit4] 2> 622788 WARN (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestH= andler {type =3D requestHandler,name =3D /dump,class =3D DumpRequestHandler= ,args =3D {defaults=3D{a=3DA,b=3DB}}} [junit4] 2> 622789 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 622789 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 622790 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 622790 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 622792 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbean= s,standard,/dump,/update/csv,/sql,/update/json/docs,/admin/luke,/export,/ad= min/segments,/get,/admin/system,/replication,/admin/properties,/config,/str= eam,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admi= n/ping,/update,/admin/file [junit4] 2> 622793 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.a= pache.solr.search.stats.LocalStatsCache [junit4] 2> 622793 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ 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> 622793 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=3D d= efaultSyncLevel=3DFLUSH numRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVer= sionBuckets=3D65536 [junit4] 2> 622793 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 622793 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 622794 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping c= lass org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolic= y: minMergeSize=3D1677721, mergeFactor=3D41, maxMergeSize=3D2147483648, max= MergeSizeForForcedMerge=3D9223372036854775807, calibrateSizeByDeletes=3Dtru= e, maxMergeDocs=3D2147483647, maxCFSSegmentSizeMB=3D8.796093022207999E12, n= oCFSRatio=3D1.0] [junit4] 2> 622794 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ 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@36690d37= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@6ce93d91),= segFN=3Dsegments_1,generation=3D1} [junit4] 2> 622794 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = =3D 1 [junit4] 2> 622794 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@76074ada[c= ollection1] main] [junit4] 2> 622794 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [= /collections/control_collection] [junit4] 2> 622794 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.c.ZkStateReader path=3D[/collections/control_c= ollection] [configName]=3D[conf1] specified config exists in ZooKeeper [junit4] 2> 622794 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-ba= sed storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 622795 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperSto= rageIO with znodeBase: /configs/conf1 [junit4] 2> 622795 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with init= Args: {} [junit4] 2> 622795 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.j= son using ZooKeeperStorageIO:path=3D/configs/conf1 [junit4] 2> 622795 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.r.ManagedResourceStorage No data found for znode= /configs/conf1/_rest_managed.json [junit4] 2> 622795 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _re= st_managed.json using ZooKeeperStorageIO:path=3D/configs/conf1 [junit4] 2> 622795 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedR= esources [junit4] 2> 622795 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved fo= r 10000 [junit4] 2> 622795 INFO (searcherExecutor-1344-thread-1-processing-n:= 127.0.0.1:34271_ x:collection1 s:shard1 c:control_collection r:core_node1) = [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_node1 x:collection= 1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@76074ada= [collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 622795 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.UpdateLog Looking up max value of version fiel= d to seed version buckets [junit4] 2> 622796 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _versi= on_ for 65536 version buckets from index [junit4] 2> 622796 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cann= ot seed version bucket highest value from index [junit4] 2> 622796 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ 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 1526268617090400256 [junit4] 2> 622797 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets w= ith highest version 1526268617090400256 [junit4] 2> 622797 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 622797 INFO (coreLoadExecutor-1343-thread-1-processing-n:= 127.0.0.1:34271_) [n:127.0.0.1:34271_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 622798 INFO (coreZkRegister-1337-thread-1-processing-n:12= 7.0.0.1:34271_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:34271_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.ZkController Register replica - core:collection1 address:http://12= 7.0.0.1:34271 collection:control_collection shard:shard1 [junit4] 2> 622798 INFO (coreZkRegister-1337-thread-1-processing-n:12= 7.0.0.1:34271_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:34271_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leader_el= ect/shard1/election [junit4] 2> 622799 INFO (coreZkRegister-1337-thread-1-processing-n:12= 7.0.0.1:34271_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:34271_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.LeaderElector Joined leadership election with path: /collections/c= ontrol_collection/leader_elect/shard1/election/95391788484132868-core_node1= -n_0000000000 [junit4] 2> 622799 INFO (coreZkRegister-1337-thread-1-processing-n:12= 7.0.0.1:34271_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:34271_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=3D= shard1 and weAreReplacement=3Dfalse and leaderVoteWait=3D10000 [junit4] 2> 622800 INFO (coreZkRegister-1337-thread-1-processing-n:12= 7.0.0.1:34271_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:34271_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 622800 INFO (coreZkRegister-1337-thread-1-processing-n:12= 7.0.0.1:34271_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:34271_ 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> 622800 INFO (coreZkRegister-1337-thread-1-processing-n:12= 7.0.0.1:34271_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:34271_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:34271/collection1/ [junit4] 2> 622800 INFO (OverseerStateUpdate-95391788484132868-127.0.= 0.1:34271_-n_0000000000) [n:127.0.0.1:34271_ ] o.a.s.c.Overseer processM= essage: queueSize: 1, message =3D { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection"} current state version= : 1 [junit4] 2> 622800 INFO (coreZkRegister-1337-thread-1-processing-n:12= 7.0.0.1:34271_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:34271_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 622800 INFO (coreZkRegister-1337-thread-1-processing-n:12= 7.0.0.1:34271_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:34271_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.SyncStrategy http://127.0.0.1:34271/collection1/ has no replicas [junit4] 2> 622800 INFO (coreZkRegister-1337-thread-1-processing-n:12= 7.0.0.1:34271_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:34271_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/s= hard1 [junit4] 2> 622801 INFO (coreZkRegister-1337-thread-1-processing-n:12= 7.0.0.1:34271_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:34271_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node [junit4] 2> 622801 INFO (coreZkRegister-1337-thread-1-processing-n:12= 7.0.0.1:34271_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:34271_ 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:3= 4271/collection1/ shard1 [junit4] 2> 622802 INFO (OverseerStateUpdate-95391788484132868-127.0.= 0.1:34271_-n_0000000000) [n:127.0.0.1:34271_ ] o.a.s.c.Overseer processM= essage: 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:34271", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 622902 INFO (zkCallback-324-thread-1-processing-n:127.0.0= .1:34271_) [n:127.0.0.1:34271_ ] o.a.s.c.c.ZkStateReader A cluster state= change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json], has occurred - updating... (live nodes size: [1]) [junit4] 2> 622952 INFO (coreZkRegister-1337-thread-1-processing-n:12= 7.0.0.1:34271_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:34271_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.ZkController We are http://127.0.0.1:34271/collection1/ and leader= is http://127.0.0.1:34271/collection1/ [junit4] 2> 622952 INFO (coreZkRegister-1337-thread-1-processing-n:12= 7.0.0.1:34271_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:34271_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.ZkController No LogReplay needed for core=3Dcollection1 baseURL=3D= http://127.0.0.1:34271 [junit4] 2> 622952 INFO (coreZkRegister-1337-thread-1-processing-n:12= 7.0.0.1:34271_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:34271_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 622952 INFO (coreZkRegister-1337-thread-1-processing-n:12= 7.0.0.1:34271_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:34271_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.ZkController publishing state=3Dactive [junit4] 2> 622952 INFO (coreZkRegister-1337-thread-1-processing-n:12= 7.0.0.1:34271_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:34271_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.ZkController numShards not found on descriptor - reading it from s= ystem property [junit4] 2> 622953 INFO (OverseerStateUpdate-95391788484132868-127.0.= 0.1:34271_-n_0000000000) [n:127.0.0.1:34271_ ] o.a.s.c.Overseer processM= essage: 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:34271", [junit4] 2> "node_name":"127.0.0.1:34271_", [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> 622953 INFO (OverseerStateUpdate-95391788484132868-127.0.= 0.1:34271_-n_0000000000) [n:127.0.0.1:34271_ ] 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:34271", [junit4] 2> "node_name":"127.0.0.1:34271_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 623054 INFO (zkCallback-324-thread-1-processing-n:127.0.0= .1:34271_) [n:127.0.0.1:34271_ ] o.a.s.c.c.ZkStateReader A cluster state= change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json], has occurred - updating... (live nodes size: [1]) [junit4] 2> 623205 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvide= r [junit4] 2> 623205 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect= to ZooKeeper [junit4] 2> 623206 INFO (zkCallback-326-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5045= 0f7b name:ZooKeeperConnection Watcher:127.0.0.1:35539/solr got event Watche= dEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 623206 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKee= per [junit4] 2> 623206 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 623206 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKe= eper...=20 [junit4] 2> 623207 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false = cause connection loss:false [junit4] 2> 623248 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /hom= e/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/te= mp/solr.client.solrj.ConnectionReuseTest_E9A53D57A79CF6B8-001/shard-1-001/c= ores/collection1 [junit4] 2> 623248 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in d= irectory /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-co= re/test/J0/temp/solr.client.solrj.ConnectionReuseTest_E9A53D57A79CF6B8-001/= shard-1-001 [junit4] 2> 623248 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 623249 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHa= ndler@657aee26{/,null,AVAILABLE} [junit4] 2> 623250 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.e.j.s.ServerConnector Started ServerConnector@5c4a4700{= HTTP/1.1}{127.0.0.1:50696} [junit4] 2> 623250 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.e.j.s.Server Started @624659ms [junit4] 2> 623250 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.= dir=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/= test/J0/temp/solr.client.solrj.ConnectionReuseTest_E9A53D57A79CF6B8-001/tem= pDir-001/jetty1, solrconfig=3Dsolrconfig.xml, hostContext=3D/, hostPort=3D5= 0696, coreRootDirectory=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspa= ce/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.client.so= lrj.ConnectionReuseTest_E9A53D57A79CF6B8-001/shard-1-001/cores} [junit4] 2> 623250 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun= .misc.Launcher$AppClassLoader@73d16e93 [junit4] 2> 623250 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for dir= ectory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-co= re/test/J0/temp/solr.client.solrj.ConnectionReuseTest_E9A53D57A79CF6B8-001/= shard-1-001' [junit4] 2> 623250 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (= NoInitialContextEx) [junit4] 2> 623250 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/'= (could not find system property or JNDI) [junit4] 2> 623250 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvide= r [junit4] 2> 623250 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect= to ZooKeeper [junit4] 2> 623251 INFO (zkCallback-327-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2801= 5749 name:ZooKeeperConnection Watcher:127.0.0.1:35539/solr got event Watche= dEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 623251 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKee= per [junit4] 2> 623251 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 623252 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome= (not found in ZooKeeper) [junit4] 2> 623252 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration fro= m /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test= /J0/temp/solr.client.solrj.ConnectionReuseTest_E9A53D57A79CF6B8-001/shard-1= -001/solr.xml [junit4] 2> 623255 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root di= rectory: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-co= re/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-tr= unk-Linux/solr/build/solr-core/test/J0/temp/solr.client.solrj.ConnectionReu= seTest_E9A53D57A79CF6B8-001/shard-1-001/cores [junit4] 2> 623255 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.CoreContainer New CoreContainer 2007050972 [junit4] 2> 623255 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [i= nstanceDir=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/sol= r-core/test/J0/temp/solr.client.solrj.ConnectionReuseTest_E9A53D57A79CF6B8-= 001/shard-1-001] [junit4] 2> 623255 WARN (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenki= ns/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr= .client.solrj.ConnectionReuseTest_E9A53D57A79CF6B8-001/shard-1-001/lib to c= lasspath: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-c= ore/test/J0/temp/solr.client.solrj.ConnectionReuseTest_E9A53D57A79CF6B8-001= /shard-1-001/lib [junit4] 2> 623255 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTime= out : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,max= Connections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThread= IdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 623256 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HT= TP client with params: socketTimeout=3D340000&connTimeout=3D45000&retry=3Dt= rue [junit4] 2> 623256 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLogg= erFactory [junit4] 2> 623256 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.s= lf4j.impl.Log4jLoggerFactory)] [junit4] 2> 623256 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.ZkContainer Zookeeper client=3D127.0.0.1:35539/so= lr [junit4] 2> 623256 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 623256 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvide= r [junit4] 2> 623257 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect= to ZooKeeper [junit4] 2> 623258 INFO (zkCallback-330-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@76a6= 511f name:ZooKeeperConnection Watcher:127.0.0.1:35539 got event WatchedEven= t state:SyncConnected type:None path:null path:null type:None [junit4] 2> 623258 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKee= per [junit4] 2> 623259 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 623260 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:50696_ ] o.a.s.c.c.ConnectionManager Waiting for= client to connect to ZooKeeper [junit4] 2> 623260 INFO (zkCallback-331-thread-1-processing-n:127.0.0= .1:50696_) [n:127.0.0.1:50696_ ] o.a.s.c.c.ConnectionManager Watcher org= .apache.solr.common.cloud.ConnectionManager@5cbb16f2 name:ZooKeeperConnecti= on Watcher:127.0.0.1:35539/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4] 2> 623260 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:50696_ ] o.a.s.c.c.ConnectionManager Client is c= onnected to ZooKeeper [junit4] 2> 623262 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:50696_ ] o.a.s.c.c.ZkStateReader Updating cluste= r state from ZooKeeper...=20 [junit4] 2> 624264 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:50696_ ] o.a.s.c.ZkController Register node as l= ive in ZooKeeper:/live_nodes/127.0.0.1:50696_ [junit4] 2> 629238 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:50696_ ] o.a.s.c.c.SolrZkClient makePath: /live_= nodes/127.0.0.1:50696_ [junit4] 2> 629240 INFO (zkCallback-326-thread-1) [ ] o.a.s.c.c.Zk= StateReader A live node change: [WatchedEvent state:SyncConnected type:Node= ChildrenChanged path:/live_nodes], has occurred - updating... (live nodes s= ize: [1]) [junit4] 2> 629240 INFO (zkCallback-331-thread-1-processing-n:127.0.0= .1:50696_) [n:127.0.0.1:50696_ ] o.a.s.c.c.ZkStateReader A live node cha= nge: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_= nodes], has occurred - updating... (live nodes size: [1]) [junit4] 2> 629240 INFO (zkCallback-324-thread-2-processing-n:127.0.0= .1:34271_) [n:127.0.0.1:34271_ ] o.a.s.c.c.ZkStateReader A live node cha= nge: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_= nodes], has occurred - updating... (live nodes size: [1]) [junit4] 2> 629241 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:50696_ ] o.a.s.c.Overseer Overseer (id=3Dnull) c= losing [junit4] 2> 629242 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:50696_ ] o.a.s.c.LeaderElector Joined leadership= election with path: /overseer_elect/election/95391788484132872-127.0.0.1:5= 0696_-n_0000000001 [junit4] 2> 629242 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:50696_ ] o.a.s.c.LeaderElector Watching path /ov= erseer_elect/election/95391788484132868-127.0.0.1:34271_-n_0000000000 to kn= ow if I could be the leader [junit4] 2> 629251 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:50696_ ] o.a.s.c.CoreContainer Security conf doe= sn't exist. Skipping setup for authorization module. [junit4] 2> 629251 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:50696_ ] o.a.s.c.CoreContainer No authentication= plugin used. [junit4] 2> 629251 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:50696_ ] o.a.s.c.CorePropertiesLocator Looking f= or core definitions underneath /home/jenkins/workspace/Lucene-Solr-trunk-Li= nux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/wo= rkspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.clie= nt.solrj.ConnectionReuseTest_E9A53D57A79CF6B8-001/shard-1-001/cores [junit4] 2> 629252 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:50696_ ] o.a.s.c.CoreDescriptor Created CoreDesc= riptor: {name=3Dcollection1, config=3Dsolrconfig.xml, transient=3Dfalse, sc= hema=3Dschema.xml, loadOnStartup=3Dtrue, configSetProperties=3Dconfigsetpro= ps.json, collection=3Dcollection1, coreNodeName=3D, dataDir=3Ddata/, shard= =3D} [junit4] 2> 629252 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:50696_ ] o.a.s.c.CorePropertiesLocator Found cor= e collection1 in /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build= /solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene= -Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.client.solrj.Conne= ctionReuseTest_E9A53D57A79CF6B8-001/shard-1-001/cores/collection1 [junit4] 2> 629252 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:50696_ ] o.a.s.c.CorePropertiesLocator Found 1 c= ore definitions [junit4] 2> 629253 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:50696_ ] o.a.s.s.SolrDispatchFilter user.dir=3D/= home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0 [junit4] 2> 629253 INFO (TEST-ConnectionReuseTest.test-seed#[E9A53D57= A79CF6B8]) [n:127.0.0.1:50696_ ] o.a.s.s.SolrDispatchFilter SolrDispatch= Filter.init() done [junit4] 2> 629253 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 x:collection1] o.a.s.= c.ZkController publishing state=3Ddown [junit4] 2> 629253 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 x:collection1] o.a.s.= c.ZkController numShards not found on descriptor - reading it from system p= roperty [junit4] 2> 629254 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 x:collection1] o.a.s.= c.ZkController look for our core node name [junit4] 2> 629254 INFO (OverseerStateUpdate-95391788484132868-127.0.= 0.1:34271_-n_0000000000) [n:127.0.0.1:34271_ ] o.a.s.c.Overseer processM= essage: queueSize: 1, message =3D { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:50696", [junit4] 2> "node_name":"127.0.0.1:50696_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 629254 INFO (OverseerStateUpdate-95391788484132868-127.0.= 0.1:34271_-n_0000000000) [n:127.0.0.1:34271_ ] 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:50696", [junit4] 2> "node_name":"127.0.0.1:50696_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 629254 INFO (OverseerStateUpdate-95391788484132868-127.0.= 0.1:34271_-n_0000000000) [n:127.0.0.1:34271_ ] o.a.s.c.o.ClusterStateMut= ator building a new cName: collection1 [junit4] 2> 629254 INFO (OverseerStateUpdate-95391788484132868-127.0.= 0.1:34271_-n_0000000000) [n:127.0.0.1:34271_ ] o.a.s.c.o.ReplicaMutator = Assigning new node to shard shard=3Dshard1 [junit4] 2> 629255 INFO (zkCallback-324-thread-2-processing-n:127.0.0= .1:34271_) [n:127.0.0.1:34271_ ] o.a.s.c.c.ZkStateReader A cluster state= change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json], has occurred - updating... (live nodes size: [2]) [junit4] 2> 629255 INFO (zkCallback-326-thread-1) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDataChanged path:/clusterstate.json], has occurred - updating... (live = nodes size: [2]) [junit4] 2> 629255 INFO (zkCallback-331-thread-1-processing-n:127.0.0= .1:50696_) [n:127.0.0.1:50696_ ] o.a.s.c.c.ZkStateReader A cluster state= change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json], has occurred - updating... (live nodes size: [2]) [junit4] 2> 630254 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 x:collection1] o.a.s.= c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 630254 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 x:collection1] o.a.s.= c.ZkController Check for collection zkNode:collection1 [junit4] 2> 630254 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 x:collection1] o.a.s.= c.ZkController Collection zkNode exists [junit4] 2> 630254 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 x:collection1] o.a.s.= c.c.ZkStateReader Load collection config from: [/collections/collection1] [junit4] 2> 630255 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 x:collection1] o.a.s.= c.c.ZkStateReader path=3D[/collections/collection1] [configName]=3D[conf1] = specified config exists in ZooKeeper [junit4] 2> 630255 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 x:collection1] o.a.s.= c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/w= orkspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cli= ent.solrj.ConnectionReuseTest_E9A53D57A79CF6B8-001/shard-1-001/cores/collec= tion1' [junit4] 2> 630255 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 x:collection1] o.a.s.= c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 630255 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 x:collection1] o.a.s.= c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system = property or JNDI) [junit4] 2> 630256 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 x:collection1] o.a.s.= c.Config loaded config solrconfig.xml with version 0=20 [junit4] 2> 630260 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 x:collection1] o.a.s.= c.SolrConfig current version of requestparams : -1 [junit4] 2> 630262 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 x:collection1] o.a.s.= c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 630273 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 x:collection1] o.a.s.= c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 630277 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 x:collection1] o.a.s.= s.IndexSchema [collection1] Schema name=3Dtest [junit4] 2> 630330 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 x:collection1] o.a.s.= s.OpenExchangeRatesOrgProvider Initialized with rates=3Dopen-exchange-rates= .json, refreshInterval=3D1440. [junit4] 2> 630333 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 x:collection1] o.a.s.= s.IndexSchema default search field in schema is text [junit4] 2> 630334 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 x:collection1] o.a.s.= s.IndexSchema unique key field: id [junit4] 2> 630338 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 x:collection1] o.a.s.= s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 630339 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 x:collection1] o.a.s.= s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 630340 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 x:collection1] o.a.s.= s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-= rates.json [junit4] 2> 630340 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 x:collection1] o.a.s.= s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-= rates.json [junit4] 2> 630341 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 x:collection1] o.a.s.= c.ConfigSetProperties Did not find ConfigSet properties, assuming default p= roperties: Can't find resource 'configsetprops.json' in classpath or '/conf= igs/conf1', cwd=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/buil= d/solr-core/test/J0 [junit4] 2> 630341 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 x:collection1] o.a.s.= c.CoreContainer Creating SolrCore 'collection1' using configuration from co= llection collection1 [junit4] 2> 630341 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 s:shard1 r:core_node1 x= :collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 630341 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 s:shard1 r:core_node1 x= :collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/h= ome/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/= temp/solr.client.solrj.ConnectionReuseTest_E9A53D57A79CF6B8-001/shard-1-001= /cores/collection1], dataDir=3D[/home/jenkins/workspace/Lucene-Solr-trunk-L= inux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/w= orkspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cli= ent.solrj.ConnectionReuseTest_E9A53D57A79CF6B8-001/shard-1-001/cores/collec= tion1/data/] [junit4] 2> 630341 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 s:shard1 r:core_node1 x= :collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Sol= r mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7bd83f3a [junit4] 2> 630342 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 s:shard1 r:core_node1 x= :collection1] o.a.s.c.CachingDirectoryFactory return new directory for /hom= e/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/..= /../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/sol= r/build/solr-core/test/J0/temp/solr.client.solrj.ConnectionReuseTest_E9A53D= 57A79CF6B8-001/shard-1-001/cores/collection1/data [junit4] 2> 630342 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 s:shard1 r:core_node1 x= :collection1] o.a.s.c.SolrCore New index directory detected: old=3Dnull new= =3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/tes= t/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Li= nux/solr/build/solr-core/test/J0/temp/solr.client.solrj.ConnectionReuseTest= _E9A53D57A79CF6B8-001/shard-1-001/cores/collection1/data/index/ [junit4] 2> 630342 WARN (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 s:shard1 r:core_node1 x= :collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/home/je= nkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/../../= ../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/bu= ild/solr-core/test/J0/temp/solr.client.solrj.ConnectionReuseTest_E9A53D57A7= 9CF6B8-001/shard-1-001/cores/collection1/data/index' doesn't exist. Creatin= g new index... [junit4] 2> 630342 INFO (coreLoadExecutor-1354-thread-1-processing-n:= 127.0.0.1:50696_) [n:127.0.0.1:50696_ c:collection1 s:shard1 r:core_node1 x= :collection1] o.a.s.c.CachingDirectoryFactory return new directory for /hom= e/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/..= /../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/sol= r/build/solr-core/test/J0/temp/solr.client.solrj.ConnectionReuseTest_E9A53D= 57A79CF6B8-001/shard-1-001/cores/colle [...truncated too long message...] 6B8-001/shard-2-001/cores/collection1/data [CachedDir<>] [junit4] 2> 904185 INFO (coreCloseExecutor-2917-thread-1) [n:127.0.0.= 1:35258_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.Caching= DirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-tru= nk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsS= afeTest_E9A53D57A79CF6B8-001/shard-2-001/cores/collection1/data [junit4] 2> 904185 INFO (coreCloseExecutor-2917-thread-1) [n:127.0.0.= 1:35258_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.Caching= DirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-trunk= -Linux/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSaf= eTest_E9A53D57A79CF6B8-001/shard-2-001/cores/collection1/data/index [Cached= Dir<>] [junit4] 2> 904185 INFO (coreCloseExecutor-2917-thread-1) [n:127.0.0.= 1:35258_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.Caching= DirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-tru= nk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsS= afeTest_E9A53D57A79CF6B8-001/shard-2-001/cores/collection1/data/index [junit4] 2> 904186 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#= [E9A53D57A79CF6B8]) [ ] o.a.s.c.Overseer Overseer (id=3D9539179613677159= 5-127.0.0.1:35258_-n_0000000002) closing [junit4] 2> 904186 INFO (OverseerStateUpdate-95391796136771595-127.0.= 0.1:35258_-n_0000000002) [n:127.0.0.1:35258_ ] o.a.s.c.Overseer Overseer= Loop exiting : 127.0.0.1:35258_ [junit4] 2> 904209 INFO (zkCallback-797-thread-4-processing-n:127.0.0= .1:35258_) [n:127.0.0.1:35258_ ] o.a.s.c.c.ZkStateReader A live node cha= nge: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_= nodes], has occurred - updating... (live nodes size: [1]) [junit4] 2> 904209 WARN (zkCallback-797-thread-4-processing-n:127.0.0= .1:35258_) [n:127.0.0.1:35258_ ] o.a.s.c.c.ZkStateReader ZooKeeper watch= triggered, but Solr cannot talk to ZK: [KeeperErrorCode =3D Session expire= d for /live_nodes] [junit4] 2> 904211 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#= [E9A53D57A79CF6B8]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@= 34072d89{HTTP/1.1}{127.0.0.1:0} [junit4] 2> 904211 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#= [E9A53D57A79CF6B8]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.Servlet= ContextHandler@7c51b3de{/,null,UNAVAILABLE} [junit4] 2> 904212 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#= [E9A53D57A79CF6B8]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:606= 19 60619 [junit4] 2> 904321 INFO (Thread-2271) [ ] o.a.s.c.ZkTestServer con= necting to 127.0.0.1:60619 60619 [junit4] 2> 904321 WARN (Thread-2271) [ ] o.a.s.c.ZkTestServer Wat= ch limit violations:=20 [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2>=20 [junit4] 2> =097=09/solr/overseer/queue [junit4] 2>=20 [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DChaosMonkeyNo= thingIsSafeTest -Dtests.method=3Dtest -Dtests.seed=3DE9A53D57A79CF6B8 -Dtes= ts.multiplier=3D3 -Dtests.slow=3Dtrue -Dtests.locale=3Dko -Dtests.timezone= =3DEurope/Andorra -Dtests.asserts=3Dtrue -Dtests.file.encoding=3DUTF-8 [junit4] ERROR 166s J1 | ChaosMonkeyNothingIsSafeTest.test <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrCl= ient$RemoteSolrException: Error from server at http://127.0.0.1:35258: coll= ection already exists: testcollection [junit4] > =09at __randomizedtesting.SeedInfo.seed([E9A53D57A79CF6B8:= 61F1028D09609B40]:0) [junit4] > =09at org.apache.solr.client.solrj.impl.HttpSolrClient.exe= cuteMethod(HttpSolrClient.java:577) [junit4] > =09at org.apache.solr.client.solrj.impl.HttpSolrClient.req= uest(HttpSolrClient.java:241) [junit4] > =09at org.apache.solr.client.solrj.impl.HttpSolrClient.req= uest(HttpSolrClient.java:230) [junit4] > =09at org.apache.solr.client.solrj.impl.LBHttpSolrClient.d= oRequest(LBHttpSolrClient.java:372) [junit4] > =09at org.apache.solr.client.solrj.impl.LBHttpSolrClient.r= equest(LBHttpSolrClient.java:325) [junit4] > =09at org.apache.solr.client.solrj.impl.CloudSolrClient.se= ndRequest(CloudSolrClient.java:1099) [junit4] > =09at org.apache.solr.client.solrj.impl.CloudSolrClient.re= questWithRetryOnStaleState(CloudSolrClient.java:870) [junit4] > =09at org.apache.solr.client.solrj.impl.CloudSolrClient.re= quest(CloudSolrClient.java:806) [junit4] > =09at org.apache.solr.client.solrj.SolrClient.request(Solr= Client.java:1219) [junit4] > =09at org.apache.solr.cloud.AbstractFullDistribZkTestBase.= createCollection(AbstractFullDistribZkTestBase.java:1593) [junit4] > =09at org.apache.solr.cloud.AbstractFullDistribZkTestBase.= createCollection(AbstractFullDistribZkTestBase.java:1614) [junit4] > =09at org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest.t= est(ChaosMonkeyNothingIsSafeTest.java:249) [junit4] > =09at org.apache.solr.BaseDistributedSearchTestCase$Shards= RepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase= .java:964) [junit4] > =09at org.apache.solr.BaseDistributedSearchTestCase$Shards= RepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:939) [junit4] > =09at java.lang.Thread.run(Thread.java:745) [junit4] 2> 904333 INFO (SUITE-ChaosMonkeyNothingIsSafeTest-seed#[E9A= 53D57A79CF6B8]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/wo= rkspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.clou= d.ChaosMonkeyNothingIsSafeTest_E9A53D57A79CF6B8-001 [junit4] 2> Feb 15, 2016 7:11:01 PM com.carrotsearch.randomizedtesting= .ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thre= ad(s). [junit4] 2> NOTE: test params are: codec=3DAsserting(Lucene60): {rnd_b= =3DPostingsFormat(name=3DMemory doPackFST=3D true), _version_=3DTestBloomFi= lteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=3D128)= )), a_t=3DTestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Luce= ne50(blocksize=3D128))), a_i=3DPostingsFormat(name=3DMemory doPackFST=3D tr= ue), id=3DPostingsFormat(name=3DMemory doPackFST=3D true)}, docValues:{}, s= im=3DRandomSimilarity(queryNorm=3Dtrue,coord=3Dcrazy): {}, locale=3Dko, tim= ezone=3DEurope/Andorra [junit4] 2> NOTE: Linux 3.19.0-47-generic amd64/Oracle Corporation 1.8= .0_72 (64-bit)/cpus=3D12,threads=3D1,free=3D158271512,total=3D515899392 [junit4] 2> NOTE: All tests run in this JVM: [SolrCmdDistributorTest, = TestSortingResponseWriter, TestRecoveryHdfs, TestDynamicFieldResource, Test= SolrDynamicMBean, DistanceFunctionTest, TestWordDelimiterFilterFactory, Tes= tBM25SimilarityFactory, HdfsWriteToMultipleCollectionsTest, TermVectorCompo= nentTest, MinimalSchemaTest, SpellPossibilityIteratorTest, ClusterStateUpda= teTest, TimeZoneUtilsTest, HdfsCollectionsAPIDistributedZkTest, OverseerTas= kQueueTest, TestQuerySenderListener, ShardRoutingCustomTest, SortByFunction= Test, TestReplicaProperties, CdcrReplicationHandlerTest, TestUseDocValuesAs= Stored2, SharedFSAutoReplicaFailoverTest, TestSolrQueryParserResource, Open= ExchangeRatesOrgProviderTest, TestEmbeddedSolrServerConstructors, TestFileD= ictionaryLookup, TestHashPartitioner, SpatialRPTFieldTypeTest, TestInitQPar= ser, TestDFRSimilarityFactory, TestQuerySenderNoQuery, SolrPluginUtilsTest,= ExitableDirectoryReaderTest, AliasIntegrationTest, TestQueryUtils, TestCol= lapseQParserPlugin, DistributedExpandComponentTest, FastVectorHighlighterTe= st, DistributedTermsComponentTest, DocValuesTest, HdfsThreadLeakTest, TestC= onfigSets, CollectionReloadTest, TestMacros, TestJsonRequest, TestShortCirc= uitedRequests, CollectionsAPIDistributedZkTest, TestConfigSetsAPI, TestDefa= ultStatsCache, FileUtilsTest, TestUpdate, TestManagedSchemaFieldResource, M= ultiTermTest, AnalysisErrorHandlingTest, ParsingFieldUpdateProcessorsTest, = TestExclusionRuleCollectionAccess, ZkCLITest, FacetPivotSmallTest, TestClou= dInspectUtil, GraphQueryTest, DistribJoinFromCollectionTest, DateRangeField= Test, LeaderInitiatedRecoveryOnCommitTest, TestCoreContainer, SchemaVersion= SpecificBehaviorTest, TestSimpleQParserPlugin, IndexBasedSpellCheckerTest, = ChaosMonkeyNothingIsSafeTest] [junit4] Completed [186/579 (2!)] on J1 in 166.66s, 1 test, 1 error <<< = FAILURES! [...truncated 1298 lines...] BUILD FAILED /home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:740: The followin= g error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:684: The followin= g error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:59: The following= error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:233: The fol= lowing error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:524: = The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:145= 7: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:101= 4: There were test failures: 579 suites (8 ignored), 2331 tests, 1 error, 1= failure, 117 ignored (72 assumptions) [seed: E9A53D57A79CF6B8] Total time: 56 minutes 25 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts [WARNINGS] Skipping publisher since build result is FAILURE Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any ------=_Part_0_697846512.1455564841676 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_0_697846512.1455564841676--