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 69AEA18A79 for ; Thu, 29 Oct 2015 04:26:18 +0000 (UTC) Received: (qmail 22899 invoked by uid 500); 29 Oct 2015 04:26:17 -0000 Delivered-To: apmail-lucene-dev-archive@lucene.apache.org Received: (qmail 22845 invoked by uid 500); 29 Oct 2015 04:26:17 -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 22835 invoked by uid 99); 29 Oct 2015 04:26:16 -0000 Received: from Unknown (HELO spamd4-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 29 Oct 2015 04:26:16 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd4-us-west.apache.org (ASF Mail Server at spamd4-us-west.apache.org) with ESMTP id 33F3AC0F9C for ; Thu, 29 Oct 2015 04:26:16 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd4-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: 2.002 X-Spam-Level: ** X-Spam-Status: No, score=2.002 tagged_above=-999 required=6.31 tests=[FSL_HELO_NON_FQDN_1=0.001, KAM_BADIPHTTP=2, SPF_PASS=-0.001, URIBL_BLOCKED=0.001, WEIRD_PORT=0.001] autolearn=disabled Received: from mx1-us-west.apache.org ([10.40.0.8]) by localhost (spamd4-us-west.apache.org [10.40.0.11]) (amavisd-new, port 10024) with ESMTP id xoxVnARk-QBG for ; Thu, 29 Oct 2015 04:25:59 +0000 (UTC) Received: from serv1 (serv1.sd-datasolutions.de [188.138.57.78]) by mx1-us-west.apache.org (ASF Mail Server at mx1-us-west.apache.org) with ESMTPS id 13BAD22F1E for ; Thu, 29 Oct 2015 04:25:58 +0000 (UTC) Received: from localhost.localdomain ([127.0.0.1] helo=serv1) by serv1 with esmtp (Exim 4.82) (envelope-from ) id 1Zremd-0003Pj-2o for dev@lucene.apache.org; Thu, 29 Oct 2015 04:25:51 +0000 Date: Thu, 29 Oct 2015 04:25:37 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org Message-ID: <76756928.137.1446092751085.JavaMail.jenkins@serv1> Subject: [JENKINS-EA] Lucene-Solr-trunk-Linux (64bit/jdk1.9.0-ea-b85) - Build # 14706 - Failure! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_136_1940737641.1446092751035" X-Jenkins-Job: Lucene-Solr-trunk-Linux X-Jenkins-Result: FAILURE X-Priority: 5 (Low) ------=_Part_136_1940737641.1446092751035 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/14706/ Java: 64bit/jdk1.9.0-ea-b85 -XX:-UseCompressedOops -XX:+UseG1GC 1 tests failed. FAILED: org.apache.solr.cloud.BasicDistributedZkTest.test Error Message: commitWithin did not work on node: http://127.0.0.1:48817/collection1 expec= ted:<68> but was:<67> Stack Trace: java.lang.AssertionError: commitWithin did not work on node: http://127.0.0= .1:48817/collection1 expected:<68> but was:<67> =09at __randomizedtesting.SeedInfo.seed([1F60B6F6759E2BB3:9734892CDB62464B]= :0) =09at org.junit.Assert.fail(Assert.java:93) =09at org.junit.Assert.failNotEquals(Assert.java:647) =09at org.junit.Assert.assertEquals(Assert.java:128) =09at org.junit.Assert.assertEquals(Assert.java:472) =09at org.apache.solr.cloud.BasicDistributedZkTest.test(BasicDistributedZkT= est.java:333) =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:520) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1665) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:864) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Random= izedRunner.java:900) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rando= mizedRunner.java:914) =09at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= FixedStatement.callStatement(BaseDistributedSearchTestCase.java:963) =09at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= Statement.evaluate(BaseDistributedSearchTestCase.java:938) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:50) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:46) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:49) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:65) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:48) =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:873) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:775) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:809) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:820) =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:46) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:42) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) =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:54) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:65) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:55) =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:747) Build Log: [...truncated 9445 lines...] [junit4] Suite: org.apache.solr.cloud.BasicDistributedZkTest [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-trun= k-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest= _1F60B6F6759E2BB3-001/init-core-data-001 [junit4] 2> 136194 INFO (SUITE-BasicDistributedZkTest-seed#[1F60B6F67= 59E2BB3]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostCon= text system property: / [junit4] 2> 136197 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 136197 INFO (Thread-382) [ ] o.a.s.c.ZkTestServer clie= nt port:0.0.0.0/0.0.0.0:0 [junit4] 2> 136197 INFO (Thread-382) [ ] o.a.s.c.ZkTestServer Star= ting server [junit4] 2> 136297 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.ZkTestServer start zk server on port:54863 [junit4] 2> 136297 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProv= ider [junit4] 2> 136297 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to conn= ect to ZooKeeper [junit4] 2> 136301 INFO (zkCallback-92-thread-1) [ ] o.a.s.c.c.Con= nectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5c5ad= c63 name:ZooKeeperConnection Watcher:127.0.0.1:54863 got event WatchedEvent= state:SyncConnected type:None path:null path:null type:None [junit4] 2> 136301 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to Zoo= Keeper [junit4] 2> 136301 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 136302 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 136304 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProv= ider [junit4] 2> 136305 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to conn= ect to ZooKeeper [junit4] 2> 136306 INFO (zkCallback-93-thread-1) [ ] o.a.s.c.c.Con= nectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@25da3= 3e name:ZooKeeperConnection Watcher:127.0.0.1:54863/solr got event WatchedE= vent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 136306 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to Zoo= Keeper [junit4] 2> 136307 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 136307 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collecti= on1 [junit4] 2> 136308 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collecti= on1/shards [junit4] 2> 136309 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_= collection [junit4] 2> 136309 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_= collection/shards [junit4] 2> 136310 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace= /Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/sol= rconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 136310 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrco= nfig.xml [junit4] 2> 136312 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace= /Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/sch= ema.xml to /configs/conf1/schema.xml [junit4] 2> 136312 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema= .xml [junit4] 2> 136313 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace= /Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/sol= rconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.= randomindexconfig.xml [junit4] 2> 136313 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrco= nfig.snippet.randomindexconfig.xml [junit4] 2> 136313 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace= /Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/sto= pwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 136313 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwo= rds.txt [junit4] 2> 136314 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace= /Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/pro= twords.txt to /configs/conf1/protwords.txt [junit4] 2> 136314 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwo= rds.txt [junit4] 2> 136315 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace= /Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/cur= rency.xml to /configs/conf1/currency.xml [junit4] 2> 136315 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/curren= cy.xml [junit4] 2> 136316 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace= /Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/enu= msConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 136316 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsC= onfig.xml [junit4] 2> 136317 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace= /Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/ope= n-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 136317 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-e= xchange-rates.json [junit4] 2> 136317 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace= /Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/map= ping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 136317 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mappin= g-ISOLatin1Accent.txt [junit4] 2> 136318 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace= /Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/old= _synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 136318 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_sy= nonyms.txt [junit4] 2> 136318 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace= /Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/syn= onyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 136318 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synony= ms.txt [junit4] 2> 136369 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /= home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0= /temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/control-001/co= res/collection1 [junit4] 2> 136370 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 136371 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContex= tHandler@26f9940b{/,null,AVAILABLE} [junit4] 2> 136373 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.e.j.s.ServerConnector Started ServerConnector@679736= d5{HTTP/1.1}{127.0.0.1:=DB=B5=DB=B2=DB=B7=DB=B9=DB=B8} [junit4] 2> 136373 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.e.j.s.Server Started @=DB=B1=DB=B3=DB=B8=DB=B0=DB=B2= =DB=B0ms [junit4] 2> 136373 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.da= ta.dir=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-co= re/test/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/temp= Dir-001/control/data, hostContext=3D/, hostPort=3D52798, coreRootDirectory= =3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/tes= t/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/control-00= 1/cores} [junit4] 2> 136374 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): = sun.misc.Launcher$AppClassLoader@73c6c3b2 [junit4] 2> 136374 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for = directory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr= -core/test/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/c= ontrol-001/' [junit4] 2> 136374 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for sol= r (NoInitialContextEx) [junit4] 2> 136374 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'sol= r/' (could not find system property or JNDI) [junit4] 2> 136382 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProv= ider [junit4] 2> 136382 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to conn= ect to ZooKeeper [junit4] 2> 136384 INFO (zkCallback-94-thread-1) [ ] o.a.s.c.c.Con= nectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6ec54= dac name:ZooKeeperConnection Watcher:127.0.0.1:54863/solr got event Watched= Event state:SyncConnected type:None path:null path:null type:None [junit4] 2> 136384 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to Zoo= Keeper [junit4] 2> 136384 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 136385 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrH= ome (not found in ZooKeeper) [junit4] 2> 136385 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration = from /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/t= est/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/control-= 001/solr.xml [junit4] 2> 136390 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.CoresLocator Config-defined core root director= y: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/tes= t/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/control-00= 1/cores [junit4] 2> 136390 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.CoreContainer New CoreContainer 680358540 [junit4] 2> 136390 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer= [instanceDir=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/= solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-0= 01/control-001/] [junit4] 2> 136390 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.CoreContainer loading shared library: /home/je= nkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/s= olr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/control-001/lib [junit4] 2> 136390 WARN (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) direct= ory to add to classloader: lib (resolved as: /home/jenkins/workspace/Lucene= -Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistrib= utedZkTest_1F60B6F6759E2BB3-001/control-001/lib). [junit4] 2> 136394 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketT= imeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,= maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThr= eadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 136395 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler= HTTP client with params: socketTimeout=3D340000&connTimeout=3D45000&retry= =3Dtrue [junit4] 2> 136396 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jL= oggerFactory [junit4] 2> 136396 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (or= g.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 136396 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.ZkContainer Zookeeper client=3D127.0.0.1:54863= /solr [junit4] 2> 136396 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 136396 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProv= ider [junit4] 2> 136396 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to conn= ect to ZooKeeper [junit4] 2> 136398 INFO (zkCallback-96-thread-1) [ ] o.a.s.c.c.Con= nectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1f63e= f33 name:ZooKeeperConnection Watcher:127.0.0.1:54863 got event WatchedEvent= state:SyncConnected type:None path:null path:null type:None [junit4] 2> 136398 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to Zoo= Keeper [junit4] 2> 136399 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 136400 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.c.c.ConnectionManager Waiting = for client to connect to ZooKeeper [junit4] 2> 136401 INFO (zkCallback-97-thread-1-processing-n:127.0.0.= 1:52798_) [n:127.0.0.1:52798_ ] o.a.s.c.c.ConnectionManager Watcher org.= apache.solr.common.cloud.ConnectionManager@2136f24f name:ZooKeeperConnectio= n Watcher:127.0.0.1:54863/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4] 2> 136401 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.c.c.ConnectionManager Client i= s connected to ZooKeeper [junit4] 2> 136402 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.c.c.SolrZkClient makePath: /ov= erseer/queue [junit4] 2> 136403 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.c.c.SolrZkClient makePath: /ov= erseer/collection-queue-work [junit4] 2> 136404 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.c.c.SolrZkClient makePath: /ov= erseer/collection-map-running [junit4] 2> 136404 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.c.c.SolrZkClient makePath: /ov= erseer/collection-map-completed [junit4] 2> 136405 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.c.c.SolrZkClient makePath: /ov= erseer/collection-map-failure [junit4] 2> 136406 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.c.c.SolrZkClient makePath: /li= ve_nodes [junit4] 2> 136406 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.c.c.SolrZkClient makePath: /al= iases.json [junit4] 2> 136406 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.c.c.SolrZkClient makePath: /cl= usterstate.json [junit4] 2> 136407 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.c.c.SolrZkClient makePath: /se= curity.json [junit4] 2> 136407 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.c.ZkController Register node a= s live in ZooKeeper:/live_nodes/127.0.0.1:52798_ [junit4] 2> 136408 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.c.c.SolrZkClient makePath: /li= ve_nodes/127.0.0.1:52798_ [junit4] 2> 136408 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.c.c.SolrZkClient makePath: /ov= erseer_elect [junit4] 2> 136408 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.c.c.SolrZkClient makePath: /ov= erseer_elect/election [junit4] 2> 136409 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.c.Overseer Overseer (id=3Dnull= ) closing [junit4] 2> 136409 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.c.LeaderElector Joined leaders= hip election with path: /overseer_elect/election/94771037125148676-127.0.0.= 1:52798_-n_0000000000 [junit4] 2> 136409 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.c.OverseerElectionContext I am= going to be the leader 127.0.0.1:52798_ [junit4] 2> 136409 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.c.c.SolrZkClient makePath: /ov= erseer_elect/leader [junit4] 2> 136409 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.c.Overseer Overseer (id=3D9477= 1037125148676-127.0.0.1:52798_-n_0000000000) starting [junit4] 2> 136410 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.c.c.SolrZkClient makePath: /ov= erseer/queue-work [junit4] 2> 136412 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.c.OverseerAutoReplicaFailoverT= hread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoo= pDelay=3D10000 autoReplicaFailoverWaitAfterExpiration=3D30000 autoReplicaFa= iloverBadNodeExpiration=3D60000 [junit4] 2> 136412 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.c.c.ZkStateReader Updating clu= ster state from ZooKeeper...=20 [junit4] 2> 136412 INFO (OverseerCollectionConfigSetProcessor-9477103= 7125148676-127.0.0.1:52798_-n_0000000000) [n:127.0.0.1:52798_ ] o.a.s.c.= OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 136412 INFO (OverseerStateUpdate-94771037125148676-127.0.= 0.1:52798_-n_0000000000) [n:127.0.0.1:52798_ ] o.a.s.c.Overseer Starting= to work on the main queue [junit4] 2> 136421 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.c.CoreContainer Security conf = doesn't exist. Skipping setup for authorization module. [junit4] 2> 136421 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.c.CoreContainer No authenticat= ion plugin used. [junit4] 2> 136422 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.c.CoresLocator Looking for cor= e definitions underneath /home/jenkins/workspace/Lucene-Solr-trunk-Linux/so= lr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F67= 59E2BB3-001/control-001/cores [junit4] 2> 136422 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.c.SolrCore Created CoreDescrip= tor: {name=3Dcollection1, config=3Dsolrconfig.xml, transient=3Dfalse, schem= a=3Dschema.xml, loadOnStartup=3Dtrue, configSetProperties=3Dconfigsetprops.= json, instanceDir=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/bu= ild/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2B= B3-001/control-001/cores/collection1, collection=3Dcontrol_collection, abso= luteInstDir=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/so= lr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001= /control-001/cores/collection1/, coreNodeName=3D, dataDir=3Ddata/, shard=3D= } [junit4] 2> 136422 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.c.CoresLocator Found core coll= ection1 in /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-= core/test/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/co= ntrol-001/cores/collection1/ [junit4] 2> 136422 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.c.CoresLocator Found 1 core de= finitions [junit4] 2> 136423 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.s.SolrDispatchFilter user.dir= =3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/tes= t/J0 [junit4] 2> 136423 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:52798_ ] o.a.s.s.SolrDispatchFilter SolrDispa= tchFilter.init() done [junit4] 2> 136423 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection x:collection1] = o.a.s.c.ZkController publishing state=3Ddown [junit4] 2> 136423 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection x:collection1] = o.a.s.c.ZkController numShards not found on descriptor - reading it from sy= stem property [junit4] 2> 136424 INFO (OverseerStateUpdate-94771037125148676-127.0.= 0.1:52798_-n_0000000000) [n:127.0.0.1:52798_ ] 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:52798", [junit4] 2> "node_name":"127.0.0.1:52798_", [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> 136424 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection x:collection1] = o.a.s.c.ZkController look for our core node name [junit4] 2> 136424 INFO (OverseerStateUpdate-94771037125148676-127.0.= 0.1:52798_-n_0000000000) [n:127.0.0.1:52798_ ] 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:52798", [junit4] 2> "node_name":"127.0.0.1:52798_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 136424 INFO (OverseerStateUpdate-94771037125148676-127.0.= 0.1:52798_-n_0000000000) [n:127.0.0.1:52798_ ] o.a.s.c.o.ClusterStateMut= ator building a new cName: control_collection [junit4] 2> 136424 INFO (OverseerStateUpdate-94771037125148676-127.0.= 0.1:52798_-n_0000000000) [n:127.0.0.1:52798_ ] o.a.s.c.o.ReplicaMutator = Assigning new node to shard shard=3Dshard1 [junit4] 2> 136425 INFO (zkCallback-97-thread-1-processing-n:127.0.0.= 1:52798_) [n:127.0.0.1:52798_ ] o.a.s.c.c.ZkStateReader A cluster state = change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/cluster= state.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 137424 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection x:collection1] = o.a.s.c.ZkController waiting to find shard id in clusterstate for collectio= n1 [junit4] 2> 137424 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection x:collection1] = o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 137425 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection x:collection1] = o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 137425 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection x:collection1] = o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_co= llection [junit4] 2> 137425 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection x:collection1] = o.a.s.c.c.ZkStateReader path=3D/collections/control_collection configName= =3Dconf1 specified config exists in ZooKeeper [junit4] 2> 137425 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection x:collection1] = o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jen= kins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/so= lr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/control-001/cores/coll= ection1/' [junit4] 2> 137425 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection x:collection1] = o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx= ) [junit4] 2> 137425 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection x:collection1] = o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find s= ystem property or JNDI) [junit4] 2> 137432 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection x:collection1] = o.a.s.c.Config loaded config solrconfig.xml with version 0=20 [junit4] 2> 137435 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection x:collection1] = o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 137438 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection x:collection1] = o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 137444 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection x:collection1] = o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 137444 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection x:collection1] = o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 137448 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection x:collection1] = o.a.s.s.IndexSchema [collection1] Schema name=3Dtest [junit4] 2> 137522 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection x:collection1] = o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=3Dopen-exchange= -rates.json, refreshInterval=3D1440. [junit4] 2> 137526 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection x:collection1] = o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 137527 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection x:collection1] = o.a.s.s.IndexSchema unique key field: id [junit4] 2> 137531 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection x:collection1] = o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currenc= y.xml [junit4] 2> 137532 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection x:collection1] = o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currenc= y.xml [junit4] 2> 137533 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection x:collection1] = o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exc= hange-rates.json [junit4] 2> 137534 WARN (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection x:collection1] = o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 137534 WARN (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection x:collection1] = o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 137534 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection x:collection1] = o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exc= hange-rates.json [junit4] 2> 137534 WARN (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection x:collection1] = o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 137534 WARN (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection x:collection1] = o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 137535 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection x:collection1] = o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming def= ault properties: Can't find resource 'configsetprops.json' in classpath or = '/configs/conf1', cwd=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/sol= r/build/solr-core/test/J0 [junit4] 2> 137535 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection x:collection1] = o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration f= rom collection control_collection [junit4] 2> 137535 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFact= ory [junit4] 2> 137535 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore = at [/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/te= st/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/control-0= 01/cores/collection1/], dataDir=3D[null] [junit4] 2> 137535 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Addi= ng Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1acb48= fa [junit4] 2> 137536 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory fo= r /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test= /J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/control-001= /cores/collection1/data [junit4] 2> 137536 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=3Dnu= ll new=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-co= re/test/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/cont= rol-001/cores/collection1/data/index/ [junit4] 2> 137536 WARN (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/h= ome/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/= temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/control-001/cor= es/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 137536 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory fo= r /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test= /J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/control-001= /cores/collection1/data/index [junit4] 2> 137536 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping cl= ass org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMerge= AtOnce=3D33, maxMergeAtOnceExplicit=3D38, maxMergedSegmentMB=3D85.2265625, = floorSegmentMB=3D1.7783203125, forceMergeDeletesPctAllowed=3D16.96637057882= 7174, segmentsPerTier=3D42.0, maxCFSSegmentSizeMB=3D1.8701171875, noCFSRati= o=3D0.0 [junit4] 2> 137536 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: = num=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@674f9942= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@74371d2a),= segFN=3Dsegments_1,generation=3D1} [junit4] 2> 137537 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.c.SolrCore newest commit generation =3D 1 [junit4] 2> 137537 INFO (OldIndexDirectoryCleanupThreadForCore-collec= tion1) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_node1 x:col= lection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for= core collection1 in /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/b= uild/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2= BB3-001/control-001/cores/collection1/data/ [junit4] 2> 137537 WARN (OldIndexDirectoryCleanupThreadForCore-collec= tion1) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_node1 x:col= lection1] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr-trun= k-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest= _1F60B6F6759E2BB3-001/control-001/cores/collection1/data/ does not point to= a valid data directory; skipping clean-up of old index directories. [junit4] 2> 137539 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRe= questProcessorChain "nodistrib" [junit4] 2> 137540 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRe= questProcessorChain "dedupe" [junit4] 2> 137540 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting Distrib= utedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 137540 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRe= questProcessorChain "stored_sig" [junit4] 2> 137540 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting Distrib= utedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 137540 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRe= questProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 137540 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRe= questProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 137540 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting Distrib= utedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-te= st-chain-implicit" [junit4] 2> 137540 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined= as default, creating implicit default [junit4] 2> 137541 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 137542 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 137542 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 137543 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 137545 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans= ,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/a= dmin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/= admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 137545 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.ap= ache.solr.search.stats.LocalStatsCache [junit4] 2> 137545 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: o= rg.apache.solr.update.UpdateLog [junit4] 2> 137545 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=3D de= faultSyncLevel=3DFLUSH numRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVers= ionBuckets=3D65536 [junit4] 2> 137546 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 137546 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 137547 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping cl= ass org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMerge= AtOnce=3D43, maxMergeAtOnceExplicit=3D22, maxMergedSegmentMB=3D46.32421875,= floorSegmentMB=3D1.93359375, forceMergeDeletesPctAllowed=3D29.150063970071= 41, segmentsPerTier=3D47.0, maxCFSSegmentSizeMB=3D8.796093022207999E12, noC= FSRatio=3D1.0 [junit4] 2> 137547 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: nu= m=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@674f9942= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@74371d2a),= segFN=3Dsegments_1,generation=3D1} [junit4] 2> 137548 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.c.SolrCore newest commit generation =3D 1 [junit4] 2> 137548 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@5297b843[col= lection1] main [junit4] 2> 137548 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/co= llections/control_collection [junit4] 2> 137548 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.c.c.ZkStateReader path=3D/collections/control_col= lection configName=3Dconf1 specified config exists in ZooKeeper [junit4] 2> 137548 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-bas= ed storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 137548 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStor= ageIO with znodeBase: /configs/conf1 [junit4] 2> 137548 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initA= rgs: {} [junit4] 2> 137548 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.js= on using ZooKeeperStorageIO:path=3D/configs/conf1 [junit4] 2> 137548 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.r.ManagedResourceStorage No data found for znode = /configs/conf1/_rest_managed.json [junit4] 2> 137548 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _res= t_managed.json using ZooKeeperStorageIO:path=3D/configs/conf1 [junit4] 2> 137549 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedRe= sources [junit4] 2> 137549 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for= 10000 [junit4] 2> 137549 INFO (searcherExecutor-350-thread-1-processing-n:1= 27.0.0.1:52798_ x:collection1 s:shard1 c:control_collection r:core_node1) [= n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_node1 x:collection1= ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5297b843[= collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 137549 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.u.UpdateLog Looking up max value of version field= to seed version buckets [junit4] 2> 137549 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _versio= n_ for 65536 version buckets from index [junit4] 2> 137549 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.u.VersionInfo No terms found for _version_, canno= t seed version bucket highest value from index [junit4] 2> 137549 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.u.UpdateLog Could not find max version in index o= r recent updates, using new clock 1516336595417956352 [junit4] 2> 137551 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets wi= th highest version 1516336595417956352 [junit4] 2> 137551 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 137551 INFO (coreLoadExecutor-349-thread-1-processing-n:1= 27.0.0.1:52798_) [n:127.0.0.1:52798_ c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 137551 INFO (coreZkRegister-343-thread-1-processing-n:127= .0.0.1:52798_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:= 127.0.0.1:52798_ c:control_collection s:shard1 r:core_node1 x:collection1] = o.a.s.c.ZkController Register replica - core:collection1 address:http://127= .0.0.1:52798 collection:control_collection shard:shard1 [junit4] 2> 137552 INFO (coreZkRegister-343-thread-1-processing-n:127= .0.0.1:52798_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:= 127.0.0.1:52798_ c:control_collection s:shard1 r:core_node1 x:collection1] = o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leader_ele= ct/shard1/election [junit4] 2> 137553 INFO (coreZkRegister-343-thread-1-processing-n:127= .0.0.1:52798_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:= 127.0.0.1:52798_ c:control_collection s:shard1 r:core_node1 x:collection1] = o.a.s.c.LeaderElector Joined leadership election with path: /collections/co= ntrol_collection/leader_elect/shard1/election/94771037125148676-core_node1-= n_0000000000 [junit4] 2> 137553 INFO (coreZkRegister-343-thread-1-processing-n:127= .0.0.1:52798_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:= 127.0.0.1:52798_ c:control_collection s:shard1 r:core_node1 x:collection1] = o.a.s.c.ShardLeaderElectionContext Running the leader process for shard sha= rd1 [junit4] 2> 137553 INFO (coreZkRegister-343-thread-1-processing-n:127= .0.0.1:52798_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:= 127.0.0.1:52798_ c:control_collection s:shard1 r:core_node1 x:collection1] = o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 137553 INFO (coreZkRegister-343-thread-1-processing-n:127= .0.0.1:52798_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:= 127.0.0.1:52798_ 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> 137553 INFO (coreZkRegister-343-thread-1-processing-n:127= .0.0.1:52798_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:= 127.0.0.1:52798_ c:control_collection s:shard1 r:core_node1 x:collection1] = o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:52798/collection1/ [junit4] 2> 137553 INFO (coreZkRegister-343-thread-1-processing-n:127= .0.0.1:52798_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:= 127.0.0.1:52798_ c:control_collection s:shard1 r:core_node1 x:collection1] = o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 137553 INFO (coreZkRegister-343-thread-1-processing-n:127= .0.0.1:52798_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:= 127.0.0.1:52798_ c:control_collection s:shard1 r:core_node1 x:collection1] = o.a.s.c.SyncStrategy http://127.0.0.1:52798/collection1/ has no replicas [junit4] 2> 137553 INFO (coreZkRegister-343-thread-1-processing-n:127= .0.0.1:52798_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:= 127.0.0.1:52798_ 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:52= 798/collection1/ shard1 [junit4] 2> 137553 INFO (OverseerStateUpdate-94771037125148676-127.0.= 0.1:52798_-n_0000000000) [n:127.0.0.1:52798_ ] 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> 137554 INFO (coreZkRegister-343-thread-1-processing-n:127= .0.0.1:52798_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:= 127.0.0.1:52798_ c:control_collection s:shard1 r:core_node1 x:collection1] = o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/sh= ard1 [junit4] 2> 137554 INFO (coreZkRegister-343-thread-1-processing-n:127= .0.0.1:52798_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:= 127.0.0.1:52798_ c:control_collection s:shard1 r:core_node1 x:collection1] = o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node [junit4] 2> 137556 INFO (OverseerStateUpdate-94771037125148676-127.0.= 0.1:52798_-n_0000000000) [n:127.0.0.1:52798_ ] 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:52798", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 137657 INFO (zkCallback-97-thread-1-processing-n:127.0.0.= 1:52798_) [n:127.0.0.1:52798_ ] o.a.s.c.c.ZkStateReader A cluster state = change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/cluster= state.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 137706 INFO (coreZkRegister-343-thread-1-processing-n:127= .0.0.1:52798_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:= 127.0.0.1:52798_ c:control_collection s:shard1 r:core_node1 x:collection1] = o.a.s.c.ZkController We are http://127.0.0.1:52798/collection1/ and leader = is http://127.0.0.1:52798/collection1/ [junit4] 2> 137706 INFO (coreZkRegister-343-thread-1-processing-n:127= .0.0.1:52798_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:= 127.0.0.1:52798_ c:control_collection s:shard1 r:core_node1 x:collection1] = o.a.s.c.ZkController No LogReplay needed for core=3Dcollection1 baseURL=3Dh= ttp://127.0.0.1:52798 [junit4] 2> 137706 INFO (coreZkRegister-343-thread-1-processing-n:127= .0.0.1:52798_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:= 127.0.0.1:52798_ c:control_collection s:shard1 r:core_node1 x:collection1] = o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 137706 INFO (coreZkRegister-343-thread-1-processing-n:127= .0.0.1:52798_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:= 127.0.0.1:52798_ c:control_collection s:shard1 r:core_node1 x:collection1] = o.a.s.c.ZkController publishing state=3Dactive [junit4] 2> 137706 INFO (coreZkRegister-343-thread-1-processing-n:127= .0.0.1:52798_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:= 127.0.0.1:52798_ c:control_collection s:shard1 r:core_node1 x:collection1] = o.a.s.c.ZkController numShards not found on descriptor - reading it from sy= stem property [junit4] 2> 137707 INFO (OverseerStateUpdate-94771037125148676-127.0.= 0.1:52798_-n_0000000000) [n:127.0.0.1:52798_ ] 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:52798", [junit4] 2> "node_name":"127.0.0.1:52798_", [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> 137707 INFO (OverseerStateUpdate-94771037125148676-127.0.= 0.1:52798_-n_0000000000) [n:127.0.0.1:52798_ ] 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:52798", [junit4] 2> "node_name":"127.0.0.1:52798_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 137808 INFO (zkCallback-97-thread-1-processing-n:127.0.0.= 1:52798_) [n:127.0.0.1:52798_ ] o.a.s.c.c.ZkStateReader A cluster state = change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/cluster= state.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 137924 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProv= ider [junit4] 2> 137924 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to conn= ect to ZooKeeper [junit4] 2> 137926 INFO (zkCallback-99-thread-1) [ ] o.a.s.c.c.Con= nectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@79f1c= de4 name:ZooKeeperConnection Watcher:127.0.0.1:54863/solr got event Watched= Event state:SyncConnected type:None path:null path:null type:None [junit4] 2> 137926 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to Zoo= Keeper [junit4] 2> 137926 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 137926 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from Zo= oKeeper...=20 [junit4] 2> 137927 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:fal= se cause connection loss:false [junit4] 2> 137976 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /= home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0= /temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/shard-1-001/co= res/collection1 [junit4] 2> 137976 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 i= n directory /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr= -core/test/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/s= hard-1-001 [junit4] 2> 137977 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 137978 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContex= tHandler@14a33069{/,null,AVAILABLE} [junit4] 2> 137978 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.e.j.s.ServerConnector Started ServerConnector@31aa6a= be{HTTP/1.1}{127.0.0.1:=DB=B4=DB=B8=DB=B8=DB=B1=DB=B7} [junit4] 2> 137978 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.e.j.s.Server Started @=DB=B1=DB=B3=DB=B9=DB=B6=DB=B2= =DB=B5ms [junit4] 2> 137978 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.da= ta.dir=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-co= re/test/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/temp= Dir-001/jetty1, solrconfig=3Dsolrconfig.xml, hostContext=3D/, hostPort=3D48= 817, coreRootDirectory=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/so= lr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F67= 59E2BB3-001/shard-1-001/cores} [junit4] 2> 137978 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): = sun.misc.Launcher$AppClassLoader@73c6c3b2 [junit4] 2> 137978 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for = directory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr= -core/test/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/s= hard-1-001/' [junit4] 2> 137979 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for sol= r (NoInitialContextEx) [junit4] 2> 137979 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'sol= r/' (could not find system property or JNDI) [junit4] 2> 137986 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProv= ider [junit4] 2> 137986 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to conn= ect to ZooKeeper [junit4] 2> 137989 INFO (zkCallback-100-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5e1c= a227 name:ZooKeeperConnection Watcher:127.0.0.1:54863/solr got event Watche= dEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 137989 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to Zoo= Keeper [junit4] 2> 137989 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 137990 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrH= ome (not found in ZooKeeper) [junit4] 2> 137990 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration = from /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/t= est/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/shard-1-= 001/solr.xml [junit4] 2> 137994 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.CoresLocator Config-defined core root director= y: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/tes= t/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/shard-1-00= 1/cores [junit4] 2> 137994 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.CoreContainer New CoreContainer 556038434 [junit4] 2> 137994 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer= [instanceDir=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/= solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-0= 01/shard-1-001/] [junit4] 2> 137994 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.CoreContainer loading shared library: /home/je= nkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/s= olr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/shard-1-001/lib [junit4] 2> 137994 WARN (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) direct= ory to add to classloader: lib (resolved as: /home/jenkins/workspace/Lucene= -Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistrib= utedZkTest_1F60B6F6759E2BB3-001/shard-1-001/lib). [junit4] 2> 137998 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketT= imeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,= maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThr= eadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 138000 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler= HTTP client with params: socketTimeout=3D340000&connTimeout=3D45000&retry= =3Dtrue [junit4] 2> 138000 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jL= oggerFactory [junit4] 2> 138000 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (or= g.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 138000 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.ZkContainer Zookeeper client=3D127.0.0.1:54863= /solr [junit4] 2> 138000 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 138000 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProv= ider [junit4] 2> 138000 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to conn= ect to ZooKeeper [junit4] 2> 138002 INFO (zkCallback-102-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3c96= a06f name:ZooKeeperConnection Watcher:127.0.0.1:54863 got event WatchedEven= t state:SyncConnected type:None path:null path:null type:None [junit4] 2> 138002 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to Zoo= Keeper [junit4] 2> 138002 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 138004 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:48817_ ] o.a.s.c.c.ConnectionManager Waiting = for client to connect to ZooKeeper [junit4] 2> 138005 INFO (zkCallback-103-thread-1-processing-n:127.0.0= .1:48817_) [n:127.0.0.1:48817_ ] o.a.s.c.c.ConnectionManager Watcher org= .apache.solr.common.cloud.ConnectionManager@48fff080 name:ZooKeeperConnecti= on Watcher:127.0.0.1:54863/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4] 2> 138005 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:48817_ ] o.a.s.c.c.ConnectionManager Client i= s connected to ZooKeeper [junit4] 2> 138007 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:48817_ ] o.a.s.c.c.ZkStateReader Updating clu= ster state from ZooKeeper...=20 [junit4] 2> 139009 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:48817_ ] o.a.s.c.ZkController Register node a= s live in ZooKeeper:/live_nodes/127.0.0.1:48817_ [junit4] 2> 139009 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:48817_ ] o.a.s.c.c.SolrZkClient makePath: /li= ve_nodes/127.0.0.1:48817_ [junit4] 2> 139009 INFO (zkCallback-99-thread-1) [ ] o.a.s.c.c.ZkS= tateReader A live node change: WatchedEvent state:SyncConnected type:NodeCh= ildrenChanged path:/live_nodes, has occurred - updating... (live nodes size= : 1) [junit4] 2> 139009 INFO (zkCallback-103-thread-1-processing-n:127.0.0= .1:48817_) [n:127.0.0.1:48817_ ] o.a.s.c.c.ZkStateReader A live node cha= nge: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_n= odes, has occurred - updating... (live nodes size: 1) [junit4] 2> 139009 INFO (zkCallback-97-thread-1-processing-n:127.0.0.= 1:52798_) [n:127.0.0.1:52798_ ] o.a.s.c.c.ZkStateReader A live node chan= ge: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_no= des, has occurred - updating... (live nodes size: 1) [junit4] 2> 139010 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:48817_ ] o.a.s.c.Overseer Overseer (id=3Dnull= ) closing [junit4] 2> 139011 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:48817_ ] o.a.s.c.LeaderElector Joined leaders= hip election with path: /overseer_elect/election/94771037125148680-127.0.0.= 1:48817_-n_0000000001 [junit4] 2> 139011 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:48817_ ] o.a.s.c.LeaderElector Watching path = /overseer_elect/election/94771037125148676-127.0.0.1:52798_-n_0000000000 to= know if I could be the leader [junit4] 2> 139015 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:48817_ ] o.a.s.c.CoreContainer Security conf = doesn't exist. Skipping setup for authorization module. [junit4] 2> 139015 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:48817_ ] o.a.s.c.CoreContainer No authenticat= ion plugin used. [junit4] 2> 139015 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:48817_ ] o.a.s.c.CoresLocator Looking for cor= e definitions underneath /home/jenkins/workspace/Lucene-Solr-trunk-Linux/so= lr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F67= 59E2BB3-001/shard-1-001/cores [junit4] 2> 139016 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:48817_ ] o.a.s.c.SolrCore Created CoreDescrip= tor: {name=3Dcollection1, config=3Dsolrconfig.xml, transient=3Dfalse, schem= a=3Dschema.xml, loadOnStartup=3Dtrue, configSetProperties=3Dconfigsetprops.= json, instanceDir=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/bu= ild/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2B= B3-001/shard-1-001/cores/collection1, collection=3Dcollection1, absoluteIns= tDir=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core= /test/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/shard-= 1-001/cores/collection1/, coreNodeName=3D, dataDir=3Ddata/, shard=3D} [junit4] 2> 139016 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:48817_ ] o.a.s.c.CoresLocator Found core coll= ection1 in /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-= core/test/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/sh= ard-1-001/cores/collection1/ [junit4] 2> 139016 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:48817_ ] o.a.s.c.CoresLocator Found 1 core de= finitions [junit4] 2> 139016 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:48817_ ] o.a.s.s.SolrDispatchFilter user.dir= =3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/tes= t/J0 [junit4] 2> 139016 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:48817_ ] o.a.s.s.SolrDispatchFilter SolrDispa= tchFilter.init() done [junit4] 2> 139016 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 x:collection1] o.a.s.c= .ZkController publishing state=3Ddown [junit4] 2> 139016 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 x:collection1] o.a.s.c= .ZkController numShards not found on descriptor - reading it from system pr= operty [junit4] 2> 139017 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 x:collection1] o.a.s.c= .ZkController look for our core node name [junit4] 2> 139017 INFO (OverseerStateUpdate-94771037125148676-127.0.= 0.1:52798_-n_0000000000) [n:127.0.0.1:52798_ ] 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:48817", [junit4] 2> "node_name":"127.0.0.1:48817_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 139017 INFO (OverseerStateUpdate-94771037125148676-127.0.= 0.1:52798_-n_0000000000) [n:127.0.0.1:52798_ ] o.a.s.c.o.ReplicaMutator = Update state numShards=3D2 message=3D{ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:48817", [junit4] 2> "node_name":"127.0.0.1:48817_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 139017 INFO (OverseerStateUpdate-94771037125148676-127.0.= 0.1:52798_-n_0000000000) [n:127.0.0.1:52798_ ] o.a.s.c.o.ClusterStateMut= ator building a new cName: collection1 [junit4] 2> 139017 INFO (OverseerStateUpdate-94771037125148676-127.0.= 0.1:52798_-n_0000000000) [n:127.0.0.1:52798_ ] o.a.s.c.o.ReplicaMutator = Assigning new node to shard shard=3Dshard2 [junit4] 2> 139119 INFO (zkCallback-97-thread-1-processing-n:127.0.0.= 1:52798_) [n:127.0.0.1:52798_ ] o.a.s.c.c.ZkStateReader A cluster state = change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/cluster= state.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 139119 INFO (zkCallback-99-thread-1) [ ] o.a.s.c.c.ZkS= tateReader A cluster state change: WatchedEvent state:SyncConnected type:No= deDataChanged path:/clusterstate.json, has occurred - updating... (live nod= es size: 2) [junit4] 2> 139119 INFO (zkCallback-103-thread-1-processing-n:127.0.0= .1:48817_) [n:127.0.0.1:48817_ ] o.a.s.c.c.ZkStateReader A cluster state= change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/cluste= rstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 140017 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 x:collection1] o.a.s.c= .ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 140017 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 x:collection1] o.a.s.c= .ZkController Check for collection zkNode:collection1 [junit4] 2> 140018 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 x:collection1] o.a.s.c= .ZkController Collection zkNode exists [junit4] 2> 140018 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 x:collection1] o.a.s.c= .c.ZkStateReader Load collection config from:/collections/collection1 [junit4] 2> 140018 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 x:collection1] o.a.s.c= .c.ZkStateReader path=3D/collections/collection1 configName=3Dconf1 specifi= ed config exists in ZooKeeper [junit4] 2> 140018 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 x:collection1] o.a.s.c= .SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/wo= rkspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.clou= d.BasicDistributedZkTest_1F60B6F6759E2BB3-001/shard-1-001/cores/collection1= /' [junit4] 2> 140018 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 x:collection1] o.a.s.c= .SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 140018 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 x:collection1] o.a.s.c= .SolrResourceLoader solr home defaulted to 'solr/' (could not find system p= roperty or JNDI) [junit4] 2> 140025 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 x:collection1] o.a.s.c= .Config loaded config solrconfig.xml with version 0=20 [junit4] 2> 140028 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 x:collection1] o.a.s.c= .SolrConfig current version of requestparams : -1 [junit4] 2> 140032 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 x:collection1] o.a.s.c= .SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 140051 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 x:collection1] o.a.s.c= .Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 140051 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 x:collection1] o.a.s.s= .IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 140055 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 x:collection1] o.a.s.s= .IndexSchema [collection1] Schema name=3Dtest [junit4] 2> 140113 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 x:collection1] o.a.s.s= .OpenExchangeRatesOrgProvider Initialized with rates=3Dopen-exchange-rates.= json, refreshInterval=3D1440. [junit4] 2> 140117 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 x:collection1] o.a.s.s= .IndexSchema default search field in schema is text [junit4] 2> 140118 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 x:collection1] o.a.s.s= .IndexSchema unique key field: id [junit4] 2> 140122 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 x:collection1] o.a.s.s= .FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 140124 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 x:collection1] o.a.s.s= .FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 140125 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 x:collection1] o.a.s.s= .OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-r= ates.json [junit4] 2> 140125 WARN (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 x:collection1] o.a.s.s= .OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 140125 WARN (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 x:collection1] o.a.s.s= .OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 140125 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 x:collection1] o.a.s.s= .OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-r= ates.json [junit4] 2> 140125 WARN (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 x:collection1] o.a.s.s= .OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 140125 WARN (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 x:collection1] o.a.s.s= .OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 140126 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 x:collection1] o.a.s.c= .ConfigSetProperties Did not find ConfigSet properties, assuming default pr= operties: Can't find resource 'configsetprops.json' in classpath or '/confi= gs/conf1', cwd=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build= /solr-core/test/J0 [junit4] 2> 140126 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 x:collection1] o.a.s.c= .CoreContainer Creating SolrCore 'collection1' using configuration from col= lection collection1 [junit4] 2> 140126 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 s:shard2 r:core_node1 x:= collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 140126 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 s:shard2 r:core_node1 x:= collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/ho= me/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/t= emp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/shard-1-001/core= s/collection1/], dataDir=3D[null] [junit4] 2> 140126 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 s:shard2 r:core_node1 x:= collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr= mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1acb48fa [junit4] 2> 140127 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 s:shard2 r:core_node1 x:= collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home= /jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/tem= p/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/shard-1-001/cores/= collection1/data [junit4] 2> 140127 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 s:shard2 r:core_node1 x:= collection1] o.a.s.c.SolrCore New index directory detected: old=3Dnull new= =3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/tes= t/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/shard-1-00= 1/cores/collection1/data/index/ [junit4] 2> 140127 WARN (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 s:shard2 r:core_node1 x:= collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jen= kins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/so= lr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/shard-1-001/cores/coll= ection1/data/index' doesn't exist. Creating new index... [junit4] 2> 140128 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 s:shard2 r:core_node1 x:= collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home= /jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/tem= p/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/shard-1-001/cores/= collection1/data/index [junit4] 2> 140128 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 s:shard2 r:core_node1 x:= collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org= .apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce= =3D33, maxMergeAtOnceExplicit=3D38, maxMergedSegmentMB=3D85.2265625, floorS= egmentMB=3D1.7783203125, forceMergeDeletesPctAllowed=3D16.966370578827174, = segmentsPerTier=3D42.0, maxCFSSegmentSizeMB=3D1.8701171875, noCFSRatio=3D0.= 0 [junit4] 2> 140128 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 s:shard2 r:core_node1 x:= collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@5dc92c2d= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@539f6c6a),= segFN=3Dsegments_1,generation=3D1} [junit4] 2> 140128 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 s:shard2 r:core_node1 x:= collection1] o.a.s.c.SolrCore newest commit generation =3D 1 [junit4] 2> 140128 INFO (OldIndexDirectoryCleanupThreadForCore-collec= tion1) [n:127.0.0.1:48817_ c:collection1 s:shard2 r:core_node1 x:collection= 1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core c= ollection1 in /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/so= lr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001= /shard-1-001/cores/collection1/data/ [junit4] 2> 140128 WARN (OldIndexDirectoryCleanupThreadForCore-collec= tion1) [n:127.0.0.1:48817_ c:collection1 s:shard2 r:core_node1 x:collection= 1] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr-trunk-Linux= /solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6= F6759E2BB3-001/shard-1-001/cores/collection1/data/ does not point to a vali= d data directory; skipping clean-up of old index directories. [junit4] 2> 140132 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 s:shard2 r:core_node1 x:= collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestPr= ocessorChain "nodistrib" [junit4] 2> 140132 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 s:shard2 r:core_node1 x:= collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestPr= ocessorChain "dedupe" [junit4] 2> 140132 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 s:shard2 r:core_node1 x:= collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpd= ateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 140132 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 s:shard2 r:core_node1 x:= collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestPr= ocessorChain "stored_sig" [junit4] 2> 140132 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 s:shard2 r:core_node1 x:= collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpd= ateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 140132 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 s:shard2 r:core_node1 x:= collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestPr= ocessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 140132 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 s:shard2 r:core_node1 x:= collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestPr= ocessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 140132 INFO (coreLoadExecutor-360-thread-1-processing-n:1= 27.0.0.1:48817_) [n:127.0.0.1:48817_ c:collection1 s:shard2 r:core_node1 x:= colle [...truncated too long message...] 0.1:44413_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.SolrC= ore SolrDeletionPolicy.onCommit: commits: num=3D2 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@3c0559d9= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@6582b1ae),= segFN=3Dsegments_7,generation=3D7} [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@3c0559d9= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@6582b1ae),= segFN=3Dsegments_8,generation=3D8} [junit4] 2> 160561 INFO (zkCallback-116-thread-1-processing-n:127.0.0= .1:44413_) [n:127.0.0.1:44413_ c:collection1 s:shard1 r:core_node4 x:collec= tion1] o.a.s.c.SolrCore newest commit generation =3D 8 [junit4] 2> 160562 INFO (zkCallback-116-thread-1-processing-n:127.0.0= .1:44413_) [n:127.0.0.1:44413_ c:collection1 s:shard1 r:core_node4 x:collec= tion1] o.a.s.c.SolrCore [collection1] Closing main searcher on request. [junit4] 2> 160566 INFO (zkCallback-116-thread-1-processing-n:127.0.0= .1:44413_) [n:127.0.0.1:44413_ c:collection1 s:shard1 r:core_node4 x:collec= tion1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 dir= ectories currently being tracked [junit4] 2> 160566 INFO (zkCallback-116-thread-1-processing-n:127.0.0= .1:44413_) [n:127.0.0.1:44413_ c:collection1 s:shard1 r:core_node4 x:collec= tion1] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/works= pace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/../../../../../..= /../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-co= re/test/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/shar= d-4-001/cores/collection1/data [CachedDir<>] [junit4] 2> 160566 INFO (zkCallback-116-thread-1-processing-n:127.0.0= .1:44413_) [n:127.0.0.1:44413_ c:collection1 s:shard1 r:core_node4 x:collec= tion1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/wor= kspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/../../../../../= ../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-= core/test/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/sh= ard-4-001/cores/collection1/data [junit4] 2> 160566 INFO (zkCallback-116-thread-1-processing-n:127.0.0= .1:44413_) [n:127.0.0.1:44413_ c:collection1 s:shard1 r:core_node4 x:collec= tion1] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/works= pace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/../../../../../..= /../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-co= re/test/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/shar= d-4-001/cores/collection1/data/index [CachedDir<>] [junit4] 2> 160566 INFO (zkCallback-116-thread-1-processing-n:127.0.0= .1:44413_) [n:127.0.0.1:44413_ c:collection1 s:shard1 r:core_node4 x:collec= tion1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/wor= kspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/../../../../../= ../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-= core/test/J0/temp/solr.cloud.BasicDistributedZkTest_1F60B6F6759E2BB3-001/sh= ard-4-001/cores/collection1/data/index [junit4] 2> 160568 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@207794= 65{HTTP/1.1}{127.0.0.1:=DB=B0} [junit4] 2> 160568 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContex= tHandler@367b3fcd{/,null,UNAVAILABLE} [junit4] 2> 160569 INFO (TEST-BasicDistributedZkTest.test-seed#[1F60B= 6F6759E2BB3]) [n:127.0.0.1:44413_ c:collection1 s:shard1 r:core_node4 x:col= lection1] o.a.s.c.ZkTestServer connecting to 127.0.0.1:54863 54863 [junit4] 2> 160582 INFO (Thread-382) [ ] o.a.s.c.ZkTestServer conn= ecting to 127.0.0.1:54863 54863 [junit4] 2> 160583 WARN (Thread-382) [ ] o.a.s.c.ZkTestServer Watc= h limit violations:=20 [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2>=20 [junit4] 2> =097=09/solr/aliases.json [junit4] 2> =095=09/solr/security.json [junit4] 2> =095=09/solr/configs/conf1 [junit4] 2>=20 [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2>=20 [junit4] 2> =097=09/solr/clusterstate.json [junit4] 2>=20 [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2>=20 [junit4] 2> =097=09/solr/live_nodes [junit4] 2> =097=09/solr/collections [junit4] 2> =094=09/solr/overseer/queue [junit4] 2> =094=09/solr/overseer/collection-queue-work [junit4] 2> =092=09/solr/overseer/queue-work [junit4] 2>=20 [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DBasicDistribu= tedZkTest -Dtests.method=3Dtest -Dtests.seed=3D1F60B6F6759E2BB3 -Dtests.mul= tiplier=3D3 -Dtests.slow=3Dtrue -Dtests.locale=3Dfa -Dtests.timezone=3DAfri= ca/Asmera -Dtests.asserts=3Dtrue -Dtests.file.encoding=3DUTF-8 [junit4] FAILURE 24.4s J0 | BasicDistributedZkTest.test <<< [junit4] > Throwable #1: java.lang.AssertionError: commitWithin did n= ot work on node: http://127.0.0.1:48817/collection1 expected:<68> but was:<= 67> [junit4] > =09at __randomizedtesting.SeedInfo.seed([1F60B6F6759E2BB3:= 9734892CDB62464B]:0) [junit4] > =09at org.apache.solr.cloud.BasicDistributedZkTest.test(Ba= sicDistributedZkTest.java:333) [junit4] > =09at org.apache.solr.BaseDistributedSearchTestCase$Shards= RepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase= .java:963) [junit4] > =09at org.apache.solr.BaseDistributedSearchTestCase$Shards= RepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938) [junit4] > =09at java.lang.Thread.run(Thread.java:747) [junit4] 2> 160587 INFO (SUITE-BasicDistributedZkTest-seed#[1F60B6F67= 59E2BB3]-worker) [n:127.0.0.1:44413_ c:collection1 s:shard1 r:core_node4 x:= collection1] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/wo= rkspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.clou= d.BasicDistributedZkTest_1F60B6F6759E2BB3-001 [junit4] 2> Oct 29, 2015 4:01:29 AM com.carrotsearch.randomizedtesting= .ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thre= ad(s). [junit4] 2> NOTE: test params are: codec=3DAsserting(Lucene54): {other= _tl1=3DLucene50(blocksize=3D128), range_facet_l_dv=3DPostingsFormat(name=3D= Asserting), SubjectTerms_mfacet=3DLucene50(blocksize=3D128), n_l1=3DLucene5= 0(blocksize=3D128), multiDefault=3DPostingsFormat(name=3DMockRandom), intDe= fault=3DLucene50(blocksize=3D128), n_td1=3DPostingsFormat(name=3DMockRandom= ), n_d1=3DLucene50(blocksize=3D128), range_facet_l=3DLucene50(blocksize=3D1= 28), n_f1=3DPostingsFormat(name=3DMockRandom), n_tl1=3DPostingsFormat(name= =3DMockRandom), oddField_s=3DFST50, n_tf1=3DLucene50(blocksize=3D128), a_t= =3DLucene50(blocksize=3D128), id=3DPostingsFormat(name=3DAsserting), text= =3DFST50, timestamp=3DLucene50(blocksize=3D128), foo_i=3DPostingsFormat(nam= e=3DMockRandom), n_dt1=3DPostingsFormat(name=3DMockRandom), a_i1=3DLucene50= (blocksize=3D128), n_ti1=3DPostingsFormat(name=3DAsserting), rnd_b=3DPostin= gsFormat(name=3DAsserting), _version_=3DLucene50(blocksize=3D128), foo_b=3D= FST50, n_tdt1=3DLucene50(blocksize=3D128), foo_d=3DPostingsFormat(name=3DAs= serting), range_facet_i_dv=3DLucene50(blocksize=3D128), foo_f=3DFST50}, doc= Values:{range_facet_l_dv=3DDocValuesFormat(name=3DDirect), range_facet_i_dv= =3DDocValuesFormat(name=3DLucene54), timestamp=3DDocValuesFormat(name=3DLuc= ene54)}, sim=3DClassicSimilarity, locale=3Dfa, timezone=3DAfrica/Asmera [junit4] 2> NOTE: Linux 3.19.0-31-generic amd64/Oracle Corporation 1.9= .0-ea (64-bit)/cpus=3D12,threads=3D1,free=3D325141768,total=3D526385152 [junit4] 2> NOTE: All tests run in this JVM: [AssignTest, AnalysisErro= rHandlingTest, AliasIntegrationTest, TestRandomDVFaceting, CursorPagingTest= , SimpleCollectionCreateDeleteTest, HdfsBasicDistributedZk2Test, OutputWrit= erTest, TestSolrXml, TestRTimerTree, DistributedFacetPivotLargeTest, Leader= InitiatedRecoveryOnCommitTest, CurrencyFieldOpenExchangeTest, PathHierarchy= TokenizerFactoryTest, PolyFieldTest, TestSchemaResource, BasicDistributedZk= Test] [junit4] Completed [38/553] on J0 in 25.42s, 1 test, 1 failure <<< FAILU= RES! [...truncated 1623 lines...] BUILD FAILED /home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:775: The followin= g error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:719: The followin= g error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:59: The following= error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:233: The fol= lowing error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:516: = The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:143= 2: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:987= : There were test failures: 553 suites (8 ignored), 2176 tests, 1 failure, = 76 ignored (38 assumptions) [seed: 1F60B6F6759E2BB3] Total time: 47 minutes 18 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_136_1940737641.1446092751035 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_136_1940737641.1446092751035--