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 2C7D618B7A for ; Fri, 17 Jul 2015 16:40:59 +0000 (UTC) Received: (qmail 72486 invoked by uid 500); 17 Jul 2015 16:40:57 -0000 Delivered-To: apmail-lucene-dev-archive@lucene.apache.org Received: (qmail 72427 invoked by uid 500); 17 Jul 2015 16:40:57 -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 72416 invoked by uid 99); 17 Jul 2015 16:40:57 -0000 Received: from Unknown (HELO spamd1-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Fri, 17 Jul 2015 16:40:57 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd1-us-west.apache.org (ASF Mail Server at spamd1-us-west.apache.org) with ESMTP id 9A881D56B1 for ; Fri, 17 Jul 2015 16:40:56 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd1-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 (spamd1-us-west.apache.org [10.40.0.7]) (amavisd-new, port 10024) with ESMTP id gSHXJWZnlr8X for ; Fri, 17 Jul 2015 16:40:49 +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 F33EC22F2D for ; Fri, 17 Jul 2015 16:40:48 +0000 (UTC) Received: from localhost.localdomain ([127.0.0.1] helo=serv1) by serv1 with esmtp (Exim 4.82) (envelope-from ) id 1ZG8gn-0008Vy-Oy for dev@lucene.apache.org; Fri, 17 Jul 2015 16:40:46 +0000 Date: Fri, 17 Jul 2015 16:40:28 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org Message-ID: <1451467913.45.1437151245772.JavaMail.jenkins@serv1> In-Reply-To: <1757616993.41.1437145548083.JavaMail.jenkins@serv1> References: <1757616993.41.1437145548083.JavaMail.jenkins@serv1> Subject: [JENKINS] Lucene-Solr-trunk-Linux (64bit/jdk1.8.0_45) - Build # 13498 - Still Failing! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_44_1286309061.1437151245733" X-Jenkins-Job: Lucene-Solr-trunk-Linux X-Jenkins-Result: FAILURE ------=_Part_44_1286309061.1437151245733 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/13498/ Java: 64bit/jdk1.8.0_45 -XX:-UseCompressedOops -XX:+UseParallelGC 1 tests failed. FAILED: org.apache.solr.cloud.TestCloudPivotFacet.test Error Message: {main(facet=3Dtrue&facet.pivot=3Dpivot_d1%2Cdense_pivot_ti1%2Cpivot_d&facet= .pivot=3D%7B%21stats%3Dst3%7Dpivot_x_s1%2Cpivot_dt%2Cpivot_tf&facet.limit= =3D15&facet.missing=3Dtrue&facet.sort=3Dindex&facet.overrequest.ratio=3D1.1= 039687),extra(rows=3D0&q=3D*%3A*&fq=3Did%3A%5B*+TO+1170%5D&stats=3Dtrue&sta= ts.field=3D%7B%21key%3Dsk1+tag%3Dst1%2Cst2%7Dpivot_tdt1&stats.field=3D%7B%2= 1key%3Dsk2+tag%3Dst2%2Cst3%7Dpivot_tdt&stats.field=3D%7B%21key%3Dsk3+tag%3D= st3%2Cst4%7Dpivot_d1&_test_miss=3Dtrue&_test_sort=3Dindex)} =3D=3D> Sum of = sk3 =3D> pivot_x_s1,pivot_dt,pivot_tf: {params(rows=3D0),defaults({main({ma= in(rows=3D0&q=3D*%3A*&fq=3Did%3A%5B*+TO+1170%5D&stats=3Dtrue&stats.field=3D= %7B%21key%3Dsk1+tag%3Dst1%2Cst2%7Dpivot_tdt1&stats.field=3D%7B%21key%3Dsk2+= tag%3Dst2%2Cst3%7Dpivot_tdt&stats.field=3D%7B%21key%3Dsk3+tag%3Dst3%2Cst4%7= Dpivot_d1&_test_miss=3Dtrue&_test_sort=3Dindex),extra(fq=3D%7B%21term+f%3Dp= ivot_x_s1%7Dc)}),extra(fq=3D%7B%21term+f%3Dpivot_dt%7D2010-10-31T10%3A31%3A= 12Z)})} expected:<-1.2747953316671947E8> but was:<-1.2747953316671945E8> Stack Trace: java.lang.AssertionError: {main(facet=3Dtrue&facet.pivot=3Dpivot_d1%2Cdense= _pivot_ti1%2Cpivot_d&facet.pivot=3D%7B%21stats%3Dst3%7Dpivot_x_s1%2Cpivot_d= t%2Cpivot_tf&facet.limit=3D15&facet.missing=3Dtrue&facet.sort=3Dindex&facet= .overrequest.ratio=3D1.1039687),extra(rows=3D0&q=3D*%3A*&fq=3Did%3A%5B*+TO+= 1170%5D&stats=3Dtrue&stats.field=3D%7B%21key%3Dsk1+tag%3Dst1%2Cst2%7Dpivot_= tdt1&stats.field=3D%7B%21key%3Dsk2+tag%3Dst2%2Cst3%7Dpivot_tdt&stats.field= =3D%7B%21key%3Dsk3+tag%3Dst3%2Cst4%7Dpivot_d1&_test_miss=3Dtrue&_test_sort= =3Dindex)} =3D=3D> Sum of sk3 =3D> pivot_x_s1,pivot_dt,pivot_tf: {params(ro= ws=3D0),defaults({main({main(rows=3D0&q=3D*%3A*&fq=3Did%3A%5B*+TO+1170%5D&s= tats=3Dtrue&stats.field=3D%7B%21key%3Dsk1+tag%3Dst1%2Cst2%7Dpivot_tdt1&stat= s.field=3D%7B%21key%3Dsk2+tag%3Dst2%2Cst3%7Dpivot_tdt&stats.field=3D%7B%21k= ey%3Dsk3+tag%3Dst3%2Cst4%7Dpivot_d1&_test_miss=3Dtrue&_test_sort=3Dindex),e= xtra(fq=3D%7B%21term+f%3Dpivot_x_s1%7Dc)}),extra(fq=3D%7B%21term+f%3Dpivot_= dt%7D2010-10-31T10%3A31%3A12Z)})} expected:<-1.2747953316671947E8> but was:= <-1.2747953316671945E8> =09at __randomizedtesting.SeedInfo.seed([9CE37D1CCF8B99FB:14B742C66177F403]= :0) =09at org.apache.solr.cloud.TestCloudPivotFacet.assertPivotCountsAreCorrect= (TestCloudPivotFacet.java:281) =09at org.apache.solr.cloud.TestCloudPivotFacet.test(TestCloudPivotFacet.ja= va:228) =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:497) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1627) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:836) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:872) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:886) =09at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= FixedStatement.callStatement(BaseDistributedSearchTestCase.java:960) =09at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= Statement.evaluate(BaseDistributedSearchTestCase.java:935) =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:365) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:798) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:458) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:845) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(Random= izedRunner.java:747) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(Random= izedRunner.java:781) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:792) =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:365) =09at java.lang.Thread.run(Thread.java:745) Caused by: java.lang.AssertionError: Sum of sk3 =3D> pivot_x_s1,pivot_dt,pi= vot_tf: {params(rows=3D0),defaults({main({main(rows=3D0&q=3D*%3A*&fq=3Did%3= A%5B*+TO+1170%5D&stats=3Dtrue&stats.field=3D%7B%21key%3Dsk1+tag%3Dst1%2Cst2= %7Dpivot_tdt1&stats.field=3D%7B%21key%3Dsk2+tag%3Dst2%2Cst3%7Dpivot_tdt&sta= ts.field=3D%7B%21key%3Dsk3+tag%3Dst3%2Cst4%7Dpivot_d1&_test_miss=3Dtrue&_te= st_sort=3Dindex),extra(fq=3D%7B%21term+f%3Dpivot_x_s1%7Dc)}),extra(fq=3D%7B= %21term+f%3Dpivot_dt%7D2010-10-31T10%3A31%3A12Z)})} expected:<-1.2747953316= 671947E8> but was:<-1.2747953316671945E8> =09at org.junit.Assert.fail(Assert.java:93) =09at org.junit.Assert.failNotEquals(Assert.java:647) =09at org.junit.Assert.assertEquals(Assert.java:443) =09at org.apache.solr.cloud.TestCloudPivotFacet.assertNumerics(TestCloudPiv= otFacet.java:733) =09at org.apache.solr.cloud.TestCloudPivotFacet.assertPivotStats(TestCloudP= ivotFacet.java:389) =09at org.apache.solr.cloud.TestCloudPivotFacet.assertPivotData(TestCloudPi= votFacet.java:339) =09at org.apache.solr.cloud.TestCloudPivotFacet.assertPivotCountsAreCorrect= (TestCloudPivotFacet.java:302) =09at org.apache.solr.cloud.TestCloudPivotFacet.assertPivotCountsAreCorrect= (TestCloudPivotFacet.java:313) =09at org.apache.solr.cloud.TestCloudPivotFacet.assertPivotCountsAreCorrect= (TestCloudPivotFacet.java:271) =09... 42 more Build Log: [...truncated 10892 lines...] [junit4] Suite: org.apache.solr.cloud.TestCloudPivotFacet [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-trun= k-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestCloudPivotFacet_9C= E37D1CCF8B99FB-001/init-core-data-001 [junit4] 2> 1230916 INFO (SUITE-TestCloudPivotFacet-seed#[9CE37D1CCF8= B99FB]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostConte= xt system property: /ln_/lz [junit4] 2> 1230916 INFO (SUITE-TestCloudPivotFacet-seed#[9CE37D1CCF8= B99FB]-worker) [ ] o.a.s.c.TestCloudPivotFacet init'ing useFieldRandomiz= edFactor =3D 19 [junit4] 2> 1230918 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1230919 INFO (Thread-3762) [ ] o.a.s.c.ZkTestServer cl= ient port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1230919 INFO (Thread-3762) [ ] o.a.s.c.ZkTestServer St= arting server [junit4] 2> 1231019 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.ZkTestServer start zk server on port:35411 [junit4] 2> 1231024 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/L= ucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrc= onfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 1231025 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/L= ucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schem= a.xml to /configs/conf1/schema.xml [junit4] 2> 1231025 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/L= ucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrc= onfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.ra= ndomindexconfig.xml [junit4] 2> 1231026 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/L= ucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/stopw= ords.txt to /configs/conf1/stopwords.txt [junit4] 2> 1231027 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/L= ucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/protw= ords.txt to /configs/conf1/protwords.txt [junit4] 2> 1231027 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/L= ucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/curre= ncy.xml to /configs/conf1/currency.xml [junit4] 2> 1231028 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/L= ucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/enums= Config.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1231028 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/L= ucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/open-= exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 1231029 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/L= ucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/mappi= ng-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1231029 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/L= ucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/old_s= ynonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1231030 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/L= ucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/synon= yms.txt to /configs/conf1/synonyms.txt [junit4] 2> 1231075 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /ho= me/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/t= emp/solr.cloud.TestCloudPivotFacet_9CE37D1CCF8B99FB-001/control-001/cores/c= ollection1 [junit4] 2> 1231076 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 1231077 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextH= andler@4b6b8ef{/ln_/lz,null,AVAILABLE} [junit4] 2> 1231080 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.e.j.s.ServerConnector Started ServerConnector@c5d8356{= HTTP/1.1}{127.0.0.1:58501} [junit4] 2> 1231081 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.e.j.s.Server Started @1232736ms [junit4] 2> 1231081 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data= .dir=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core= /test/J1/temp/solr.cloud.TestCloudPivotFacet_9CE37D1CCF8B99FB-001/tempDir-0= 01/control/data, hostContext=3D/ln_/lz, hostPort=3D58501, coreRootDirectory= =3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/tes= t/J1/temp/solr.cloud.TestCloudPivotFacet_9CE37D1CCF8B99FB-001/control-001/c= ores} [junit4] 2> 1231081 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): su= n.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1231081 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for di= rectory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-c= ore/test/J1/temp/solr.cloud.TestCloudPivotFacet_9CE37D1CCF8B99FB-001/contro= l-001/' [junit4] 2> 1231090 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration fr= om /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/tes= t/J1/temp/solr.cloud.TestCloudPivotFacet_9CE37D1CCF8B99FB-001/control-001/s= olr.xml [junit4] 2> 1231093 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.CoresLocator Config-defined core root directory:= /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/= J1/temp/solr.cloud.TestCloudPivotFacet_9CE37D1CCF8B99FB-001/control-001/cor= es [junit4] 2> 1231094 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.CoreContainer New CoreContainer 823000380 [junit4] 2> 1231094 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [= instanceDir=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/so= lr-core/test/J1/temp/solr.cloud.TestCloudPivotFacet_9CE37D1CCF8B99FB-001/co= ntrol-001/] [junit4] 2> 1231094 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.CoreContainer loading shared library: /home/jenk= ins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/sol= r.cloud.TestCloudPivotFacet_9CE37D1CCF8B99FB-001/control-001/lib [junit4] 2> 1231094 WARN (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) director= y to add to classloader: lib (resolved as: /home/jenkins/workspace/Lucene-S= olr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestCloudPivot= Facet_9CE37D1CCF8B99FB-001/control-001/lib). [junit4] 2> 1231098 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTim= eout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,ma= xConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThrea= dIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 1231099 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler H= TTP client with params: socketTimeout=3D340000&connTimeout=3D45000&retry=3D= true [junit4] 2> 1231099 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLog= gerFactory [junit4] 2> 1231099 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.= slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1231099 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.ZkContainer Zookeeper client=3D127.0.0.1:35411/s= olr [junit4] 2> 1231099 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1231106 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.ZkController Register = node as live in ZooKeeper:/live_nodes/127.0.0.1:58501_ln_%2Flz [junit4] 2> 1231107 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.Overseer Overseer (id= =3Dnull) closing [junit4] 2> 1231107 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.LeaderElector Joined l= eadership election with path: /overseer_elect/election/94185121059045379-12= 7.0.0.1:58501_ln_%2Flz-n_0000000000 [junit4] 2> 1231108 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.OverseerElectionContex= t I am going to be the leader 127.0.0.1:58501_ln_%2Flz [junit4] 2> 1231108 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.Overseer Overseer (id= =3D94185121059045379-127.0.0.1:58501_ln_%2Flz-n_0000000000) starting [junit4] 2> 1231110 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.OverseerAutoReplicaFai= loverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverW= orkLoopDelay=3D10000 autoReplicaFailoverWaitAfterExpiration=3D30000 autoRep= licaFailoverBadNodeExpiration=3D60000 [junit4] 2> 1231111 INFO (OverseerCollectionProcessor-941851210590453= 79-127.0.0.1:58501_ln_%2Flz-n_0000000000) [n:127.0.0.1:58501_ln_%2Flz ] = o.a.s.c.OverseerCollectionProcessor Process current queue of collection cre= ations [junit4] 2> 1231111 INFO (OverseerStateUpdate-94185121059045379-127.0= .0.1:58501_ln_%2Flz-n_0000000000) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.= Overseer Starting to work on the main queue [junit4] 2> 1231112 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.CoreContainer No authe= ntication plugin used. [junit4] 2> 1231112 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.CoreContainer Security= conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1231113 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.CoresLocator Looking f= or core definitions underneath /home/jenkins/workspace/Lucene-Solr-trunk-Li= nux/solr/build/solr-core/test/J1/temp/solr.cloud.TestCloudPivotFacet_9CE37D= 1CCF8B99FB-001/control-001/cores [junit4] 2> 1231113 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.SolrCore Created CoreD= escriptor: {name=3Dcollection1, config=3Dsolrconfig.xml, transient=3Dfalse,= schema=3Dschema.xml, loadOnStartup=3Dtrue, configSetProperties=3Dconfigset= props.json, instanceDir=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/build/solr-core/test/J1/temp/solr.cloud.TestCloudPivotFacet_9CE37D1CCF8= B99FB-001/control-001/cores/collection1, collection=3Dcontrol_collection, a= bsoluteInstDir=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build= /solr-core/test/J1/temp/solr.cloud.TestCloudPivotFacet_9CE37D1CCF8B99FB-001= /control-001/cores/collection1/, coreNodeName=3D, dataDir=3Ddata/, shard=3D= } [junit4] 2> 1231113 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.CoresLocator Found cor= e collection1 in /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build= /solr-core/test/J1/temp/solr.cloud.TestCloudPivotFacet_9CE37D1CCF8B99FB-001= /control-001/cores/collection1/ [junit4] 2> 1231113 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.CoresLocator Found 1 c= ore definitions [junit4] 2> 1231114 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= x:collection1] o.a.s.c.ZkController publishing state=3Ddown [junit4] 2> 1231114 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.s.SolrDispatchFilter use= r.dir=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-cor= e/test/J1 [junit4] 2> 1231114 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.s.SolrDispatchFilter Sol= rDispatchFilter.init() done [junit4] 2> 1231114 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= x:collection1] o.a.s.c.ZkController numShards not found on descriptor - = reading it from system property [junit4] 2> 1231114 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1231114 INFO (zkCallback-940-thread-1-processing-n:127.0.= 0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.DistributedQue= ue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1231115 INFO (OverseerStateUpdate-94185121059045379-127.0= .0.1:58501_ln_%2Flz-n_0000000000) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.= Overseer processMessage: queueSize: 1, message =3D { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:58501/ln_/lz", [junit4] 2> "node_name":"127.0.0.1:58501_ln_%2Flz", [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> 1231115 INFO (OverseerStateUpdate-94185121059045379-127.0= .0.1:58501_ln_%2Flz-n_0000000000) [n:127.0.0.1:58501_ln_%2Flz ] 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:58501/ln_/lz", [junit4] 2> "node_name":"127.0.0.1:58501_ln_%2Flz", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1231115 INFO (OverseerStateUpdate-94185121059045379-127.0= .0.1:58501_ln_%2Flz-n_0000000000) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.= o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 1231115 INFO (OverseerStateUpdate-94185121059045379-127.0= .0.1:58501_ln_%2Flz-n_0000000000) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.= o.ReplicaMutator Assigning new node to shard shard=3Dshard1 [junit4] 2> 1232115 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= x:collection1] o.a.s.c.ZkController waiting to find shard id in clusters= tate for collection1 [junit4] 2> 1232115 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_= collection [junit4] 2> 1232115 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1232115 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for dir= ectory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-co= re/test/J1/temp/solr.cloud.TestCloudPivotFacet_9CE37D1CCF8B99FB-001/control= -001/cores/collection1/' [junit4] 2> 1232122 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version = 0=20 [junit4] 2> 1232124 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1232133 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1232139 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 1232140 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/con= f1/schema.xml [junit4] 2> 1232142 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=3Dtest [junit4] 2> 1232218 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rat= es=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4] 2> 1232222 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= x:collection1] o.a.s.s.IndexSchema default search field in schema is tex= t [junit4] 2> 1232223 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1232226 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates= from file currency.xml [junit4] 2> 1232227 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates= from file currency.xml [junit4] 2> 1232228 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange r= ates from open-exchange-rates.json [junit4] 2> 1232228 WARN (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTAN= T NOTE [junit4] 2> 1232229 WARN (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got ST= RING [junit4] 2> 1232229 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange r= ates from open-exchange-rates.json [junit4] 2> 1232229 WARN (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTAN= T NOTE [junit4] 2> 1232229 WARN (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got ST= RING [junit4] 2> 1232230 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet proper= ties [junit4] 2> org.apache.solr.core.SolrResourceNotFoundException: Can't = find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd= =3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/tes= t/J1 [junit4] 2> =09at org.apache.solr.cloud.ZkSolrResourceLoader.openResou= rce(ZkSolrResourceLoader.java:99) [junit4] 2> =09at org.apache.solr.core.ConfigSetProperties.readFromRes= ourceLoader(ConfigSetProperties.java:49) [junit4] 2> =09at org.apache.solr.core.ConfigSetService.createConfigSe= tProperties(ConfigSetService.java:114) [junit4] 2> =09at org.apache.solr.core.ConfigSetService.getConfig(Conf= igSetService.java:76) [junit4] 2> =09at org.apache.solr.core.CoreContainer.create(CoreContai= ner.java:668) [junit4] 2> =09at org.apache.solr.core.CoreContainer$1.call(CoreContai= ner.java:397) [junit4] 2> =09at org.apache.solr.core.CoreContainer$1.call(CoreContai= ner.java:388) [junit4] 2> =09at java.util.concurrent.FutureTask.run(FutureTask.java:= 266) [junit4] 2> =09at org.apache.solr.common.util.ExecutorUtil$MDCAwareThr= eadPoolExecutor$1.run(ExecutorUtil.java:156) [junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor.runWorker(Th= readPoolExecutor.java:1142) [junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor$Worker.run(T= hreadPoolExecutor.java:617) [junit4] 2> =09at java.lang.Thread.run(Thread.java:745) [junit4] 2> 1232230 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' usi= ng configuration from collection control_collection [junit4] 2> 1232232 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core= .MockDirectoryFactory [junit4] 2> 1232232 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Ope= ning new SolrCore at [/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/= build/solr-core/test/J1/temp/solr.cloud.TestCloudPivotFacet_9CE37D1CCF8B99F= B-001/control-001/cores/collection1/], dataDir=3D[null] [junit4] 2> 1232232 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitorin= g is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.Jmx= MBeanServer@56760817 [junit4] 2> 1232233 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory retur= n new directory for /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/bu= ild/solr-core/test/J1/temp/solr.cloud.TestCloudPivotFacet_9CE37D1CCF8B99FB-= 001/control-001/cores/collection1/data [junit4] 2> 1232233 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory = detected: old=3Dnull new=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/= solr/build/solr-core/test/J1/temp/solr.cloud.TestCloudPivotFacet_9CE37D1CCF= 8B99FB-001/control-001/cores/collection1/data/index/ [junit4] 2> 1232233 WARN (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr i= ndex directory '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/= solr-core/test/J1/temp/solr.cloud.TestCloudPivotFacet_9CE37D1CCF8B99FB-001/= control-001/cores/collection1/data/index' doesn't exist. Creating new index= ... [junit4] 2> 1232233 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory retur= n new directory for /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/bu= ild/solr-core/test/J1/temp/solr.cloud.TestCloudPivotFacet_9CE37D1CCF8B99FB-= 001/control-001/cores/collection1/data/index [junit4] 2> 1232233 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMerge= Policy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogB= yteSizeMergePolicy: minMergeSize=3D1677721, mergeFactor=3D39, maxMergeSize= =3D2147483648, maxMergeSizeForForcedMerge=3D9223372036854775807, calibrateS= izeByDeletes=3Dtrue, maxMergeDocs=3D2147483647, maxCFSSegmentSizeMB=3D8.796= 093022207999E12, noCFSRatio=3D0.38847940073839893] [junit4] 2> 1232234 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.o= nCommit: commits: num=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@2d9cb37e= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@58cef3f),s= egFN=3Dsegments_1,generation=3D1} [junit4] 2> 1232234 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit genera= tion =3D 1 [junit4] 2> 1232234 INFO (OldIndexDirectoryCleanupThreadForCore-colle= ction1) [n:127.0.0.1:58501_ln_%2Flz c:control_collection s:shard1 r:core_no= de1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cl= eanup for core collection1 in /home/jenkins/workspace/Lucene-Solr-trunk-Lin= ux/solr/build/solr-core/test/J1/temp/solr.cloud.TestCloudPivotFacet_9CE37D1= CCF8B99FB-001/control-001/cores/collection1/data/ [junit4] 2> 1232235 WARN (OldIndexDirectoryCleanupThreadForCore-colle= ction1) [n:127.0.0.1:58501_ln_%2Flz c:control_collection s:shard1 r:core_no= de1 x:collection1] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-= Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestCloudPivo= tFacet_9CE37D1CCF8B99FB-001/control-001/cores/collection1/data/ does not po= int to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 1232236 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain= creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1232236 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain= creating updateRequestProcessorChain "dedupe" [junit4] 2> 1232237 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain= inserting DistributedUpdateProcessorFactory into updateRequestProcessorCha= in "dedupe" [junit4] 2> 1232237 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain= creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1232237 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain= inserting DistributedUpdateProcessorFactory into updateRequestProcessorCha= in "stored_sig" [junit4] 2> 1232237 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain= creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1232237 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain= creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1232237 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain= inserting DistributedUpdateProcessorFactory into updateRequestProcessorCha= in "distrib-dup-test-chain-implicit" [junit4] 2> 1232237 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore no updateRequestProc= essorChain defined as default, creating implicit default [junit4] 2> 1232238 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetime= Seconds=3D60 [junit4] 2> 1232239 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetime= Seconds=3D60 [junit4] 2> 1232239 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetime= Seconds=3D60 [junit4] 2> 1232240 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetime= Seconds=3D60 [junit4] 2> 1232242 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered pa= ths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admi= n/segments,/get,/admin/system,/replication,/admin/properties,/config,/schem= a,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/up= date,/admin/file [junit4] 2> 1232242 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Using default statsC= ache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 1232242 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog= implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1232243 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Initializing Update= Log: dataDir=3D defaultSyncLevel=3DFLUSH numRecordsToKeep=3D100 maxNumLogsT= oKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 1232243 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: dis= abled [junit4] 2> 1232243 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: dis= abled [junit4] 2> 1232244 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMerge= Policy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMer= gePolicy: maxMergeAtOnce=3D15, maxMergeAtOnceExplicit=3D21, maxMergedSegmen= tMB=3D19.9697265625, floorSegmentMB=3D0.400390625, forceMergeDeletesPctAllo= wed=3D29.482463469247342, segmentsPerTier=3D20.0, maxCFSSegmentSizeMB=3D8.7= 96093022207999E12, noCFSRatio=3D0.0 [junit4] 2> 1232244 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.o= nInit: commits: num=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@2d9cb37e= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@58cef3f),s= egFN=3Dsegments_1,generation=3D1} [junit4] 2> 1232244 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit genera= tion =3D 1 [junit4] 2> 1232244 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Sea= rcher@5c9c6821[collection1] main [junit4] 2> 1232245 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Settin= g up ZooKeeper-based storage for the RestManager with znodeBase: /configs/c= onf1 [junit4] 2> 1232245 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Config= ured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1232245 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing Rest= Manager with initArgs: {} [junit4] 2> 1232245 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Readin= g _rest_managed.json using ZooKeeperStorageIO:path=3D/configs/conf1 [junit4] 2> 1232245 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage No dat= a found for znode /configs/conf1/_rest_managed.json [junit4] 2> 1232245 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Loaded= null at path _rest_managed.json using ZooKeeperStorageIO:path=3D/configs/c= onf1 [junit4] 2> 1232245 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 re= gistered ManagedResources [junit4] 2> 1232246 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits wi= ll be reserved for 10000 [junit4] 2> 1232246 INFO (searcherExecutor-4328-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz x:collection1 s:shard1 c:control_collection r:cor= e_node1) [n:127.0.0.1:58501_ln_%2Flz c:control_collection s:shard1 r:core_n= ode1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher = Searcher@5c9c6821[collection1] main{ExitableDirectoryReader(UninvertingDire= ctoryReader())} [junit4] 2> 1232246 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Looking up max valu= e of version field to seed version buckets [junit4] 2> 1232246 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo Refreshing highes= t value of _version_ for 65536 version buckets from index [junit4] 2> 1232246 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo No terms found fo= r _version_, cannot seed version bucket highest value from index [junit4] 2> 1232246 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Could not find max = version in index or recent updates, using new clock 1506961938335137792 [junit4] 2> 1232248 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 2 ms to seed v= ersion buckets with highest version 1506961938335137792 [junit4] 2> 1232248 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /con= figs/conf1 [junit4] 2> 1232249 INFO (coreLoadExecutor-4327-thread-1-processing-n= :127.0.0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz c:control_collection= s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering cor= e: collection1 [junit4] 2> 1232249 INFO (coreZkRegister-4321-thread-1-processing-n:1= 27.0.0.1:58501_ln_%2Flz x:collection1 s:shard1 c:control_collection r:core_= node1) [n:127.0.0.1:58501_ln_%2Flz c:control_collection s:shard1 r:core_nod= e1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 = address:http://127.0.0.1:58501/ln_/lz collection:control_collection shard:s= hard1 [junit4] 2> 1232250 INFO (coreZkRegister-4321-thread-1-processing-n:1= 27.0.0.1:58501_ln_%2Flz x:collection1 s:shard1 c:control_collection r:core_= node1) [n:127.0.0.1:58501_ln_%2Flz c:control_collection s:shard1 r:core_nod= e1 x:collection1] o.a.s.c.LeaderElector Joined leadership election with pat= h: /collections/control_collection/leader_elect/shard1/election/94185121059= 045379-core_node1-n_0000000000 [junit4] 2> 1232251 INFO (coreZkRegister-4321-thread-1-processing-n:1= 27.0.0.1:58501_ln_%2Flz x:collection1 s:shard1 c:control_collection r:core_= node1) [n:127.0.0.1:58501_ln_%2Flz c:control_collection s:shard1 r:core_nod= e1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader pro= cess for shard shard1 [junit4] 2> 1232252 INFO (zkCallback-940-thread-1-processing-n:127.0.= 0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.DistributedQue= ue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1232252 INFO (coreZkRegister-4321-thread-1-processing-n:1= 27.0.0.1:58501_ln_%2Flz x:collection1 s:shard1 c:control_collection r:core_= node1) [n:127.0.0.1:58501_ln_%2Flz c:control_collection s:shard1 r:core_nod= e1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found = to continue. [junit4] 2> 1232252 INFO (coreZkRegister-4321-thread-1-processing-n:1= 27.0.0.1:58501_ln_%2Flz x:collection1 s:shard1 c:control_collection r:core_= node1) [n:127.0.0.1:58501_ln_%2Flz c:control_collection s:shard1 r:core_nod= e1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leade= r - try and sync [junit4] 2> 1232252 INFO (OverseerStateUpdate-94185121059045379-127.0= .0.1:58501_ln_%2Flz-n_0000000000) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.= Overseer processMessage: queueSize: 1, message =3D { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection"} current state version= : 1 [junit4] 2> 1232252 INFO (coreZkRegister-4321-thread-1-processing-n:1= 27.0.0.1:58501_ln_%2Flz x:collection1 s:shard1 c:control_collection r:core_= node1) [n:127.0.0.1:58501_ln_%2Flz c:control_collection s:shard1 r:core_nod= e1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:58= 501/ln_/lz/collection1/ [junit4] 2> 1232252 INFO (coreZkRegister-4321-thread-1-processing-n:1= 27.0.0.1:58501_ln_%2Flz x:collection1 s:shard1 c:control_collection r:core_= node1) [n:127.0.0.1:58501_ln_%2Flz c:control_collection s:shard1 r:core_nod= e1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to = me [junit4] 2> 1232252 INFO (coreZkRegister-4321-thread-1-processing-n:1= 27.0.0.1:58501_ln_%2Flz x:collection1 s:shard1 c:control_collection r:core_= node1) [n:127.0.0.1:58501_ln_%2Flz c:control_collection s:shard1 r:core_nod= e1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:58501/ln_/lz/collec= tion1/ has no replicas [junit4] 2> 1232252 INFO (coreZkRegister-4321-thread-1-processing-n:1= 27.0.0.1:58501_ln_%2Flz x:collection1 s:shard1 c:control_collection r:core_= node1) [n:127.0.0.1:58501_ln_%2Flz c:control_collection s:shard1 r:core_nod= e1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: h= ttp://127.0.0.1:58501/ln_/lz/collection1/ shard1 [junit4] 2> 1232253 INFO (zkCallback-940-thread-1-processing-n:127.0.= 0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.DistributedQue= ue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1232254 INFO (OverseerStateUpdate-94185121059045379-127.0= .0.1:58501_ln_%2Flz-n_0000000000) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.= Overseer processMessage: 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:58501/ln_/lz", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 1232404 INFO (coreZkRegister-4321-thread-1-processing-n:1= 27.0.0.1:58501_ln_%2Flz x:collection1 s:shard1 c:control_collection r:core_= node1) [n:127.0.0.1:58501_ln_%2Flz c:control_collection s:shard1 r:core_nod= e1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:58501/ln_/lz= /collection1/ and leader is http://127.0.0.1:58501/ln_/lz/collection1/ [junit4] 2> 1232404 INFO (coreZkRegister-4321-thread-1-processing-n:1= 27.0.0.1:58501_ln_%2Flz x:collection1 s:shard1 c:control_collection r:core_= node1) [n:127.0.0.1:58501_ln_%2Flz c:control_collection s:shard1 r:core_nod= e1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=3Dcolle= ction1 baseURL=3Dhttp://127.0.0.1:58501/ln_/lz [junit4] 2> 1232404 INFO (coreZkRegister-4321-thread-1-processing-n:1= 27.0.0.1:58501_ln_%2Flz x:collection1 s:shard1 c:control_collection r:core_= node1) [n:127.0.0.1:58501_ln_%2Flz c:control_collection s:shard1 r:core_nod= e1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessa= ry [junit4] 2> 1232404 INFO (coreZkRegister-4321-thread-1-processing-n:1= 27.0.0.1:58501_ln_%2Flz x:collection1 s:shard1 c:control_collection r:core_= node1) [n:127.0.0.1:58501_ln_%2Flz c:control_collection s:shard1 r:core_nod= e1 x:collection1] o.a.s.c.ZkController publishing state=3Dactive [junit4] 2> 1232404 INFO (coreZkRegister-4321-thread-1-processing-n:1= 27.0.0.1:58501_ln_%2Flz x:collection1 s:shard1 c:control_collection r:core_= node1) [n:127.0.0.1:58501_ln_%2Flz c:control_collection s:shard1 r:core_nod= e1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - = reading it from system property [junit4] 2> 1232405 INFO (zkCallback-940-thread-1-processing-n:127.0.= 0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.DistributedQue= ue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1232405 INFO (OverseerStateUpdate-94185121059045379-127.0= .0.1:58501_ln_%2Flz-n_0000000000) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.= Overseer processMessage: 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:58501/ln_/lz", [junit4] 2> "node_name":"127.0.0.1:58501_ln_%2Flz", [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> 1232406 INFO (OverseerStateUpdate-94185121059045379-127.0= .0.1:58501_ln_%2Flz-n_0000000000) [n:127.0.0.1:58501_ln_%2Flz ] 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:58501/ln_/lz", [junit4] 2> "node_name":"127.0.0.1:58501_ln_%2Flz", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1232617 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false= cause connection loss:false [junit4] 2> 1232617 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collectio= n1 with stateFormat=3D2 [junit4] 2> 1232619 INFO (zkCallback-940-thread-1-processing-n:127.0.= 0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.DistributedQue= ue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1232619 INFO (OverseerStateUpdate-94185121059045379-127.0= .0.1:58501_ln_%2Flz-n_0000000000) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.= Overseer processMessage: queueSize: 1, message =3D { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "stateFormat":"2"} current state version: 3 [junit4] 2> 1232619 INFO (OverseerStateUpdate-94185121059045379-127.0= .0.1:58501_ln_%2Flz-n_0000000000) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.= o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 1232663 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /ho= me/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/t= emp/solr.cloud.TestCloudPivotFacet_9CE37D1CCF8B99FB-001/shard-1-001/cores/c= ollection1 [junit4] 2> 1232663 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in = directory /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-c= ore/test/J1/temp/solr.cloud.TestCloudPivotFacet_9CE37D1CCF8B99FB-001/shard-= 1-001 [junit4] 2> 1232664 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 1232664 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextH= andler@7209d435{/ln_/lz,null,AVAILABLE} [junit4] 2> 1232665 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.e.j.s.ServerConnector Started ServerConnector@2ba87594= {HTTP/1.1}{127.0.0.1:51086} [junit4] 2> 1232665 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.e.j.s.Server Started @1234320ms [junit4] 2> 1232665 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data= .dir=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core= /test/J1/temp/solr.cloud.TestCloudPivotFacet_9CE37D1CCF8B99FB-001/tempDir-0= 01/jetty1, solrconfig=3Dsolrconfig.xml, hostContext=3D/ln_/lz, hostPort=3D5= 1086, coreRootDirectory=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspa= ce/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.Tes= tCloudPivotFacet_9CE37D1CCF8B99FB-001/shard-1-001/cores} [junit4] 2> 1232665 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): su= n.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1232665 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for di= rectory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-c= ore/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-t= runk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestCloudPivotFacet= _9CE37D1CCF8B99FB-001/shard-1-001/' [junit4] 2> 1232673 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration fr= om /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/tes= t/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Li= nux/solr/build/solr-core/test/J1/temp/solr.cloud.TestCloudPivotFacet_9CE37D= 1CCF8B99FB-001/shard-1-001/solr.xml [junit4] 2> 1232676 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.CoresLocator Config-defined core root directory:= /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/= J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linu= x/solr/build/solr-core/test/J1/temp/solr.cloud.TestCloudPivotFacet_9CE37D1C= CF8B99FB-001/shard-1-001/cores [junit4] 2> 1232677 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.CoreContainer New CoreContainer 89986271 [junit4] 2> 1232677 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [= instanceDir=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/so= lr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-So= lr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestCloudPivotF= acet_9CE37D1CCF8B99FB-001/shard-1-001/] [junit4] 2> 1232677 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.CoreContainer loading shared library: /home/jenk= ins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../..= /../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/buil= d/solr-core/test/J1/temp/solr.cloud.TestCloudPivotFacet_9CE37D1CCF8B99FB-00= 1/shard-1-001/lib [junit4] 2> 1232677 WARN (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) director= y to add to classloader: lib (resolved as: /home/jenkins/workspace/Lucene-S= olr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../hom= e/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/te= mp/solr.cloud.TestCloudPivotFacet_9CE37D1CCF8B99FB-001/shard-1-001/lib). [junit4] 2> 1232681 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTim= eout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,ma= xConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThrea= dIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 1232681 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler H= TTP client with params: socketTimeout=3D340000&connTimeout=3D45000&retry=3D= true [junit4] 2> 1232682 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLog= gerFactory [junit4] 2> 1232682 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.= slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1232682 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.ZkContainer Zookeeper client=3D127.0.0.1:35411/s= olr [junit4] 2> 1232682 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1232720 INFO (OverseerStateUpdate-94185121059045379-127.0= .0.1:58501_ln_%2Flz-n_0000000000) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.= o.ZkStateWriter going to create_collection /collections/collection1/state.j= son [junit4] 2> 1233686 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [n:127.0.0.1:51086_ln_%2Flz ] o.a.s.c.ZkController Register = node as live in ZooKeeper:/live_nodes/127.0.0.1:51086_ln_%2Flz [junit4] 2> 1233687 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [n:127.0.0.1:51086_ln_%2Flz ] o.a.s.c.Overseer Overseer (id= =3Dnull) closing [junit4] 2> 1233688 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [n:127.0.0.1:51086_ln_%2Flz ] o.a.s.c.LeaderElector Joined l= eadership election with path: /overseer_elect/election/94185121059045383-12= 7.0.0.1:51086_ln_%2Flz-n_0000000001 [junit4] 2> 1233688 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [n:127.0.0.1:51086_ln_%2Flz ] o.a.s.c.LeaderElector Watching= path /overseer_elect/election/94185121059045379-127.0.0.1:58501_ln_%2Flz-n= _0000000000 to know if I could be the leader [junit4] 2> 1233688 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [n:127.0.0.1:51086_ln_%2Flz ] o.a.s.c.CoreContainer No authe= ntication plugin used. [junit4] 2> 1233688 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [n:127.0.0.1:51086_ln_%2Flz ] o.a.s.c.CoreContainer Security= conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1233689 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [n:127.0.0.1:51086_ln_%2Flz ] o.a.s.c.CoresLocator Looking f= or core definitions underneath /home/jenkins/workspace/Lucene-Solr-trunk-Li= nux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/wo= rkspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.clou= d.TestCloudPivotFacet_9CE37D1CCF8B99FB-001/shard-1-001/cores [junit4] 2> 1233689 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [n:127.0.0.1:51086_ln_%2Flz ] o.a.s.c.SolrCore Created CoreD= escriptor: {name=3Dcollection1, config=3Dsolrconfig.xml, transient=3Dfalse,= schema=3Dschema.xml, loadOnStartup=3Dtrue, configSetProperties=3Dconfigset= props.json, instanceDir=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspa= ce/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.Tes= tCloudPivotFacet_9CE37D1CCF8B99FB-001/shard-1-001/cores/collection1, collec= tion=3Dcollection1, absoluteInstDir=3D/home/jenkins/workspace/Lucene-Solr-t= runk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jen= kins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/so= lr.cloud.TestCloudPivotFacet_9CE37D1CCF8B99FB-001/shard-1-001/cores/collect= ion1/, coreNodeName=3D, dataDir=3Ddata/, shard=3D} [junit4] 2> 1233689 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [n:127.0.0.1:51086_ln_%2Flz ] o.a.s.c.CoresLocator Found cor= e collection1 in /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build= /solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene= -Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestCloudPiv= otFacet_9CE37D1CCF8B99FB-001/shard-1-001/cores/collection1/ [junit4] 2> 1233689 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [n:127.0.0.1:51086_ln_%2Flz ] o.a.s.c.CoresLocator Found 1 c= ore definitions [junit4] 2> 1233690 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [n:127.0.0.1:51086_ln_%2Flz ] o.a.s.s.SolrDispatchFilter use= r.dir=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-cor= e/test/J1 [junit4] 2> 1233690 INFO (TEST-TestCloudPivotFacet.test-seed#[9CE37D1= CCF8B99FB]) [n:127.0.0.1:51086_ln_%2Flz ] o.a.s.s.SolrDispatchFilter Sol= rDispatchFilter.init() done [junit4] 2> 1233690 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 x:co= llection1] o.a.s.c.ZkController publishing state=3Ddown [junit4] 2> 1233690 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 x:co= llection1] o.a.s.c.ZkController numShards not found on descriptor - reading= it from system property [junit4] 2> 1233691 INFO (zkCallback-940-thread-1-processing-n:127.0.= 0.1:58501_ln_%2Flz) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.DistributedQue= ue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1233691 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 x:co= llection1] o.a.s.c.ZkController Registering watch for external collection c= ollection1 [junit4] 2> 1233691 INFO (OverseerStateUpdate-94185121059045379-127.0= .0.1:58501_ln_%2Flz-n_0000000000) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.= Overseer processMessage: queueSize: 1, message =3D { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51086/ln_/lz", [junit4] 2> "node_name":"127.0.0.1:51086_ln_%2Flz", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 4 [junit4] 2> 1233692 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 x:co= llection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1233692 INFO (OverseerStateUpdate-94185121059045379-127.0= .0.1:58501_ln_%2Flz-n_0000000000) [n:127.0.0.1:58501_ln_%2Flz ] 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:51086/ln_/lz", [junit4] 2> "node_name":"127.0.0.1:51086_ln_%2Flz", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 1233692 INFO (OverseerStateUpdate-94185121059045379-127.0= .0.1:58501_ln_%2Flz-n_0000000000) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.= o.ReplicaMutator Collection already exists with numShards=3D2 [junit4] 2> 1233692 INFO (OverseerStateUpdate-94185121059045379-127.0= .0.1:58501_ln_%2Flz-n_0000000000) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.= o.ReplicaMutator Assigning new node to shard shard=3Dshard2 [junit4] 2> 1233793 INFO (OverseerStateUpdate-94185121059045379-127.0= .0.1:58501_ln_%2Flz-n_0000000000) [n:127.0.0.1:58501_ln_%2Flz ] o.a.s.c.= o.ZkStateWriter going to update_collection /collections/collection1/state.j= son version: 0 [junit4] 2> 1234692 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 x:co= llection1] o.a.s.c.ZkController waiting to find shard id in clusterstate fo= r collection1 [junit4] 2> 1234692 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 x:co= llection1] o.a.s.c.ZkController Check for collection zkNode:collection1 [junit4] 2> 1234692 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 x:co= llection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1234692 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 x:co= llection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:= '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test= /J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Lin= ux/solr/build/solr-core/test/J1/temp/solr.cloud.TestCloudPivotFacet_9CE37D1= CCF8B99FB-001/shard-1-001/cores/collection1/' [junit4] 2> 1234699 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 x:co= llection1] o.a.s.c.Config loaded config solrconfig.xml with version 0=20 [junit4] 2> 1234702 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 x:co= llection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1234705 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 x:co= llection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1234717 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 x:co= llection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 1234718 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 x:co= llection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/sche= ma.xml [junit4] 2> 1234721 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 x:co= llection1] o.a.s.s.IndexSchema [collection1] Schema name=3Dtest [junit4] 2> 1234794 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 x:co= llection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=3Dop= en-exchange-rates.json, refreshInterval=3D1440. [junit4] 2> 1234798 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 x:co= llection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1234798 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 x:co= llection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1234801 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 x:co= llection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from f= ile currency.xml [junit4] 2> 1234803 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 x:co= llection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from f= ile currency.xml [junit4] 2> 1234804 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 x:co= llection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates fr= om open-exchange-rates.json [junit4] 2> 1234804 WARN (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 x:co= llection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1234804 WARN (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 x:co= llection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1234804 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 x:co= llection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates fr= om open-exchange-rates.json [junit4] 2> 1234804 WARN (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 x:co= llection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1234805 WARN (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 x:co= llection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1234805 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 x:co= llection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties [junit4] 2> org.apache.solr.core.SolrResourceNotFoundException: Can't = find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd= =3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/tes= t/J1 [junit4] 2> =09at org.apache.solr.cloud.ZkSolrResourceLoader.openResou= rce(ZkSolrResourceLoader.java:99) [junit4] 2> =09at org.apache.solr.core.ConfigSetProperties.readFromRes= ourceLoader(ConfigSetProperties.java:49) [junit4] 2> =09at org.apache.solr.core.ConfigSetService.createConfigSe= tProperties(ConfigSetService.java:114) [junit4] 2> =09at org.apache.solr.core.ConfigSetService.getConfig(Conf= igSetService.java:76) [junit4] 2> =09at org.apache.solr.core.CoreContainer.create(CoreContai= ner.java:668) [junit4] 2> =09at org.apache.solr.core.CoreContainer$1.call(CoreContai= ner.java:397) [junit4] 2> =09at org.apache.solr.core.CoreContainer$1.call(CoreContai= ner.java:388) [junit4] 2> =09at java.util.concurrent.FutureTask.run(FutureTask.java:= 266) [junit4] 2> =09at org.apache.solr.common.util.ExecutorUtil$MDCAwareThr= eadPoolExecutor$1.run(ExecutorUtil.java:156) [junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor.runWorker(Th= readPoolExecutor.java:1142) [junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor$Worker.run(T= hreadPoolExecutor.java:617) [junit4] 2> =09at java.lang.Thread.run(Thread.java:745) [junit4] 2> 1234805 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 x:co= llection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using conf= iguration from collection collection1 [junit4] 2> 1234805 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDi= rectoryFactory [junit4] 2> 1234806 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening ne= w SolrCore at [/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/s= olr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-S= olr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestCloudPivot= Facet_9CE37D1CCF8B99FB-001/shard-1-001/cores/collection1/], dataDir=3D[null= ] [junit4] 2> 1234806 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is en= abled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanSe= rver@56760817 [junit4] 2> 1234806 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new d= irectory for /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/sol= r-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Sol= r-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestCloudPivotFa= cet_9CE37D1CCF8B99FB-001/shard-1-001/cores/collection1/data [junit4] 2> 1234806 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detecte= d: old=3Dnull new=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/bu= ild/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Luc= ene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestCloud= PivotFacet_9CE37D1CCF8B99FB-001/shard-1-001/cores/collection1/data/index/ [junit4] 2> 1234806 WARN (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index di= rectory '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-co= re/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-tr= unk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestCloudPivotFacet_= 9CE37D1CCF8B99FB-001/shard-1-001/cores/collection1/data/index' doesn't exis= t. Creating new index... [junit4] 2> 1234807 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new d= irectory for /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/sol= r-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Sol= r-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestCloudPivotFa= cet_9CE37D1CCF8B99FB-001/shard-1-001/cores/collection1/data/index [junit4] 2> 1234807 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy = wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSize= MergePolicy: minMergeSize=3D1677721, mergeFactor=3D39, maxMergeSize=3D21474= 83648, maxMergeSizeForForcedMerge=3D9223372036854775807, calibrateSizeByDel= etes=3Dtrue, maxMergeDocs=3D2147483647, maxCFSSegmentSizeMB=3D8.79609302220= 7999E12, noCFSRatio=3D0.38847940073839893] [junit4] 2> 1234807 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit= : commits: num=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@133212ba= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@77135b33),= segFN=3Dsegments_1,generation=3D1} [junit4] 2> 1234807 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = =3D 1 [junit4] 2> 1234807 INFO (OldIndexDirectoryCleanupThreadForCore-colle= ction1) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shard2 r:core_node1 x:c= ollection1] o.a.s.c.SolrCore Looking for old index directories to cleanup f= or core collection1 in /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr= /build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/= Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestCl= oudPivotFacet_9CE37D1CCF8B99FB-001/shard-1-001/cores/collection1/data/ [junit4] 2> 1234807 WARN (OldIndexDirectoryCleanupThreadForCore-colle= ction1) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shard2 r:core_node1 x:c= ollection1] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr-tr= unk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenk= ins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/sol= r.cloud.TestCloudPivotFacet_9CE37D1CCF8B99FB-001/shard-1-001/cores/collecti= on1/data/ does not point to a valid data directory; skipping clean-up of ol= d index directories. [junit4] 2> 1234809 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creati= ng updateRequestProcessorChain "nodistrib" [junit4] 2> 1234809 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creati= ng updateRequestProcessorChain "dedupe" [junit4] 2> 1234809 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain insert= ing DistributedUpdateProcessorFactory into updateRequestProcessorChain "ded= upe" [junit4] 2> 1234810 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creati= ng updateRequestProcessorChain "stored_sig" [junit4] 2> 1234810 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain insert= ing DistributedUpdateProcessorFactory into updateRequestProcessorChain "sto= red_sig" [junit4] 2> 1234810 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creati= ng updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1234810 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creati= ng updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1234810 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain insert= ing DistributedUpdateProcessorFactory into updateRequestProcessorChain "dis= trib-dup-test-chain-implicit" [junit4] 2> 1234810 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.c.SolrCore no updateRequestProcessorCh= ain defined as default, creating implicit default [junit4] 2> 1234811 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds= =3D60 [junit4] 2> 1234812 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds= =3D60 [junit4] 2> 1234812 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds= =3D60 [junit4] 2> 1234813 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds= =3D60 [junit4] 2> 1234815 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /a= dmin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segme= nts,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admi= n/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/a= dmin/file [junit4] 2> 1234815 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.c.SolrCore Using default statsCache ca= che: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 1234816 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implem= entation: org.apache.solr.update.UpdateLog [junit4] 2> 1234816 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: da= taDir=3D defaultSyncLevel=3DFLUSH numRecordsToKeep=3D100 maxNumLogsToKeep= =3D10 numVersionBuckets=3D65536 [junit4] 2> 1234816 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 1234816 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 1234817 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy = wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolic= y: maxMergeAtOnce=3D15, maxMergeAtOnceExplicit=3D21, maxMergedSegmentMB=3D1= 9.9697265625, floorSegmentMB=3D0.400390625, forceMergeDeletesPctAllowed=3D2= 9.482463469247342, segmentsPerTier=3D20.0, maxCFSSegmentSizeMB=3D8.79609302= 2207999E12, noCFSRatio=3D0.0 [junit4] 2> 1234817 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: = commits: num=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@133212ba= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@77135b33),= segFN=3Dsegments_1,generation=3D1} [junit4] 2> 1234817 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = =3D 1 [junit4] 2> 1234817 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@1= 9ac5a97[collection1] main [junit4] 2> 1234817 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Setting up Zo= oKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 1234818 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured Zo= oKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1234818 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager= with initArgs: {} [junit4] 2> 1234818 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest= _managed.json using ZooKeeperStorageIO:path=3D/configs/conf1 [junit4] 2> 1234818 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage No data found= for znode /configs/conf1/_rest_managed.json [junit4] 2> 1234818 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null a= t path _rest_managed.json using ZooKeeperStorageIO:path=3D/configs/conf1 [junit4] 2> 1234818 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registere= d ManagedResources [junit4] 2> 1234818 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be r= eserved for 10000 [junit4] 2> 1234819 INFO (searcherExecutor-4339-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz x:collection1 s:shard2 c:collection1 r:core_node1= ) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shard2 r:core_node1 x:collect= ion1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@19ac5= a97[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())= } [junit4] 2> 1234819 INFO (coreLoadExecutor-4338-thread-1-processing-n= :127.0.0.1:51086_ln_%2Flz) [n:127.0.0.1:51086_ln_%2Flz c:collection1 s:shar= d2 r:core_node1 x [...truncated too long message...] %5B*+TO+1170%5D&stats=3Dtrue&stats.field=3D%7B%21key%3Dsk1+tag%3Dst1%2Cst2%= 7Dpivot_tdt1&stats.field=3D%7B%21key%3Dsk2+tag%3Dst2%2Cst3%7Dpivot_tdt&stat= s.field=3D%7B%21key%3Dsk3+tag%3Dst3%2Cst4%7Dpivot_d1&_test_miss=3Dtrue&_tes= t_sort=3Dindex),extra(fq=3D%7B%21term+f%3Dpivot_x_s1%7Dc)}),extra(fq=3D%7B%= 21term+f%3Dpivot_dt%7D2010-10-31T10%3A31%3A12Z)})} expected:<-1.27479533166= 71947E8> but was:<-1.2747953316671945E8> [junit4] > =09at __randomizedtesting.SeedInfo.seed([9CE37D1CCF8B99FB:= 14B742C66177F403]:0) [junit4] > =09at org.apache.solr.cloud.TestCloudPivotFacet.assertPivo= tCountsAreCorrect(TestCloudPivotFacet.java:281) [junit4] > =09at org.apache.solr.cloud.TestCloudPivotFacet.test(TestC= loudPivotFacet.java:228) [junit4] > =09at org.apache.solr.BaseDistributedSearchTestCase$Shards= RepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase= .java:960) [junit4] > =09at org.apache.solr.BaseDistributedSearchTestCase$Shards= RepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935) [junit4] > =09at java.lang.Thread.run(Thread.java:745) [junit4] > Caused by: java.lang.AssertionError: Sum of sk3 =3D> pivot= _x_s1,pivot_dt,pivot_tf: {params(rows=3D0),defaults({main({main(rows=3D0&q= =3D*%3A*&fq=3Did%3A%5B*+TO+1170%5D&stats=3Dtrue&stats.field=3D%7B%21key%3Ds= k1+tag%3Dst1%2Cst2%7Dpivot_tdt1&stats.field=3D%7B%21key%3Dsk2+tag%3Dst2%2Cs= t3%7Dpivot_tdt&stats.field=3D%7B%21key%3Dsk3+tag%3Dst3%2Cst4%7Dpivot_d1&_te= st_miss=3Dtrue&_test_sort=3Dindex),extra(fq=3D%7B%21term+f%3Dpivot_x_s1%7Dc= )}),extra(fq=3D%7B%21term+f%3Dpivot_dt%7D2010-10-31T10%3A31%3A12Z)})} expec= ted:<-1.2747953316671947E8> but was:<-1.2747953316671945E8> [junit4] > =09at org.apache.solr.cloud.TestCloudPivotFacet.assertNume= rics(TestCloudPivotFacet.java:733) [junit4] > =09at org.apache.solr.cloud.TestCloudPivotFacet.assertPivo= tStats(TestCloudPivotFacet.java:389) [junit4] > =09at org.apache.solr.cloud.TestCloudPivotFacet.assertPivo= tData(TestCloudPivotFacet.java:339) [junit4] > =09at org.apache.solr.cloud.TestCloudPivotFacet.assertPivo= tCountsAreCorrect(TestCloudPivotFacet.java:302) [junit4] > =09at org.apache.solr.cloud.TestCloudPivotFacet.assertPivo= tCountsAreCorrect(TestCloudPivotFacet.java:313) [junit4] > =09at org.apache.solr.cloud.TestCloudPivotFacet.assertPivo= tCountsAreCorrect(TestCloudPivotFacet.java:271) [junit4] > =09... 42 more [junit4] 2> 1281601 INFO (SUITE-TestCloudPivotFacet-seed#[9CE37D1CCF8= B99FB]-worker) [n:127.0.0.1:51022_ln_%2Flz c:collection1 s:shard1 r:core_no= de4 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/J1/temp/solr.clou= d.TestCloudPivotFacet_9CE37D1CCF8B99FB-001 [junit4] 2> Jul 17, 2015 4:35:45 PM com.carrotsearch.randomizedtesting= .ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thre= ad(s). [junit4] 2> NOTE: test params are: codec=3DAsserting(Lucene53): {pivot= _dt1=3DPostingsFormat(name=3DLuceneVarGapFixedInterval), dense_pivot_i1=3DP= ostingsFormat(name=3DMemory doPackFST=3D true), pivot_l1=3DPostingsFormat(n= ame=3DLuceneVarGapDocFreqInterval), range_facet_l_dv=3DPostingsFormat(name= =3DMemory doPackFST=3D true), multiDefault=3DPostingsFormat(name=3DLuceneVa= rGapFixedInterval), pivot_f1=3DPostingsFormat(name=3DLuceneVarGapFixedInter= val), intDefault=3DPostingsFormat(name=3DLuceneVarGapDocFreqInterval), pivo= t_d1=3DPostingsFormat(name=3DLuceneVarGapDocFreqInterval), pivot_x_s=3DPost= ingsFormat(name=3DMockRandom), pivot_b1=3DPostingsFormat(name=3DLuceneVarGa= pFixedInterval), pivot_dt=3DPostingsFormat(name=3DMemory doPackFST=3D true)= , dense_pivot_ti1=3DPostingsFormat(name=3DMemory doPackFST=3D true), range_= facet_l=3DPostingsFormat(name=3DLuceneVarGapDocFreqInterval), pivot_x_s1=3D= PostingsFormat(name=3DLuceneVarGapDocFreqInterval), pivot_y_s=3DPostingsFor= mat(name=3DLuceneVarGapFixedInterval), pivot_z_s=3DPostingsFormat(name=3DMe= mory doPackFST=3D true), dense_pivot_ti=3DPostingsFormat(name=3DLuceneVarGa= pDocFreqInterval), pivot_z_s1=3DPostingsFormat(name=3DLuceneVarGapFixedInte= rval), pivot_tl1=3DPostingsFormat(name=3DLuceneVarGapFixedInterval), id=3DP= ostingsFormat(name=3DMemory doPackFST=3D true), dense_pivot_x_s1=3DPostings= Format(name=3DLuceneVarGapDocFreqInterval), timestamp=3DPostingsFormat(name= =3DLuceneVarGapDocFreqInterval), dense_pivot_y_s=3DPostingsFormat(name=3DLu= ceneVarGapFixedInterval), pivot_td1=3DPostingsFormat(name=3DLuceneVarGapFix= edInterval), pivot_tdt=3DPostingsFormat(name=3DMockRandom), pivot_i1=3DPost= ingsFormat(name=3DMemory doPackFST=3D true), pivot_tf1=3DPostingsFormat(nam= e=3DLuceneVarGapDocFreqInterval), pivot_td=3DPostingsFormat(name=3DMemory d= oPackFST=3D true), pivot_tf=3DPostingsFormat(name=3DMockRandom), pivot_f=3D= PostingsFormat(name=3DMemory doPackFST=3D true), pivot_ti=3DPostingsFormat(= name=3DLuceneVarGapDocFreqInterval), _version_=3DPostingsFormat(name=3DLuce= neVarGapDocFreqInterval), pivot_i=3DPostingsFormat(name=3DLuceneVarGapFixed= Interval), pivot_tl=3DPostingsFormat(name=3DMemory doPackFST=3D true), pivo= t_l=3DPostingsFormat(name=3DMockRandom), pivot_y_s1=3DPostingsFormat(name= =3DMockRandom), pivot_ti1=3DPostingsFormat(name=3DMockRandom), range_facet_= i_dv=3DPostingsFormat(name=3DLuceneVarGapDocFreqInterval), dense_pivot_i=3D= PostingsFormat(name=3DLuceneVarGapFixedInterval), pivot_b=3DPostingsFormat(= name=3DMemory doPackFST=3D true), dense_pivot_y_s1=3DPostingsFormat(name=3D= MockRandom), pivot_d=3DPostingsFormat(name=3DMockRandom), pivot_tdt1=3DPost= ingsFormat(name=3DLuceneVarGapDocFreqInterval), dense_pivot_x_s=3DPostingsF= ormat(name=3DMemory doPackFST=3D true)}, docValues:{range_facet_l_dv=3DDocV= aluesFormat(name=3DDirect), range_facet_i_dv=3DDocValuesFormat(name=3DAsser= ting), timestamp=3DDocValuesFormat(name=3DAsserting)}, sim=3DDefaultSimilar= ity, locale=3Dhr_HR, timezone=3DCanada/Yukon [junit4] 2> NOTE: Linux 3.16.0-43-generic amd64/Oracle Corporation 1.8= .0_45 (64-bit)/cpus=3D12,threads=3D1,free=3D155995600,total=3D529530880 [junit4] 2> NOTE: All tests run in this JVM: [ShardRoutingTest, AddSch= emaFieldsUpdateProcessorFactoryTest, TestCollationFieldDocValues, TestField= SortValues, RequestLoggingTest, ResourceLoaderTest, SpellCheckComponentTest= , DistributedExpandComponentTest, TestLRUStatsCache, TriLevelCompositeIdRou= tingTest, SolrCloudExampleTest, TestFuzzyAnalyzedSuggestions, FacetPivotSma= llTest, TestSolrQueryParserResource, TestManagedResourceStorage, TestManage= dSchemaDynamicFieldResource, TestSchemaVersionResource, CacheHeaderTest, Te= stPHPSerializedResponseWriter, TestBulkSchemaAPI, BigEndianAscendingWordDes= erializerTest, TestReplicaProperties, TestLMJelinekMercerSimilarityFactory,= TestAtomicUpdateErrorCases, TestAuthorizationFramework, TestSolrDeletionPo= licy2, ReturnFieldsTest, TestComplexPhraseQParserPlugin, HdfsDirectoryTest,= CollectionsAPIDistributedZkTest, SliceStateTest, TestHashPartitioner, Shar= dSplitTest, TestStressUserVersions, MigrateRouteKeyTest, CoreAdminHandlerTe= st, TestFieldTypeCollectionResource, TestSearchPerf, JsonLoaderTest, Curren= cyFieldOpenExchangeTest, TestMacros, IgnoreCommitOptimizeUpdateProcessorFac= toryTest, DistributedFacetPivotSmallAdvancedTest, HLLUtilTest, HdfsCollecti= onsAPIDistributedZkTest, TestSolrCloudWithKerberosAlt, TestIndexSearcher, T= estReRankQParserPlugin, WordBreakSolrSpellCheckerTest, TestStandardQParsers= , SolrCmdDistributorTest, TestNRTOpen, TestLeaderElectionZkExpiry, OpenClos= eCoreStressTest, TestFastWriter, SystemInfoHandlerTest, TestWriterPerf, Tes= tValueSourceCache, DistributedIntervalFacetingTest, ShardRoutingCustomTest,= TestAddFieldRealTimeGet, CdcrUpdateLogTest, DistributedQueueTest, TestFast= LRUCache, BasicFunctionalityTest, HdfsDirectoryFactoryTest, DocumentBuilder= Test, TestCoreContainer, DistribDocExpirationUpdateProcessorTest, TestAnaly= zedSuggestions, TestFieldResource, TestCryptoKeys, BlockCacheTest, TestImpl= icitCoreProperties, TestElisionMultitermQuery, TestStressVersions, TestFold= ingMultitermQuery, RequiredFieldsTest, TestLMDirichletSimilarityFactory, Pr= eAnalyzedFieldTest, EchoParamsTest, MergeStrategyTest, TestReplicationHandl= er, TestRangeQuery, SimpleFacetsTest, SolrCoreTest, PeerSyncTest, TestFilte= ring, AnalysisAfterCoreReloadTest, SolrRequestParserTest, SuggesterWFSTTest= , SolrCoreCheckLockOnStartupTest, TestRemoteStreaming, DistanceFunctionTest= , DisMaxRequestHandlerTest, DocumentAnalysisRequestHandlerTest, FileBasedSp= ellCheckerTest, FieldAnalysisRequestHandlerTest, SolrPluginUtilsTest, TestC= SVResponseWriter, TestPartialUpdateDeduplication, TestLFUCache, CloudExitab= leDirectoryReaderTest, CollectionStateFormat2Test, DeleteReplicaTest, Delet= eShardTest, LeaderFailoverAfterPartitionTest, OverriddenZkACLAndCredentials= ProvidersTest, OverseerStatusTest, ReplicationFactorTest, RollingRestartTes= t, TestCloudPivotFacet] [junit4] Completed [412/525] on J1 in 51.20s, 1 test, 1 failure <<< FAIL= URES! [...truncated 357 lines...] BUILD FAILED /home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:526: The followin= g error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:474: The followin= g error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:61: The following= error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/extra-targets.xml:39: The f= ollowing error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:230: The fol= lowing error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:513: = The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:142= 6: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:981= : There were test failures: 525 suites, 2067 tests, 1 failure, 60 ignored (= 26 assumptions) Total time: 42 minutes 48 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any ------=_Part_44_1286309061.1437151245733 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_44_1286309061.1437151245733--