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 E80FC18231 for ; Fri, 4 Mar 2016 14:19:44 +0000 (UTC) Received: (qmail 42568 invoked by uid 500); 4 Mar 2016 14:19:43 -0000 Delivered-To: apmail-lucene-dev-archive@lucene.apache.org Received: (qmail 42506 invoked by uid 500); 4 Mar 2016 14:19:43 -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 42496 invoked by uid 99); 4 Mar 2016 14:19:43 -0000 Received: from pnap-us-west-generic-nat.apache.org (HELO spamd3-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Fri, 04 Mar 2016 14:19:43 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd3-us-west.apache.org (ASF Mail Server at spamd3-us-west.apache.org) with ESMTP id 0AE6B1806AD for ; Fri, 4 Mar 2016 14:19:43 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd3-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 (spamd3-us-west.apache.org [10.40.0.10]) (amavisd-new, port 10024) with ESMTP id glhIcoS31AMV for ; Fri, 4 Mar 2016 14:19:26 +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 E663B5F56F for ; Fri, 4 Mar 2016 14:19:25 +0000 (UTC) Received: from localhost.localdomain ([127.0.0.1] helo=serv1) by serv1 with esmtp (Exim 4.82) (envelope-from ) id 1abqZg-0002ky-6d for dev@lucene.apache.org; Fri, 04 Mar 2016 14:19:24 +0000 Date: Fri, 4 Mar 2016 14:19:11 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org Message-ID: <591969062.229.1457101164206.JavaMail.jenkins@serv1> In-Reply-To: <413570064.223.1457096493483.JavaMail.jenkins@serv1> References: <413570064.223.1457096493483.JavaMail.jenkins@serv1> Subject: [JENKINS] Lucene-Solr-6.x-Linux (32bit/jdk1.8.0_72) - Build # 14 - Still Failing! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_228_1122943243.1457101164171" X-Jenkins-Job: Lucene-Solr-6.x-Linux X-Jenkins-Result: FAILURE ------=_Part_228_1122943243.1457101164171 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: http://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/14/ Java: 32bit/jdk1.8.0_72 -client -XX:+UseSerialGC 1 tests failed. FAILED: org.apache.solr.cloud.CollectionTooManyReplicasTest.testDownedShar= ds Error Message: Should have gotten a message about shard not=20 Stack Trace: java.lang.AssertionError: Should have gotten a message about shard not=20 =09at __randomizedtesting.SeedInfo.seed([EDF3CF085726CE2A:8FDB675DDCDD836E]= :0) =09at org.junit.Assert.fail(Assert.java:93) =09at org.junit.Assert.assertTrue(Assert.java:43) =09at org.apache.solr.cloud.CollectionTooManyReplicasTest.testDownedShards(= CollectionTooManyReplicasTest.java:256) =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:996) =09at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= Statement.evaluate(BaseDistributedSearchTestCase.java:971) =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 11084 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionTooManyReplicasTest [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-6.x-= Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionTooManyReplica= sTest_EDF3CF085726CE2A-001/init-core-data-001 [junit4] 2> 524708 INFO (SUITE-CollectionTooManyReplicasTest-seed#[ED= F3CF085726CE2A]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) = and clientAuth (false) [junit4] 2> 524708 INFO (SUITE-CollectionTooManyReplicasTest-seed#[ED= F3CF085726CE2A]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting = hostContext system property: / [junit4] 2> 524709 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.ZkTestServer STARTING= ZK TEST SERVER [junit4] 2> 524710 INFO (Thread-2035) [ ] o.a.s.c.ZkTestServer cli= ent port:0.0.0.0/0.0.0.0:0 [junit4] 2> 524710 INFO (Thread-2035) [ ] o.a.s.c.ZkTestServer Sta= rting server [junit4] 2> 524810 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.ZkTestServer start zk= server on port:52046 [junit4] 2> 524810 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.SolrZkClient Using = default ZkCredentialsProvider [junit4] 2> 524811 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.ConnectionManager W= aiting for client to connect to ZooKeeper [junit4] 2> 524812 INFO (zkCallback-670-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@d45f= ad name:ZooKeeperConnection Watcher:127.0.0.1:52046 got event WatchedEvent = state:SyncConnected type:None path:null path:null type:None [junit4] 2> 524812 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.ConnectionManager C= lient is connected to ZooKeeper [junit4] 2> 524813 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.SolrZkClient Using = default ZkACLProvider [junit4] 2> 524813 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.SolrZkClient makePa= th: /solr [junit4] 2> 524815 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.SolrZkClient Using = default ZkCredentialsProvider [junit4] 2> 524816 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.ConnectionManager W= aiting for client to connect to ZooKeeper [junit4] 2> 524817 INFO (zkCallback-671-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1be0= d77 name:ZooKeeperConnection Watcher:127.0.0.1:52046/solr got event Watched= Event state:SyncConnected type:None path:null path:null type:None [junit4] 2> 524817 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.ConnectionManager C= lient is connected to ZooKeeper [junit4] 2> 524817 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.SolrZkClient Using = default ZkACLProvider [junit4] 2> 524817 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.SolrZkClient makePa= th: /collections/collection1 [junit4] 2> 524819 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.SolrZkClient makePa= th: /collections/collection1/shards [junit4] 2> 524821 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.SolrZkClient makePa= th: /collections/control_collection [junit4] 2> 524822 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.SolrZkClient makePa= th: /collections/control_collection/shards [junit4] 2> 524824 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.AbstractZkTestCase pu= t /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/so= lr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 524824 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.SolrZkClient makePa= th: /configs/conf1/solrconfig.xml [junit4] 2> 524827 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.AbstractZkTestCase pu= t /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/so= lr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 524827 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.SolrZkClient makePa= th: /configs/conf1/schema.xml [junit4] 2> 524829 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.AbstractZkTestCase pu= t /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/so= lr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/co= nf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 524829 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.SolrZkClient makePa= th: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 524831 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.AbstractZkTestCase pu= t /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/so= lr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 524831 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.SolrZkClient makePa= th: /configs/conf1/stopwords.txt [junit4] 2> 524833 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.AbstractZkTestCase pu= t /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/so= lr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 524833 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.SolrZkClient makePa= th: /configs/conf1/protwords.txt [junit4] 2> 524835 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.AbstractZkTestCase pu= t /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/so= lr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 524836 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.SolrZkClient makePa= th: /configs/conf1/currency.xml [junit4] 2> 524837 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.AbstractZkTestCase pu= t /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/so= lr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 524837 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.SolrZkClient makePa= th: /configs/conf1/enumsConfig.xml [junit4] 2> 524839 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.AbstractZkTestCase pu= t /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/so= lr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchang= e-rates.json [junit4] 2> 524839 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.SolrZkClient makePa= th: /configs/conf1/open-exchange-rates.json [junit4] 2> 524841 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.AbstractZkTestCase pu= t /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/so= lr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-I= SOLatin1Accent.txt [junit4] 2> 524841 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.SolrZkClient makePa= th: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 524842 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.AbstractZkTestCase pu= t /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/so= lr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 524843 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.SolrZkClient makePa= th: /configs/conf1/old_synonyms.txt [junit4] 2> 524844 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.AbstractZkTestCase pu= t /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/so= lr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 524845 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.SolrZkClient makePa= th: /configs/conf1/synonyms.txt [junit4] 2> 524951 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.SolrTestCaseJ4 Writing = core.properties file to /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/= build/solr-core/test/J0/temp/solr.cloud.CollectionTooManyReplicasTest_EDF3C= F085726CE2A-001/control-001/cores/collection1 [junit4] 2> 524953 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.e.j.s.Server jetty-9.3.6.v2= 0151106 [junit4] 2> 524954 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.e.j.s.h.ContextHandler Star= ted o.e.j.s.ServletContextHandler@1730d20{/,null,AVAILABLE} [junit4] 2> 524955 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.e.j.s.ServerConnector Start= ed ServerConnector@38053e{HTTP/1.1,[http/1.1]}{127.0.0.1:46397} [junit4] 2> 524955 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.e.j.s.Server Started @52617= 7ms [junit4] 2> 524956 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.s.e.JettySolrRunner J= etty properties: {solr.data.dir=3D/home/jenkins/workspace/Lucene-Solr-6.x-L= inux/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionTooManyReplicas= Test_EDF3CF085726CE2A-001/tempDir-001/control/data, hostContext=3D/, hostPo= rt=3D46397, coreRootDirectory=3D/home/jenkins/workspace/Lucene-Solr-6.x-Lin= ux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/wor= kspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.C= ollectionTooManyReplicasTest_EDF3CF085726CE2A-001/control-001/cores} [junit4] 2> 524956 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.s.SolrDispatchFilter So= lrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1d16e93 [junit4] 2> 524956 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.SolrResourceLoader ne= w SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-6.= x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionTooManyRepli= casTest_EDF3CF085726CE2A-001/control-001' [junit4] 2> 524956 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.SolrResourceLoader JN= DI not configured for solr (NoInitialContextEx) [junit4] 2> 524956 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.SolrResourceLoader so= lr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 524957 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.SolrZkClient Using = default ZkCredentialsProvider [junit4] 2> 524957 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.ConnectionManager W= aiting for client to connect to ZooKeeper [junit4] 2> 524958 INFO (zkCallback-672-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@13f0= 45b name:ZooKeeperConnection Watcher:127.0.0.1:52046/solr got event Watched= Event state:SyncConnected type:None path:null path:null type:None [junit4] 2> 524958 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.ConnectionManager C= lient is connected to ZooKeeper [junit4] 2> 524959 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.SolrZkClient Using = default ZkACLProvider [junit4] 2> 524959 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.s.SolrDispatchFilter Lo= ading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 524960 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.SolrXmlConfig Loading= container configuration from /home/jenkins/workspace/Lucene-Solr-6.x-Linux= /solr/build/solr-core/test/J0/temp/solr.cloud.CollectionTooManyReplicasTest= _EDF3CF085726CE2A-001/control-001/solr.xml [junit4] 2> 524968 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.CorePropertiesLocator= Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-6.= x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkin= s/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cl= oud.CollectionTooManyReplicasTest_EDF3CF085726CE2A-001/control-001/cores [junit4] 2> 524968 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.CoreContainer New Cor= eContainer 30744468 [junit4] 2> 524968 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.CoreContainer Loading= cores into CoreContainer [instanceDir=3D/home/jenkins/workspace/Lucene-Sol= r-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionTooManyR= eplicasTest_EDF3CF085726CE2A-001/control-001] [junit4] 2> 524968 WARN (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.CoreContainer Couldn'= t add files from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/s= olr-core/test/J0/temp/solr.cloud.CollectionTooManyReplicasTest_EDF3CF085726= CE2A-001/control-001/lib to classpath: /home/jenkins/workspace/Lucene-Solr-= 6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionTooManyRep= licasTest_EDF3CF085726CE2A-001/control-001/lib [junit4] 2> 524969 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.h.c.HttpShardHandlerFac= tory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,ma= xConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoo= lSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : = false,useRetries : false, [junit4] 2> 524970 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.u.UpdateShardHandler Cr= eating UpdateShardHandler HTTP client with params: socketTimeout=3D340000&c= onnTimeout=3D45000&retry=3Dtrue [junit4] 2> 524971 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.l.LogWatcher SLF4J impl= is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 524971 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.l.LogWatcher Registerin= g Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 524971 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.ZkContainer Zookeeper= client=3D127.0.0.1:52046/solr [junit4] 2> 524971 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.ZkController zkHost i= ncludes chroot [junit4] 2> 524971 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.SolrZkClient Using = default ZkCredentialsProvider [junit4] 2> 524972 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.ConnectionManager W= aiting for client to connect to ZooKeeper [junit4] 2> 524973 INFO (zkCallback-675-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@b30f= 39 name:ZooKeeperConnection Watcher:127.0.0.1:52046 got event WatchedEvent = state:SyncConnected type:None path:null path:null type:None [junit4] 2> 524973 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.ConnectionManager C= lient is connected to ZooKeeper [junit4] 2> 524974 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.SolrZkClient Using = default ZkACLProvider [junit4] 2> 524975 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.c.c.C= onnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 524976 INFO (zkCallback-676-thread-1-processing-n:127.0.0= .1:46397_) [n:127.0.0.1:46397_ ] o.a.s.c.c.ConnectionManager Watcher org= .apache.solr.common.cloud.ConnectionManager@1388d0b name:ZooKeeperConnectio= n Watcher:127.0.0.1:52046/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4] 2> 524976 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.c.c.C= onnectionManager Client is connected to ZooKeeper [junit4] 2> 524978 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.c.c.S= olrZkClient makePath: /overseer/queue [junit4] 2> 524980 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.c.c.S= olrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 524983 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.c.c.S= olrZkClient makePath: /overseer/collection-map-running [junit4] 2> 524985 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.c.c.S= olrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 524987 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.c.c.S= olrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 524989 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.c.c.S= olrZkClient makePath: /live_nodes [junit4] 2> 524991 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.c.c.S= olrZkClient makePath: /aliases.json [junit4] 2> 524992 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.c.c.S= olrZkClient makePath: /clusterstate.json [junit4] 2> 524993 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.c.c.S= olrZkClient makePath: /security.json [junit4] 2> 524994 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.c.c.Z= kStateReader Updating cluster state from ZooKeeper...=20 [junit4] 2> 524995 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.c.c.Z= kStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 524997 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.c.c.S= olrZkClient makePath: /overseer_elect [junit4] 2> 524998 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.c.c.S= olrZkClient makePath: /overseer_elect/election [junit4] 2> 524999 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.c.Ove= rseer Overseer (id=3Dnull) closing [junit4] 2> 525000 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.c.Lea= derElector Joined leadership election with path: /overseer_elect/election/9= 5492479487967236-127.0.0.1:46397_-n_0000000000 [junit4] 2> 525000 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.c.Ove= rseerElectionContext I am going to be the leader 127.0.0.1:46397_ [junit4] 2> 525000 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.c.c.S= olrZkClient makePath: /overseer_elect/leader [junit4] 2> 525001 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.c.Ove= rseer Overseer (id=3D95492479487967236-127.0.0.1:46397_-n_0000000000) start= ing [junit4] 2> 525003 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.c.c.S= olrZkClient makePath: /overseer/queue-work [junit4] 2> 525009 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.c.Ove= rseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread a= utoReplicaFailoverWorkLoopDelay=3D10000 autoReplicaFailoverWaitAfterExpirat= ion=3D30000 autoReplicaFailoverBadNodeExpiration=3D60000 [junit4] 2> 525009 INFO (OverseerCollectionConfigSetProcessor-9549247= 9487967236-127.0.0.1:46397_-n_0000000000) [n:127.0.0.1:46397_ ] o.a.s.c.= OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 525010 INFO (OverseerStateUpdate-95492479487967236-127.0.= 0.1:46397_-n_0000000000) [n:127.0.0.1:46397_ ] o.a.s.c.Overseer Starting= to work on the main queue [junit4] 2> 525010 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.c.ZkC= ontroller Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46397_ [junit4] 2> 525011 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.c.c.S= olrZkClient makePath: /live_nodes/127.0.0.1:46397_ [junit4] 2> 525012 INFO (OverseerStateUpdate-95492479487967236-127.0.= 0.1:46397_-n_0000000000) [n:127.0.0.1:46397_ ] o.a.s.c.c.ZkStateReader U= pdated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 525013 INFO (zkCallback-676-thread-1-processing-n:127.0.0= .1:46397_) [n:127.0.0.1:46397_ ] 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: [0]) [junit4] 2> 525013 INFO (zkCallback-676-thread-1-processing-n:127.0.0= .1:46397_) [n:127.0.0.1:46397_ ] o.a.s.c.c.ZkStateReader Updated live no= des from ZooKeeper... (0) -> (1) [junit4] 2> 525077 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.c.Cor= eContainer Security conf doesn't exist. Skipping setup for authorization mo= dule. [junit4] 2> 525077 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.c.Cor= eContainer No authentication plugin used. [junit4] 2> 525078 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.c.Cor= ePropertiesLocator Looking for core definitions underneath /home/jenkins/wo= rkspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/../../../../../.= ./../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-cor= e/test/J0/temp/solr.cloud.CollectionTooManyReplicasTest_EDF3CF085726CE2A-00= 1/control-001/cores [junit4] 2> 525079 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.c.Cor= eDescriptor Created CoreDescriptor: {name=3Dcollection1, config=3Dsolrconfi= g.xml, transient=3Dfalse, schema=3Dschema.xml, loadOnStartup=3Dtrue, config= SetProperties=3Dconfigsetprops.json, collection=3Dcontrol_collection, coreN= odeName=3D, dataDir=3Ddata/, shard=3D} [junit4] 2> 525079 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.c.Cor= ePropertiesLocator Found core collection1 in /home/jenkins/workspace/Lucene= -Solr-6.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../hom= e/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp= /solr.cloud.CollectionTooManyReplicasTest_EDF3CF085726CE2A-001/control-001/= cores/collection1 [junit4] 2> 525079 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.c.Cor= ePropertiesLocator Found 1 core definitions [junit4] 2> 525080 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.s.Sol= rDispatchFilter user.dir=3D/home/jenkins/workspace/Lucene-Solr-6.x-Linux/so= lr/build/solr-core/test/J0 [junit4] 2> 525080 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:46397_ ] o.a.s.s.Sol= rDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 525080 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection x:collection1]= o.a.s.c.ZkController publishing state=3Ddown [junit4] 2> 525080 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection x:collection1]= o.a.s.c.ZkController numShards not found on descriptor - reading it from s= ystem property [junit4] 2> 525082 INFO (OverseerStateUpdate-95492479487967236-127.0.= 0.1:46397_-n_0000000000) [n:127.0.0.1:46397_ ] 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:46397", [junit4] 2> "node_name":"127.0.0.1:46397_", [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> 525082 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection x:collection1]= o.a.s.c.ZkController look for our core node name [junit4] 2> 525082 INFO (OverseerStateUpdate-95492479487967236-127.0.= 0.1:46397_-n_0000000000) [n:127.0.0.1:46397_ ] 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:46397", [junit4] 2> "node_name":"127.0.0.1:46397_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 525083 INFO (OverseerStateUpdate-95492479487967236-127.0.= 0.1:46397_-n_0000000000) [n:127.0.0.1:46397_ ] o.a.s.c.o.ClusterStateMut= ator building a new cName: control_collection [junit4] 2> 525083 INFO (OverseerStateUpdate-95492479487967236-127.0.= 0.1:46397_-n_0000000000) [n:127.0.0.1:46397_ ] o.a.s.c.o.ReplicaMutator = Assigning new node to shard shard=3Dshard1 [junit4] 2> 525084 INFO (zkCallback-676-thread-1-processing-n:127.0.0= .1:46397_) [n:127.0.0.1:46397_ ] 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> 526083 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection x:collection1]= o.a.s.c.ZkController waiting to find shard id in clusterstate for collecti= on1 [junit4] 2> 526083 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection x:collection1]= o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 526083 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection x:collection1]= o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 526083 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection x:collection1]= o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control= _collection] [junit4] 2> 526084 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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> 526084 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection x:collection1]= o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/je= nkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/sol= r.cloud.CollectionTooManyReplicasTest_EDF3CF085726CE2A-001/control-001/core= s/collection1' [junit4] 2> 526084 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection x:collection1]= o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextE= x) [junit4] 2> 526084 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection x:collection1]= o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find = system property or JNDI) [junit4] 2> 526086 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection x:collection1]= o.a.s.c.Config loaded config solrconfig.xml with version 0=20 [junit4] 2> 526091 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection x:collection1]= o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 526105 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection x:collection1]= o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.1.0 [junit4] 2> 526116 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection x:collection1]= o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 526126 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection x:collection1]= o.a.s.s.IndexSchema [collection1] Schema name=3Dtest [junit4] 2> 526264 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection x:collection1]= o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=3Dopen-exchang= e-rates.json, refreshInterval=3D1440. [junit4] 2> 526274 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection x:collection1]= o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 526276 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection x:collection1]= o.a.s.s.IndexSchema unique key field: id [junit4] 2> 526291 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection x:collection1]= o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file curren= cy.xml [junit4] 2> 526294 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection x:collection1]= o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file curren= cy.xml [junit4] 2> 526298 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection x:collection1]= o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-ex= change-rates.json [junit4] 2> 526299 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection x:collection1]= o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-ex= change-rates.json [junit4] 2> 526301 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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-6.x-Linux/solr= /build/solr-core/test/J0 [junit4] 2> 526302 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection x:collection1]= o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration = from collection control_collection [junit4] 2> 526302 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFac= tory [junit4] 2> 526302 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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-6.x-Linux/solr/build/solr-core/tes= t/J0/temp/solr.cloud.CollectionTooManyReplicasTest_EDF3CF085726CE2A-001/con= trol-001/cores/collection1], dataDir=3D[/home/jenkins/workspace/Lucene-Solr= -6.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jen= kins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr= .cloud.CollectionTooManyReplicasTest_EDF3CF085726CE2A-001/control-001/cores= /collection1/data/] [junit4] 2> 526302 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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@38e7a= e [junit4] 2> 526304 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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-6.x-Linux/solr/build/solr-core/test/= J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/= solr/build/solr-core/test/J0/temp/solr.cloud.CollectionTooManyReplicasTest_= EDF3CF085726CE2A-001/control-001/cores/collection1/data [junit4] 2> 526304 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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-6.x-Linux/solr/build/solr-cor= e/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-6.x= -Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionTooManyReplic= asTest_EDF3CF085726CE2A-001/control-001/cores/collection1/data/index/ [junit4] 2> 526304 WARN (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/= home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/.= ./../../../../../../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr= /build/solr-core/test/J0/temp/solr.cloud.CollectionTooManyReplicasTest_EDF3= CF085726CE2A-001/control-001/cores/collection1/data/index' doesn't exist. C= reating new index... [junit4] 2> 526304 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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-6.x-Linux/solr/build/solr-core/test/= J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/= solr/build/solr-core/test/J0/temp/solr.cloud.CollectionTooManyReplicasTest_= EDF3CF085726CE2A-001/control-001/cores/collection1/data/index [junit4] 2> 526305 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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=3D27, maxMergeAtOnceExplicit=3D19, maxMergedSegmentMB=3D99.45507812= 5, floorSegmentMB=3D0.3408203125, forceMergeDeletesPctAllowed=3D26.63727002= 970598, segmentsPerTier=3D27.0, maxCFSSegmentSizeMB=3D8.796093022207999E12,= noCFSRatio=3D0.0 [junit4] 2> 526306 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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@138d1 lo= ckFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@8a1a27),segFN= =3Dsegments_1,generation=3D1} [junit4] 2> 526306 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = =3D 1 [junit4] 2> 526306 INFO (OldIndexDirectoryCleanupThreadForCore-collec= tion1) [n:127.0.0.1:46397_ 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-6.x-Linux/solr/bui= ld/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Luce= ne-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionTo= oManyReplicasTest_EDF3CF085726CE2A-001/control-001/cores/collection1/data/ [junit4] 2> 526306 WARN (OldIndexDirectoryCleanupThreadForCore-collec= tion1) [n:127.0.0.1:46397_ c:control_collection s:shard1 r:core_node1 x:col= lection1] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr-6.x-= Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/= workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.clou= d.CollectionTooManyReplicasTest_EDF3CF085726CE2A-001/control-001/cores/coll= ection1/data/ does not point to a valid data directory; skipping clean-up o= f old index directories. [junit4] 2> 526312 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateR= equestProcessorChain "nodistrib" [junit4] 2> 526312 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateR= equestProcessorChain "dedupe" [junit4] 2> 526313 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting Distri= butedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 526313 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateR= equestProcessorChain "stored_sig" [junit4] 2> 526313 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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> 526313 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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> 526313 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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> 526313 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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> 526314 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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> 526314 WARN (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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> 526316 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 526318 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 526320 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 526322 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 526331 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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/ping,/admin/t= hreads,/update,/admin/file [junit4] 2> 526332 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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> 526333 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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> 526333 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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> 526341 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 526341 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 526342 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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=3D17, maxMergeAtOnceExplicit=3D35, maxMergedSegmentMB=3D80.78515625= , floorSegmentMB=3D1.9951171875, forceMergeDeletesPctAllowed=3D26.300552044= 842878, segmentsPerTier=3D33.0, maxCFSSegmentSizeMB=3D8.796093022207999E12,= noCFSRatio=3D1.0 [junit4] 2> 526342 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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@138d1 lo= ckFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@8a1a27),segFN= =3Dsegments_1,generation=3D1} [junit4] 2> 526342 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = =3D 1 [junit4] 2> 526343 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@a60adb[col= lection1] main] [junit4] 2> 526343 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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> 526344 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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> 526344 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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> 526345 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperSto= rageIO with znodeBase: /configs/conf1 [junit4] 2> 526345 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with init= Args: {} [junit4] 2> 526345 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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> 526345 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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> 526346 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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> 526346 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedR= esources [junit4] 2> 526346 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved fo= r 10000 [junit4] 2> 526348 INFO (searcherExecutor-2472-thread-1-processing-n:= 127.0.0.1:46397_ x:collection1 s:shard1 c:control_collection r:core_node1) = [n:127.0.0.1:46397_ c:control_collection s:shard1 r:core_node1 x:collection= 1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@a60adb[c= ollection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 526348 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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> 526348 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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> 526348 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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> 526348 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ 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 1527879673523994624 [junit4] 2> 526350 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets w= ith highest version 1527879673523994624 [junit4] 2> 526350 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 526351 INFO (coreLoadExecutor-2471-thread-1-processing-n:= 127.0.0.1:46397_) [n:127.0.0.1:46397_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 526352 INFO (coreZkRegister-2465-thread-1-processing-n:12= 7.0.0.1:46397_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:46397_ 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:46397 collection:control_collection shard:shard1 [junit4] 2> 526352 INFO (coreZkRegister-2465-thread-1-processing-n:12= 7.0.0.1:46397_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:46397_ 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> 526356 INFO (coreZkRegister-2465-thread-1-processing-n:12= 7.0.0.1:46397_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:46397_ 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/95492479487967236-core_node1= -n_0000000000 [junit4] 2> 526356 INFO (coreZkRegister-2465-thread-1-processing-n:12= 7.0.0.1:46397_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:46397_ 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> 526358 INFO (coreZkRegister-2465-thread-1-processing-n:12= 7.0.0.1:46397_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:46397_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 526358 INFO (coreZkRegister-2465-thread-1-processing-n:12= 7.0.0.1:46397_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:46397_ 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> 526358 INFO (coreZkRegister-2465-thread-1-processing-n:12= 7.0.0.1:46397_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:46397_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:46397/collection1/ [junit4] 2> 526358 INFO (coreZkRegister-2465-thread-1-processing-n:12= 7.0.0.1:46397_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:46397_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 526358 INFO (coreZkRegister-2465-thread-1-processing-n:12= 7.0.0.1:46397_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:46397_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.SyncStrategy http://127.0.0.1:46397/collection1/ has no replicas [junit4] 2> 526358 INFO (OverseerStateUpdate-95492479487967236-127.0.= 0.1:46397_-n_0000000000) [n:127.0.0.1:46397_ ] 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> 526359 INFO (coreZkRegister-2465-thread-1-processing-n:12= 7.0.0.1:46397_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:46397_ 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> 526362 INFO (coreZkRegister-2465-thread-1-processing-n:12= 7.0.0.1:46397_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:46397_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /= collections/control_collection/leaders/shard1/leader after winning as /coll= ections/control_collection/leader_elect/shard1/election/95492479487967236-c= ore_node1-n_0000000000 [junit4] 2> 526365 INFO (coreZkRegister-2465-thread-1-processing-n:12= 7.0.0.1:46397_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:46397_ 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:4= 6397/collection1/ shard1 [junit4] 2> 526366 INFO (OverseerStateUpdate-95492479487967236-127.0.= 0.1:46397_-n_0000000000) [n:127.0.0.1:46397_ ] 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:46397", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 526468 INFO (zkCallback-676-thread-1-processing-n:127.0.0= .1:46397_) [n:127.0.0.1:46397_ ] 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> 526516 INFO (coreZkRegister-2465-thread-1-processing-n:12= 7.0.0.1:46397_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:46397_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.ZkController We are http://127.0.0.1:46397/collection1/ and leader= is http://127.0.0.1:46397/collection1/ [junit4] 2> 526516 INFO (coreZkRegister-2465-thread-1-processing-n:12= 7.0.0.1:46397_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:46397_ 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:46397 [junit4] 2> 526516 INFO (coreZkRegister-2465-thread-1-processing-n:12= 7.0.0.1:46397_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:46397_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 526516 INFO (coreZkRegister-2465-thread-1-processing-n:12= 7.0.0.1:46397_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:46397_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.ZkController publishing state=3Dactive [junit4] 2> 526516 INFO (coreZkRegister-2465-thread-1-processing-n:12= 7.0.0.1:46397_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:46397_ 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> 526518 INFO (OverseerStateUpdate-95492479487967236-127.0.= 0.1:46397_-n_0000000000) [n:127.0.0.1:46397_ ] 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:46397", [junit4] 2> "node_name":"127.0.0.1:46397_", [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> 526519 INFO (coreZkRegister-2465-thread-1-processing-n:12= 7.0.0.1:46397_ x:collection1 s:shard1 c:control_collection r:core_node1) [n= :127.0.0.1:46397_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (1) [junit4] 2> 526519 INFO (OverseerStateUpdate-95492479487967236-127.0.= 0.1:46397_-n_0000000000) [n:127.0.0.1:46397_ ] 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:46397", [junit4] 2> "node_name":"127.0.0.1:46397_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 526581 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.SolrZkClient Using = default ZkCredentialsProvider [junit4] 2> 526582 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.ConnectionManager W= aiting for client to connect to ZooKeeper [junit4] 2> 526584 INFO (zkCallback-678-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6c17= c name:ZooKeeperConnection Watcher:127.0.0.1:52046/solr got event WatchedEv= ent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 526584 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.ConnectionManager C= lient is connected to ZooKeeper [junit4] 2> 526584 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.SolrZkClient Using = default ZkACLProvider [junit4] 2> 526584 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.ZkStateReader Updat= ing cluster state from ZooKeeper...=20 [junit4] 2> 526585 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 526586 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.ChaosMonkey monkey: i= nit - expire sessions:false cause connection loss:false [junit4] 2> 526622 INFO (zkCallback-676-thread-1-processing-n:127.0.0= .1:46397_) [n:127.0.0.1:46397_ ] 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> 526622 INFO (zkCallback-678-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: [1]) [junit4] 2> 526660 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.SolrTestCaseJ4 Writing = core.properties file to /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/= build/solr-core/test/J0/temp/solr.cloud.CollectionTooManyReplicasTest_EDF3C= F085726CE2A-001/shard-1-001/cores/collection1 [junit4] 2> 526660 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.AbstractFullDistribZk= TestBase create jetty 1 in directory /home/jenkins/workspace/Lucene-Solr-6.= x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionTooManyRepli= casTest_EDF3CF085726CE2A-001/shard-1-001 [junit4] 2> 526661 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.e.j.s.Server jetty-9.3.6.v2= 0151106 [junit4] 2> 526662 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.e.j.s.h.ContextHandler Star= ted o.e.j.s.ServletContextHandler@a8b0f6{/,null,AVAILABLE} [junit4] 2> 526663 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.e.j.s.ServerConnector Start= ed ServerConnector@188a595{HTTP/1.1,[http/1.1]}{127.0.0.1:45533} [junit4] 2> 526663 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.e.j.s.Server Started @52788= 5ms [junit4] 2> 526663 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.s.e.JettySolrRunner J= etty properties: {solr.data.dir=3D/home/jenkins/workspace/Lucene-Solr-6.x-L= inux/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionTooManyReplicas= Test_EDF3CF085726CE2A-001/tempDir-001/jetty1, solrconfig=3Dsolrconfig.xml, = hostContext=3D/, hostPort=3D45533, coreRootDirectory=3D/home/jenkins/worksp= ace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/../../../../../../..= /../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/te= st/J0/temp/solr.cloud.CollectionTooManyReplicasTest_EDF3CF085726CE2A-001/sh= ard-1-001/cores} [junit4] 2> 526664 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.s.SolrDispatchFilter So= lrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1d16e93 [junit4] 2> 526664 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.SolrResourceLoader ne= w SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-6.= x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionTooManyRepli= casTest_EDF3CF085726CE2A-001/shard-1-001' [junit4] 2> 526664 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.SolrResourceLoader JN= DI not configured for solr (NoInitialContextEx) [junit4] 2> 526664 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.SolrResourceLoader so= lr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 526664 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.SolrZkClient Using = default ZkCredentialsProvider [junit4] 2> 526665 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.ConnectionManager W= aiting for client to connect to ZooKeeper [junit4] 2> 526666 INFO (zkCallback-679-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1e2f= 893 name:ZooKeeperConnection Watcher:127.0.0.1:52046/solr got event Watched= Event state:SyncConnected type:None path:null path:null type:None [junit4] 2> 526666 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.ConnectionManager C= lient is connected to ZooKeeper [junit4] 2> 526668 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.SolrZkClient Using = default ZkACLProvider [junit4] 2> 526669 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.s.SolrDispatchFilter Lo= ading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 526669 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.SolrXmlConfig Loading= container configuration from /home/jenkins/workspace/Lucene-Solr-6.x-Linux= /solr/build/solr-core/test/J0/temp/solr.cloud.CollectionTooManyReplicasTest= _EDF3CF085726CE2A-001/shard-1-001/solr.xml [junit4] 2> 526675 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.CorePropertiesLocator= Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-6.= x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkin= s/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cl= oud.CollectionTooManyReplicasTest_EDF3CF085726CE2A-001/shard-1-001/cores [junit4] 2> 526675 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.CoreContainer New Cor= eContainer 28270137 [junit4] 2> 526675 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.CoreContainer Loading= cores into CoreContainer [instanceDir=3D/home/jenkins/workspace/Lucene-Sol= r-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionTooManyR= eplicasTest_EDF3CF085726CE2A-001/shard-1-001] [junit4] 2> 526675 WARN (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.CoreContainer Couldn'= t add files from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/s= olr-core/test/J0/temp/solr.cloud.CollectionTooManyReplicasTest_EDF3CF085726= CE2A-001/shard-1-001/lib to classpath: /home/jenkins/workspace/Lucene-Solr-= 6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionTooManyRep= licasTest_EDF3CF085726CE2A-001/shard-1-001/lib [junit4] 2> 526675 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.h.c.HttpShardHandlerFac= tory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,ma= xConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoo= lSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : = false,useRetries : false, [junit4] 2> 526676 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.u.UpdateShardHandler Cr= eating UpdateShardHandler HTTP client with params: socketTimeout=3D340000&c= onnTimeout=3D45000&retry=3Dtrue [junit4] 2> 526677 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.l.LogWatcher SLF4J impl= is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 526677 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.l.LogWatcher Registerin= g Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 526677 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.ZkContainer Zookeeper= client=3D127.0.0.1:52046/solr [junit4] 2> 526677 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.ZkController zkHost i= ncludes chroot [junit4] 2> 526677 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.SolrZkClient Using = default ZkCredentialsProvider [junit4] 2> 526678 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.ConnectionManager W= aiting for client to connect to ZooKeeper [junit4] 2> 526679 INFO (zkCallback-682-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1ff4= e3a name:ZooKeeperConnection Watcher:127.0.0.1:52046 got event WatchedEvent= state:SyncConnected type:None path:null path:null type:None [junit4] 2> 526679 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.ConnectionManager C= lient is connected to ZooKeeper [junit4] 2> 526680 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.c.SolrZkClient Using = default ZkACLProvider [junit4] 2> 526681 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:45533_ ] o.a.s.c.c.C= onnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 526682 INFO (zkCallback-683-thread-1-processing-n:127.0.0= .1:45533_) [n:127.0.0.1:45533_ ] o.a.s.c.c.ConnectionManager Watcher org= .apache.solr.common.cloud.ConnectionManager@b13217 name:ZooKeeperConnection= Watcher:127.0.0.1:52046/solr got event WatchedEvent state:SyncConnected ty= pe:None path:null path:null type:None [junit4] 2> 526682 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:45533_ ] o.a.s.c.c.C= onnectionManager Client is connected to ZooKeeper [junit4] 2> 526686 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:45533_ ] o.a.s.c.c.Z= kStateReader Updating cluster state from ZooKeeper...=20 [junit4] 2> 526687 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:45533_ ] o.a.s.c.c.Z= kStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 526689 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:45533_ ] o.a.s.c.Ove= rseer Overseer (id=3Dnull) closing [junit4] 2> 526689 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:45533_ ] o.a.s.c.Lea= derElector Joined leadership election with path: /overseer_elect/election/9= 5492479487967240-127.0.0.1:45533_-n_0000000001 [junit4] 2> 526690 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:45533_ ] o.a.s.c.Lea= derElector Watching path /overseer_elect/election/95492479487967236-127.0.0= .1:46397_-n_0000000000 to know if I could be the leader [junit4] 2> 526690 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:45533_ ] o.a.s.c.ZkC= ontroller Publish node=3D127.0.0.1:45533_ as DOWN [junit4] 2> 526692 INFO (OverseerStateUpdate-95492479487967236-127.0.= 0.1:46397_-n_0000000000) [n:127.0.0.1:46397_ ] o.a.s.c.Overseer processM= essage: queueSize: 1, message =3D { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:45533_"} current state version: 3 [junit4] 2> 526692 INFO (OverseerStateUpdate-95492479487967236-127.0.= 0.1:46397_-n_0000000000) [n:127.0.0.1:46397_ ] o.a.s.c.o.NodeMutator Dow= nNode state invoked for node: 127.0.0.1:45533_ [junit4] 2> 526795 INFO (zkCallback-683-thread-1-processing-n:127.0.0= .1:45533_) [n:127.0.0.1:45533_ ] 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> 526795 INFO (zkCallback-678-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: [1]) [junit4] 2> 526795 INFO (zkCallback-676-thread-1-processing-n:127.0.0= .1:46397_) [n:127.0.0.1:46397_ ] 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> 527692 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:45533_ ] o.a.s.c.ZkC= ontroller Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45533_ [junit4] 2> 527693 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:45533_ ] o.a.s.c.c.S= olrZkClient makePath: /live_nodes/127.0.0.1:45533_ [junit4] 2> 527694 INFO (zkCallback-683-thread-1-processing-n:127.0.0= .1:45533_) [n:127.0.0.1:45533_ ] 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> 527694 INFO (zkCallback-676-thread-1-processing-n:127.0.0= .1:46397_) [n:127.0.0.1:46397_ ] 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> 527694 INFO (zkCallback-678-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> 527694 INFO (zkCallback-676-thread-1-processing-n:127.0.0= .1:46397_) [n:127.0.0.1:46397_ ] o.a.s.c.c.ZkStateReader Updated live no= des from ZooKeeper... (1) -> (2) [junit4] 2> 527694 INFO (zkCallback-678-thread-1) [ ] o.a.s.c.c.Zk= StateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 527695 INFO (zkCallback-683-thread-1-processing-n:127.0.0= .1:45533_) [n:127.0.0.1:45533_ ] o.a.s.c.c.ZkStateReader Updated live no= des from ZooKeeper... (1) -> (2) [junit4] 2> 527729 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:45533_ ] o.a.s.c.Cor= eContainer Security conf doesn't exist. Skipping setup for authorization mo= dule. [junit4] 2> 527730 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:45533_ ] o.a.s.c.Cor= eContainer No authentication plugin used. [junit4] 2> 527730 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:45533_ ] o.a.s.c.Cor= ePropertiesLocator Looking for core definitions underneath /home/jenkins/wo= rkspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/../../../../../.= ./../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-cor= e/test/J0/temp/solr.cloud.CollectionTooManyReplicasTest_EDF3CF085726CE2A-00= 1/shard-1-001/cores [junit4] 2> 527731 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:45533_ ] o.a.s.c.Cor= eDescriptor Created CoreDescriptor: {name=3Dcollection1, config=3Dsolrconfi= g.xml, transient=3Dfalse, schema=3Dschema.xml, loadOnStartup=3Dtrue, config= SetProperties=3Dconfigsetprops.json, collection=3Dcollection1, coreNodeName= =3D, dataDir=3Ddata/, shard=3D} [junit4] 2> 527731 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:45533_ ] o.a.s.c.Cor= ePropertiesLocator Found core collection1 in /home/jenkins/workspace/Lucene= -Solr-6.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../hom= e/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp= /solr.cloud.CollectionTooManyReplicasTest_EDF3CF085726CE2A-001/shard-1-001/= cores/collection1 [junit4] 2> 527731 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:45533_ ] o.a.s.c.Cor= ePropertiesLocator Found 1 core definitions [junit4] 2> 527732 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:45533_ ] o.a.s.s.Sol= rDispatchFilter user.dir=3D/home/jenkins/workspace/Lucene-Solr-6.x-Linux/so= lr/build/solr-core/test/J0 [junit4] 2> 527732 INFO (TEST-CollectionTooManyReplicasTest.testAddTo= oManyReplicas-seed#[EDF3CF085726CE2A]) [n:127.0.0.1:45533_ ] o.a.s.s.Sol= rDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 527732 INFO (coreLoadExecutor-2482-thread-1-processing-n:= 127.0.0.1:45533_) [n:127.0.0.1:45533_ c:collection1 x:collection1] o.a.s.= c.ZkController publishing state=3Ddown [junit4] 2> 527732 INFO (coreLoadExecutor-2482-thread-1-processing-n:= 127.0.0.1:45533_) [n:127.0.0.1:45533_ c:collection1 x:collection1] o.a.s.= c.ZkController numShards not found on descriptor - reading it from system p= roperty [junit4] 2> 527734 INFO (coreLoadExecutor-2482-thread-1-processing-n:= 127.0.0.1:45533_) [n:127.0.0.1:45533_ c:collection1 x:collection1] o.a.s.= c.ZkController look for our core node name [junit4] 2> 527734 INFO (OverseerStateUpdate-95492479487967236-127.0.= 0.1:46397_-n_0000000000) [n:127.0.0.1:46397_ ] 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:45533", [junit4] 2> "node_name":"127.0.0.1:45533_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 4 [junit4] 2> 527734 INFO (OverseerStateUpdate-95492479487967236-127.0.= 0.1:46397_-n_0000000000) [n:127.0.0.1:46397_ ] 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:45533", [junit4] 2> "node_name":"127.0.0.1:45533_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 527734 INFO (OverseerStateUpdate-95492479487967236-127.0.= 0.1:46397_-n_0000000000) [n:127.0.0.1:46397_ ] o.a.s.c.o.ClusterStateMut= ator building a new cName: collection1 [junit4] 2> 527735 INFO (OverseerStateUpdate-95492479487967236-127.0.= 0.1:46397_-n_0000000000) [n:127.0.0.1:46397_ ] o.a.s.c.o.ReplicaMutator = Assigning new node to shard shard=3Dshard1 [junit4] 2> 527837 INFO (zkCallback-683-thread-1-processing-n:127.0.0= .1:45533_) [n:127.0.0.1:45533_ ] 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> 527837 INFO (zkCallback-676-thread-1-processing-n:127.0.0= .1:46397_) [n:127.0.0.1:46397_ ] 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> 527837 INFO (zkCallback-678-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> 528734 INFO (coreLoadExecutor-2482-thread-1-processing-n:= 127.0.0.1:45533_) [n:127.0.0.1:45533_ c:collection1 x:collection1] o.a.s.= c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 528734 INFO (coreLoadExecutor-2482-thread-1-processing-n:= 127.0.0.1:45533_) [n:127.0.0.1:45533_ c:collection1 x:collection1] o.a.s.= c.ZkController Check for collection zkNode:collection1 [junit4] 2> 528735 INFO (coreLoadExecutor-2482-thread-1-processing-n:= 127.0.0.1:45533_) [n:127.0.0.1:45533_ c:collection1 x:collection1] o.a.s.= c.ZkController Collection zkNode exists [junit4] 2> 528735 INFO (coreLoadExecutor-2482-thread-1-processing-n:= 127.0.0.1:45533_) [n:127.0.0.1:45533_ c:collection1 x:collection1] o.a.s.= c.c.ZkStateReader Load collection config from: [/collections/collection1] [junit4] 2> 528736 INFO (coreLoadExecutor-2482-thread-1-processing-n:= 127.0.0.1:45533_) [n:127.0.0.1:45533_ c:collection1 x:collection1] o.a.s.= c.c.ZkStateReader path=3D[/collections/collection1] [configName]=3D[conf1] = specified config exists in ZooKeeper [junit4] 2> 528736 INFO (coreLoadExecutor-2482-thread-1-processing-n:= 127.0.0.1:45533_) [n:127.0.0.1:45533_ c:collection1 x:collection1] o.a.s.= c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/w= orkspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud= .CollectionTooManyReplicasTest_EDF3CF085726CE2A-001/shard-1-001/cores/colle= ction1' [junit4] 2> 528736 INFO (coreLoadExecutor-2482-thread-1-processing-n:= 127.0.0.1:45533_) [n:127.0.0.1:45533_ c:collection1 x:collection1] o.a.s.= c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 528736 INFO (coreLoadExecutor-2482-thread-1-processing-n:= 127.0.0.1:45533_) [n:127.0.0.1:45533_ c:collection1 x:collection1] o.a.s.= c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system = property or JNDI) [junit4] 2> 528737 INFO (coreLoadExecutor-2482-thread-1-processing-n:= 127.0.0.1:45533_) [n:127.0.0.1:45533_ c:collection1 x:collection1] o.a.s.= c.Config loaded config solrconfig.xml with version 0=20 [junit4] 2> 528742 INFO (coreLoadExecutor-2482-thread-1-processing-n:= 127.0.0.1:45533_) [n:127.0.0.1:45533_ c:collection1 x:collection1] o.a.s.= c.SolrConfig current version of requestparams : -1 [junit4] 2> 528748 INFO (coreLoadExecutor-2482-thread-1-processing-n:= 127.0.0.1:45533_) [n:127.0.0.1:45533_ c:collection1 x:collection1] o.a.s.= c.SolrConfig Using Lucene MatchVersion: 6.1.0 [junit4] 2> 528761 INFO (coreLoadExecutor-2482-thread-1-processing-n:= 127.0.0.1:45533_) [n:127.0.0.1:45533_ c:collection1 x:collection1] o.a.s.= c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 528771 INFO (coreLoadExecutor-2482-thread-1-processing-n:= 127.0.0.1:45533_) [n:127.0.0.1:45533_ c:collection1 x:collection1] o.a.s.= s.IndexSchema [collection1] Schema name=3Dtest [junit4] 2> 528919 INFO (coreLoadExecutor-2482-thread-1-processing-n:= 127.0.0.1:45533_) [n:127.0.0.1:45533_ c:collection1 x:collection1] o.a.s.= s.OpenExchangeRatesOrgProvider Initialized with rates=3Dopen-exchange-rates= .json, refreshInterval=3D1440. [junit4] 2> 528927 INFO (coreLoadExecutor-2482-thread-1-processing-n:= 127.0.0.1:45533_) [n:127.0.0.1:45533_ c:collection1 x:collection1] o.a.s.= s.IndexSchema default search field in schema is text [junit4] 2> 528928 INFO (coreLoadExecutor-2482-thread-1-processing-n:= 127.0.0.1:45533_) [n:127.0.0.1:45533_ c:collection1 x:collection1] o.a.s.= s.IndexSchema unique key field: id [junit4] 2> 528938 INFO (coreLoadExecutor-2482-thread-1-processing-n:= 127.0.0.1:45533_) [n:127.0.0.1:45533_ c:collection1 x:collection1] o.a.s.= s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 528941 INFO (coreLoadExecutor-2482-thread-1-processing-n:= 127.0.0.1:45533_) [n:127.0.0.1:45533_ c:collection1 x:collection1] o.a.s.= s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 528943 INFO (coreLoadExecutor-2482-thread-1-processing-n:= 127.0.0.1:45533_) [n:127.0.0.1:45533_ c:collection1 x:collection1] o.a.s.= s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-= rates.json [junit4] 2> 528944 INFO (coreLoadExecutor-2482-thread-1-processing-n:= 127.0.0.1:45533_) [n:127.0.0.1:45533_ c:collection1 x:collection1] o.a.s.= s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-= rates.json [junit4] 2> 528945 INFO (coreLoadExe [...truncated too long message...] ionTooManyReplicasTest_EDF3CF085726CE2A-001/shard-2-002/cores/collection1/d= ata/index;done=3Dfalse>>] [junit4] 2> 596412 INFO (zkCallback-729-thread-1-processing-n:127.0.0= .1:50137_) [n:127.0.0.1:50137_ c:collection1 s:shard1 r:core_node2 x:collec= tion1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/wor= kspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/../../../../../..= /../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core= /test/J0/temp/solr.cloud.CollectionTooManyReplicasTest_EDF3CF085726CE2A-001= /shard-2-002/cores/collection1/data/index [junit4] 2> 596412 INFO (zkCallback-729-thread-1-processing-n:127.0.0= .1:50137_) [n:127.0.0.1:50137_ c:collection1 s:shard1 r:core_node2 x:collec= tion1] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/works= pace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/../../../../../../.= ./../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/t= est/J0/temp/solr.cloud.CollectionTooManyReplicasTest_EDF3CF085726CE2A-001/s= hard-2-002/cores/collection1/data [CachedDir<>] [junit4] 2> 596412 INFO (zkCallback-729-thread-1-processing-n:127.0.0= .1:50137_) [n:127.0.0.1:50137_ c:collection1 s:shard1 r:core_node2 x:collec= tion1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/wor= kspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/../../../../../..= /../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core= /test/J0/temp/solr.cloud.CollectionTooManyReplicasTest_EDF3CF085726CE2A-001= /shard-2-002/cores/collection1/data [junit4] 2> 596412 INFO (zkCallback-729-thread-1-processing-n:127.0.0= .1:50137_) [n:127.0.0.1:50137_ c:collection1 s:shard1 r:core_node2 x:collec= tion1] o.a.s.c.ElectionContext Canceling election /collections/collection1/= leader_elect/shard1/election/95492481948581899-core_node2-n_0000000001 [junit4] 2> 596413 WARN (zkCallback-729-thread-1-processing-n:127.0.0= .1:50137_) [n:127.0.0.1:50137_ c:collection1 s:shard1 r:core_node2 x:collec= tion1] o.a.s.c.LeaderElector=20 [junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredExcepti= on: KeeperErrorCode =3D Session expired for /collections/collection1/leader= _elect/shard1/election/95492481948581899-core_node2-n_0000000001 [junit4] 2> =09at org.apache.zookeeper.KeeperException.create(KeeperEx= ception.java:127) [junit4] 2> =09at org.apache.zookeeper.KeeperException.create(KeeperEx= ception.java:51) [junit4] 2> =09at org.apache.zookeeper.ZooKeeper.delete(ZooKeeper.java= :873) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$2.execute(= SolrZkClient.java:244) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$2.execute(= SolrZkClient.java:241) [junit4] 2> =09at org.apache.solr.common.cloud.ZkCmdExecutor.retryOper= ation(ZkCmdExecutor.java:60) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient.delete(Sol= rZkClient.java:241) [junit4] 2> =09at org.apache.solr.cloud.ElectionContext.cancelElection= (ElectionContext.java:86) [junit4] 2> =09at org.apache.solr.cloud.ShardLeaderElectionContextBase= .cancelElection(ElectionContext.java:143) [junit4] 2> =09at org.apache.solr.cloud.ShardLeaderElectionContext.can= celElection(ElectionContext.java:279) [junit4] 2> =09at org.apache.solr.cloud.ShardLeaderElectionContext.run= LeaderProcess(ElectionContext.java:455) [junit4] 2> =09at org.apache.solr.cloud.LeaderElector.runIamLeaderProc= ess(LeaderElector.java:173) [junit4] 2> =09at org.apache.solr.cloud.LeaderElector.checkIfIamLeader= (LeaderElector.java:138) [junit4] 2> =09at org.apache.solr.cloud.LeaderElector.access$200(Leade= rElector.java:59) [junit4] 2> =09at org.apache.solr.cloud.LeaderElector$ElectionWatcher.= process(LeaderElector.java:351) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$3$1.run(So= lrZkClient.java:269) [junit4] 2> =09at java.util.concurrent.Executors$RunnableAdapter.call(= Executors.java:511) [junit4] 2> =09at java.util.concurrent.FutureTask.run(FutureTask.java:= 266) [junit4] 2> =09at org.apache.solr.common.util.ExecutorUtil$MDCAwareThr= eadPoolExecutor$1.run(ExecutorUtil.java:231) [junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor.runWorker(Th= readPoolExecutor.java:1142) [junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor$Worker.run(T= hreadPoolExecutor.java:617) [junit4] 2> =09at java.lang.Thread.run(Thread.java:745) [junit4] 2> 596414 INFO (TEST-CollectionTooManyReplicasTest.testAddSh= ard-seed#[EDF3CF085726CE2A]) [ ] o.e.j.s.ServerConnector Stopped ServerC= onnector@91680f{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 596415 INFO (TEST-CollectionTooManyReplicasTest.testAddSh= ard-seed#[EDF3CF085726CE2A]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.= s.ServletContextHandler@1dc6fac{/,null,UNAVAILABLE} [junit4] 2> 596416 INFO (TEST-CollectionTooManyReplicasTest.testAddSh= ard-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.ChaosMonkey monkey: stop shard!= 54355 [junit4] 2> 596416 INFO (TEST-CollectionTooManyReplicasTest.testAddSh= ard-seed#[EDF3CF085726CE2A]) [ ] o.a.s.c.ZkTestServer connecting to 127.= 0.0.1:60440 60440 [junit4] 2> 601458 INFO (Thread-2131) [ ] o.a.s.c.ZkTestServer con= necting to 127.0.0.1:60440 60440 [junit4] 2> 601459 WARN (Thread-2131) [ ] o.a.s.c.ZkTestServer Wat= ch limit violations:=20 [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2>=20 [junit4] 2> =095=09/solr/aliases.json [junit4] 2> =093=09/solr/security.json [junit4] 2> =093=09/solr/configs/conf1 [junit4] 2>=20 [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2>=20 [junit4] 2> =095=09/solr/clusterstate.json [junit4] 2> =093=09/solr/collections/TooManyReplicasWhenAddingShards/s= tate.json [junit4] 2>=20 [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2>=20 [junit4] 2> =095=09/solr/live_nodes [junit4] 2> =095=09/solr/collections [junit4] 2> =093=09/solr/overseer/queue [junit4] 2> =093=09/solr/overseer/collection-queue-work [junit4] 2> =092=09/solr/overseer/queue-work [junit4] 2>=20 [junit4] 2> 601460 INFO (SUITE-CollectionTooManyReplicasTest-seed#[ED= F3CF085726CE2A]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/wo= rkspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.= CollectionTooManyReplicasTest_EDF3CF085726CE2A-001 [junit4] 2> NOTE: test params are: codec=3DAsserting(Lucene60): {}, do= cValues:{}, maxPointsInLeafNode=3D1711, maxMBSortInHeap=3D6.89760161911928,= sim=3DRandomSimilarity(queryNorm=3Dtrue,coord=3Dyes): {}, locale=3Dmk, tim= ezone=3DEurope/Paris [junit4] 2> NOTE: Linux 3.19.0-51-generic i386/Oracle Corporation 1.8.= 0_72 (32-bit)/cpus=3D12,threads=3D1,free=3D77674136,total=3D226852864 [junit4] 2> NOTE: All tests run in this JVM: [FileBasedSpellCheckerTes= t, DistributedSuggestComponentTest, TestSolr4Spatial, TestCustomSort, Distr= ibutedDebugComponentTest, TestDynamicFieldCollectionResource, DistribDocExp= irationUpdateProcessorTest, TestFieldResource, EchoParamsTest, AsyncCallReq= uestStatusResponseTest, BasicZkTest, TestFoldingMultitermQuery, TestRealTim= eGet, RollingRestartTest, TestNamedUpdateProcessors, PrimUtilsTest, Analyti= csQueryTest, DistributedSpellCheckComponentTest, TestReplicationHandler, Te= stAtomicUpdateErrorCases, DefaultValueUpdateProcessorTest, TestExactSharedS= tatsCache, ZkNodePropsTest, DistributedFacetPivotLongTailTest, TestConfig, = DeleteInactiveReplicaTest, TestRemoveLastDynamicCopyField, CdcrReplicationD= istributedZkTest, TestConfigSetsAPIExclusivity, TestSchemaSimilarityResourc= e, TestPseudoReturnFields, CursorPagingTest, StandardRequestHandlerTest, Re= turnFieldsTest, LeaderElectionIntegrationTest, DirectoryFactoryTest, Leader= FailoverAfterPartitionTest, RequiredFieldsTest, TestTrieFacet, OverriddenZk= ACLAndCredentialsProvidersTest, CoreAdminCreateDiscoverTest, QueryElevation= ComponentTest, ZkStateReaderTest, TestManagedResourceStorage, TestTestInjec= tion, TestPhraseSuggestions, BlockJoinFacetSimpleTest, TestObjectReleaseTra= cker, TestHighFrequencyDictionaryFactory, AnalysisAfterCoreReloadTest, Java= binLoaderTest, CloudExitableDirectoryReaderTest, TestBinaryField, TestSolrC= onfigHandler, DataDrivenBlockJoinTest, SolrRequestParserTest, TestSolrQuery= ParserDefaultOperatorResource, CollectionTooManyReplicasTest] [junit4] Completed [176/582 (1!)] on J0 in 76.78s, 3 tests, 1 failure <<= < FAILURES! [...truncated 1291 lines...] BUILD FAILED /home/jenkins/workspace/Lucene-Solr-6.x-Linux/build.xml:740: The following = error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/build.xml:684: The following = error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/build.xml:59: The following e= rror occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build.xml:233: The follo= wing error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/common-build.xml:524: Th= e following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/lucene/common-build.xml:1457:= The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/lucene/common-build.xml:1014:= There were test failures: 582 suites (8 ignored), 2351 tests, 1 failure, 1= 16 ignored (71 assumptions) [seed: EDF3CF085726CE2A] Total time: 61 minutes 46 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_228_1122943243.1457101164171 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_228_1122943243.1457101164171--