Return-Path: X-Original-To: archive-asf-public-internal@cust-asf2.ponee.io Delivered-To: archive-asf-public-internal@cust-asf2.ponee.io Received: from cust-asf.ponee.io (cust-asf.ponee.io [163.172.22.183]) by cust-asf2.ponee.io (Postfix) with ESMTP id B8EED200B3B for ; Mon, 27 Jun 2016 05:03:45 +0200 (CEST) Received: by cust-asf.ponee.io (Postfix) id B81E3160A69; Mon, 27 Jun 2016 03:03:45 +0000 (UTC) Delivered-To: archive-asf-public@cust-asf.ponee.io Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by cust-asf.ponee.io (Postfix) with SMTP id 1AA81160A5C for ; Mon, 27 Jun 2016 05:03:42 +0200 (CEST) Received: (qmail 71036 invoked by uid 500); 27 Jun 2016 03:03:41 -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 71014 invoked by uid 99); 27 Jun 2016 03:03:41 -0000 Received: from pnap-us-west-generic-nat.apache.org (HELO spamd1-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Mon, 27 Jun 2016 03:03:41 +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 6CDC7C0E7B for ; Mon, 27 Jun 2016 03:03:40 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd1-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: 2.001 X-Spam-Level: ** X-Spam-Status: No, score=2.001 tagged_above=-999 required=6.31 tests=[FSL_HELO_NON_FQDN_1=0.001, KAM_BADIPHTTP=2, SPF_PASS=-0.001, WEIRD_PORT=0.001] autolearn=disabled Received: from mx2-lw-us.apache.org ([10.40.0.8]) by localhost (spamd1-us-west.apache.org [10.40.0.7]) (amavisd-new, port 10024) with ESMTP id hWnAonvklQ5Y for ; Mon, 27 Jun 2016 03:03:33 +0000 (UTC) Received: from serv1 (serv1.sd-datasolutions.de [188.138.57.78]) by mx2-lw-us.apache.org (ASF Mail Server at mx2-lw-us.apache.org) with ESMTPS id A91AE5FBEC for ; Mon, 27 Jun 2016 03:03:32 +0000 (UTC) Received: from localhost.localdomain ([127.0.0.1] helo=serv1) by serv1 with esmtp (Exim 4.82) (envelope-from ) id 1bHMpa-00086g-7w for dev@lucene.apache.org; Mon, 27 Jun 2016 03:03:26 +0000 Date: Mon, 27 Jun 2016 03:03:11 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org Message-ID: <402509923.27.1466996606247.JavaMail.jenkins@serv1> Subject: [JENKINS-EA] Lucene-Solr-6.x-Linux (32bit/jdk-9-ea+124) - Build # 976 - Failure! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_26_311064946.1466996606188" X-Jenkins-Job: Lucene-Solr-6.x-Linux X-Jenkins-Result: FAILURE X-Priority: 5 (Low) archived-at: Mon, 27 Jun 2016 03:03:45 -0000 ------=_Part_26_311064946.1466996606188 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: http://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/976/ Java: 32bit/jdk-9-ea+124 -client -XX:+UseSerialGC 1 tests failed. FAILED: org.apache.solr.cloud.HttpPartitionTest.test Error Message: Doc with id=3D2 not found in http://127.0.0.1:42032/c8n_1x2_leader_session_= loss due to: Path not found: /id; rsp=3D{doc=3Dnull} Stack Trace: java.lang.AssertionError: Doc with id=3D2 not found in http://127.0.0.1:420= 32/c8n_1x2_leader_session_loss due to: Path not found: /id; rsp=3D{doc=3Dnu= ll} =09at __randomizedtesting.SeedInfo.seed([40E288E62AAF1C35:C8B6B73C845371CD]= :0) =09at org.junit.Assert.fail(Assert.java:93) =09at org.junit.Assert.assertTrue(Assert.java:43) =09at org.apache.solr.cloud.HttpPartitionTest.assertDocExists(HttpPartition= Test.java:603) =09at org.apache.solr.cloud.HttpPartitionTest.assertDocsExistInAllReplicas(= HttpPartitionTest.java:558) =09at org.apache.solr.cloud.HttpPartitionTest.testLeaderZkSessionLoss(HttpP= artitionTest.java:506) =09at org.apache.solr.cloud.HttpPartitionTest.test(HttpPartitionTest.java:1= 19) =09at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/= Native Method) =09at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/N= ativeMethodAccessorImpl.java:62) =09at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-= ea/DelegatingMethodAccessorImpl.java:43) =09at java.lang.reflect.Method.invoke(java.base@9-ea/Method.java:533) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1764) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:871) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Random= izedRunner.java:907) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rando= mizedRunner.java:921) =09at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= FixedStatement.callStatement(BaseDistributedSearchTestCase.java:992) =09at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= Statement.evaluate(BaseDistributedSearchTestCase.java:967) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:49) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:367) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:809) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:460) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:880) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:781) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:816) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:827) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:41) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:53) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:54) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:367) =09at java.lang.Thread.run(java.base@9-ea/Thread.java:843) Build Log: [...truncated 12123 lines...] [junit4] Suite: org.apache.solr.cloud.HttpPartitionTest [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-6.x-= Linux/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_40E288= E62AAF1C35-001/init-core-data-001 [junit4] 2> 1614115 INFO (SUITE-HttpPartitionTest-seed#[40E288E62AAF1= C35]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientA= uth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=3Dhttps= ://issues.apache.org/jira/browse/SOLR-5776) [junit4] 2> 1614115 INFO (SUITE-HttpPartitionTest-seed#[40E288E62AAF1= C35]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext= system property: / [junit4] 2> 1614117 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1614117 INFO (Thread-4461) [ ] o.a.s.c.ZkTestServer cl= ient port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1614117 INFO (Thread-4461) [ ] o.a.s.c.ZkTestServer St= arting server [junit4] 2> 1614217 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.ZkTestServer start zk server on port:46250 [junit4] 2> 1614217 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1614218 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect = to ZooKeeper [junit4] 2> 1614219 INFO (zkCallback-1953-thread-1) [ ] o.a.s.c.c.= ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1b= d9da3 name:ZooKeeperConnection Watcher:127.0.0.1:46250 got event WatchedEve= nt state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1614219 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeep= er [junit4] 2> 1614219 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1614220 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1614221 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1614221 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect = to ZooKeeper [junit4] 2> 1614222 INFO (zkCallback-1954-thread-1) [ ] o.a.s.c.c.= ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@19= ef58f name:ZooKeeperConnection Watcher:127.0.0.1:46250/solr got event Watch= edEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1614222 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeep= er [junit4] 2> 1614222 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1614222 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 1614223 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/= shards [junit4] 2> 1614224 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_coll= ection [junit4] 2> 1614224 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_coll= ection/shards [junit4] 2> 1614225 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Luc= ene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfi= g-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 1614225 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig= .xml [junit4] 2> 1614226 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Luc= ene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xm= l to /configs/conf1/schema.xml [junit4] 2> 1614226 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 1614227 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Luc= ene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfi= g.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.random= indexconfig.xml [junit4] 2> 1614227 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig= .snippet.randomindexconfig.xml [junit4] 2> 1614228 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Luc= ene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords= .txt to /configs/conf1/stopwords.txt [junit4] 2> 1614228 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.= txt [junit4] 2> 1614230 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Luc= ene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/protwords= .txt to /configs/conf1/protwords.txt [junit4] 2> 1614230 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.= txt [junit4] 2> 1614230 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Luc= ene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/currency.= xml to /configs/conf1/currency.xml [junit4] 2> 1614231 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.x= ml [junit4] 2> 1614231 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Luc= ene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConf= ig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1614231 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfi= g.xml [junit4] 2> 1614232 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Luc= ene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/open-exch= ange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 1614232 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-excha= nge-rates.json [junit4] 2> 1614233 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Luc= ene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-I= SOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1614233 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-IS= OLatin1Accent.txt [junit4] 2> 1614233 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Luc= ene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/old_synon= yms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1614234 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synony= ms.txt [junit4] 2> 1614234 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Luc= ene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.= txt to /configs/conf1/synonyms.txt [junit4] 2> 1614234 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.t= xt [junit4] 2> 1614299 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home= /jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/= solr.cloud.HttpPartitionTest_40E288E62AAF1C35-001/control-001/cores/collect= ion1 [junit4] 2> 1614301 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 1614302 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHan= dler@129f6e9{/,null,AVAILABLE} [junit4] 2> 1614302 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.e.j.s.ServerConnector Started ServerConnector@959369{HTT= P/1.1,[http/1.1]}{127.0.0.1:35877} [junit4] 2> 1614302 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.e.j.s.Server Started @1615625ms [junit4] 2> 1614302 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext= =3D/, solr.data.dir=3D/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/bu= ild/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_40E288E62AAF1C35-00= 1/tempDir-001/control/data, hostPort=3D42032, coreRootDirectory=3D/home/jen= kins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr= .cloud.HttpPartitionTest_40E288E62AAF1C35-001/control-001/cores} [junit4] 2> 1614303 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): jdk.= internal.loader.ClassLoaders$AppClassLoader@864e92 [junit4] 2> 1614303 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for dire= ctory: '/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/= test/J1/temp/solr.cloud.HttpPartitionTest_40E288E62AAF1C35-001/control-001' [junit4] 2> 1614303 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (N= oInitialContextEx) [junit4] 2> 1614303 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' = (could not find system property or JNDI) [junit4] 2> 1614303 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1614304 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect = to ZooKeeper [junit4] 2> 1614304 INFO (zkCallback-1955-thread-1) [ ] o.a.s.c.c.= ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@8b= 7443 name:ZooKeeperConnection Watcher:127.0.0.1:46250/solr got event Watche= dEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1614304 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeep= er [junit4] 2> 1614305 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1614306 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome = (not found in ZooKeeper) [junit4] 2> 1614306 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from= /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1= /temp/solr.cloud.HttpPartitionTest_40E288E62AAF1C35-001/control-001/solr.xm= l [junit4] 2> 1614310 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root dir= ectory: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/= test/J1/temp/solr.cloud.HttpPartitionTest_40E288E62AAF1C35-001/control-001/= cores [junit4] 2> 1614310 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.CoreContainer New CoreContainer 18305820 [junit4] 2> 1614310 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [in= stanceDir=3D/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-c= ore/test/J1/temp/solr.cloud.HttpPartitionTest_40E288E62AAF1C35-001/control-= 001] [junit4] 2> 1614311 WARN (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkin= s/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cl= oud.HttpPartitionTest_40E288E62AAF1C35-001/control-001/lib to classpath: /h= ome/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/te= mp/solr.cloud.HttpPartitionTest_40E288E62AAF1C35-001/control-001/lib [junit4] 2> 1614311 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeo= ut : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxC= onnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadI= dleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 1614312 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTT= P client with params: socketTimeout=3D340000&connTimeout=3D45000&retry=3Dtr= ue [junit4] 2> 1614312 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLogge= rFactory [junit4] 2> 1614312 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.sl= f4j.impl.Log4jLoggerFactory)] [junit4] 2> 1614312 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.ZkContainer Zookeeper client=3D127.0.0.1:46250/sol= r [junit4] 2> 1614312 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1614312 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1614313 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect = to ZooKeeper [junit4] 2> 1614314 INFO (zkCallback-1958-thread-1) [ ] o.a.s.c.c.= ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@8a= 207c name:ZooKeeperConnection Watcher:127.0.0.1:46250 got event WatchedEven= t state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1614314 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeep= er [junit4] 2> 1614314 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1614315 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.ZkController Added new OnReconnect listener org.ap= ache.solr.cloud.ZkController$$Lambda$183/8568906@1148444 [junit4] 2> 1614315 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect = to ZooKeeper [junit4] 2> 1614316 INFO (zkCallback-1959-thread-1) [ ] o.a.s.c.c.= ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@ff= 1dc6 name:ZooKeeperConnection Watcher:127.0.0.1:46250/solr got event Watche= dEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1614316 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeep= er [junit4] 2> 1614317 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 1614318 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queu= e-work [junit4] 2> 1614320 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-= running [junit4] 2> 1614320 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-= completed [junit4] 2> 1614321 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-= failure [junit4] 2> 1614322 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1614323 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1614323 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1614324 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1614324 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKee= per...=20 [junit4] 2> 1614324 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 1614325 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper.= .. (0) -> (0) [junit4] 2> 1614326 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 1614327 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 1614327 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 1614328 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.LeaderElector Joined leadership election with path= : /overseer_elect/election/96141046340452356-127.0.0.1:42032_-n_0000000000 [junit4] 2> 1614328 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.OverseerElectionContext I am going to be the leade= r 127.0.0.1:42032_ [junit4] 2> 1614328 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 1614328 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.Overseer Overseer (id=3D96141046340452356-127.0.0.= 1:42032_-n_0000000000) starting [junit4] 2> 1614329 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 1614331 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting Oversee= rAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=3D10000 autoRep= licaFailoverWaitAfterExpiration=3D30000 autoReplicaFailoverBadNodeExpiratio= n=3D60000 [junit4] 2> 1614331 INFO (OverseerCollectionConfigSetProcessor-961410= 46340452356-127.0.0.1:42032_-n_0000000000) [ ] o.a.s.c.OverseerTaskProce= ssor Process current queue of overseer operations [junit4] 2> 1614331 INFO (OverseerStateUpdate-96141046340452356-127.0= .0.1:42032_-n_0000000000) [ ] o.a.s.c.Overseer Starting to work on the m= ain queue [junit4] 2> 1614331 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/l= ive_nodes/127.0.0.1:42032_ [junit4] 2> 1614332 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:420= 32_ [junit4] 2> 1614332 INFO (OverseerStateUpdate-96141046340452356-127.0= .0.1:42032_-n_0000000000) [ ] o.a.s.c.c.ZkStateReader Updated live nodes= from ZooKeeper... (0) -> (0) [junit4] 2> 1614333 INFO (zkCallback-1959-thread-1) [ ] o.a.s.c.c.= ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:No= deChildrenChanged path:/live_nodes], has occurred - updating... (live nodes= size: [0]) [junit4] 2> 1614333 INFO (zkCallback-1959-thread-1) [ ] o.a.s.c.c.= ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1614372 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skippin= g setup for authorization module. [junit4] 2> 1614372 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1614372 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions= underneath /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-c= ore/test/J1/temp/solr.cloud.HttpPartitionTest_40E288E62AAF1C35-001/control-= 001/cores [junit4] 2> 1614373 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {schema=3Ds= chema.xml, dataDir=3Ddata/, collection=3Dcontrol_collection, loadOnStartup= =3Dtrue, coreNodeName=3D, transient=3Dfalse, name=3Dcollection1, shard=3D, = config=3Dsolrconfig.xml, configSetProperties=3Dconfigsetprops.json} [junit4] 2> 1614373 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /h= ome/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/te= mp/solr.cloud.HttpPartitionTest_40E288E62AAF1C35-001/control-001/cores/coll= ection1 [junit4] 2> 1614374 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 1614374 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.s.SolrDispatchFilter user.dir=3D/home/jenkins/worksp= ace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1 [junit4] 2> 1614374 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1614374 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.ZkController publishing state=3Ddown [junit4] 2> 1614375 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.ZkController numShards not found on descriptor - reading it from system= property [junit4] 2> 1614375 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.ZkController Collection control_collection not visible yet, but flaggin= g it so a watch is registered when it becomes visible [junit4] 2> 1614375 INFO (OverseerStateUpdate-96141046340452356-127.0= .0.1:42032_-n_0000000000) [ ] 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:42032", [junit4] 2> "node_name":"127.0.0.1:42032_", [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> 1614376 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.c.ZkStateReader Deleting data for [control_collection] [junit4] 2> 1614376 INFO (OverseerStateUpdate-96141046340452356-127.0= .0.1:42032_-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Update state numS= hards=3D1 message=3D{ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:42032", [junit4] 2> "node_name":"127.0.0.1:42032_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1614376 INFO (OverseerStateUpdate-96141046340452356-127.0= .0.1:42032_-n_0000000000) [ ] o.a.s.c.o.ClusterStateMutator building a n= ew cName: control_collection [junit4] 2> 1614376 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.ZkController look for our core node name [junit4] 2> 1614376 INFO (OverseerStateUpdate-96141046340452356-127.0= .0.1:42032_-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new nod= e to shard shard=3Dshard1 [junit4] 2> 1614377 INFO (zkCallback-1959-thread-1) [ ] o.a.s.c.c.= ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected typ= e:NodeDataChanged path:/clusterstate.json], has occurred - updating... (liv= e nodes size: [1]) [junit4] 2> 1615376 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1615376 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 1615377 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.ZkController Collection zkNode exists [junit4] 2> 1615377 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.c.ZkStateReader Load collection config from: [/collections/control_coll= ection] [junit4] 2> 1615377 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.c.ZkStateReader path=3D[/collections/control_collection] [configName]= =3D[conf1] specified config exists in ZooKeeper [junit4] 2> 1615377 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins= /workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.clo= ud.HttpPartitionTest_40E288E62AAF1C35-001/control-001/cores/collection1' [junit4] 2> 1615378 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1615378 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find syste= m property or JNDI) [junit4] 2> 1615379 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.Config loaded config solrconfig.xml with version 0=20 [junit4] 2> 1615383 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1615397 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.SolrConfig Using Lucene MatchVersion: 6.2.0 [junit4] 2> 1615404 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 1615411 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.s.IndexSchema [collection1] Schema name=3Dtest [junit4] 2> 1615488 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.s.OpenExchangeRatesOrgProvider Initialized with rates=3Dopen-exchange-rat= es.json, refreshInterval=3D1440. [junit4] 2> 1615493 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.s.IndexSchema default search field in schema is text [junit4] 2> 1615494 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.s.IndexSchema unique key field: id [junit4] 2> 1615500 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xm= l [junit4] 2> 1615501 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xm= l [junit4] 2> 1615503 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchang= e-rates.json [junit4] 2> 1615503 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchang= e-rates.json [junit4] 2> 1615504 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default= properties: Can't find resource 'configsetprops.json' in classpath or '/co= nfigs/conf1', cwd=3D/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/buil= d/solr-core/test/J1 [junit4] 2> 1615504 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.CoreContainer Creating SolrCore 'collection1' using configuration from = collection control_collection [junit4] 2> 1615504 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1615505 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/worksp= ace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.Http= PartitionTest_40E288E62AAF1C35-001/control-001/cores/collection1], dataDir= =3D[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test= /J1/temp/solr.cloud.HttpPartitionTest_40E288E62AAF1C35-001/control-001/core= s/collection1/data/] [junit4] 2> 1615505 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Se= rver: com.sun.jmx.mbeanserver.JmxMBeanServer@70c8d8 [junit4] 2> 1615505 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspac= e/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPa= rtitionTest_40E288E62AAF1C35-001/control-001/cores/collection1/data [junit4] 2> 1615505 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.SolrCore New index directory detected: old=3Dnull new=3D/home/jenkins/w= orkspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud= .HttpPartitionTest_40E288E62AAF1C35-001/control-001/cores/collection1/data/= index/ [junit4] 2> 1615506 WARN (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lu= cene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartit= ionTest_40E288E62AAF1C35-001/control-001/cores/collection1/data/index' does= n't exist. Creating new index... [junit4] 2> 1615506 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspac= e/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPa= rtitionTest_40E288E62AAF1C35-001/control-001/cores/collection1/data/index [junit4] 2> 1615506 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.in= dex.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=3D1000, mergeFactor= =3D24, maxMergeSize=3D9223372036854775807, maxMergeSizeForForcedMerge=3D922= 3372036854775807, calibrateSizeByDeletes=3Dfalse, maxMergeDocs=3D2147483647= , maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio=3D0.88870182399159= 25] [junit4] 2> 1615506 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@1d0e5ba = lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@147e6e6),se= gFN=3Dsegments_1,generation=3D1} [junit4] 2> 1615506 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.SolrDeletionPolicy newest commit generation =3D 1 [junit4] 2> 1615507 INFO (OldIndexDirectoryCleanupThreadForCore-colle= ction1) [ ] o.a.s.c.SolrCore Looking for old index directories to cleanu= p for core collection1 in /home/jenkins/workspace/Lucene-Solr-6.x-Linux/sol= r/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_40E288E62AAF1C3= 5-001/control-001/cores/collection1/data/ [junit4] 2> 1615507 WARN (OldIndexDirectoryCleanupThreadForCore-colle= ction1) [ ] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr= -6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_4= 0E288E62AAF1C35-001/control-001/cores/collection1/data/ does not point to a= valid data directory; skipping clean-up of old index directories. [junit4] 2> 1615509 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nod= istrib" [junit4] 2> 1615509 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "ded= upe" [junit4] 2> 1615509 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFacto= ry into updateRequestProcessorChain "dedupe" [junit4] 2> 1615509 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "sto= red_sig" [junit4] 2> 1615509 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFacto= ry into updateRequestProcessorChain "stored_sig" [junit4] 2> 1615510 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dis= trib-dup-test-chain-explicit" [junit4] 2> 1615510 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dis= trib-dup-test-chain-implicit" [junit4] 2> 1615510 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFacto= ry into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1615510 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.SolrCore no updateRequestProcessorChain defined as default, creating im= plicit default [junit4] 2> 1615510 WARN (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.RequestHandlers INVALID paramSet a in requestHandler {type =3D requestH= andler,name =3D /dump,class =3D DumpRequestHandler,args =3D {defaults=3D{a= =3DA,b=3DB}}} [junit4] 2> 1615511 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1615512 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1615514 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1615514 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1615518 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/update/json/d= ocs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,= /dump,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,/c= onfig,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/ad= min/file [junit4] 2> 1615518 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.L= ocalStatsCache [junit4] 2> 1615519 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.Up= dateLog [junit4] 2> 1615519 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.u.UpdateLog Initializing UpdateLog: dataDir=3D defaultSyncLevel=3DFLUSH n= umRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 1615520 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1615520 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1615520 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.in= dex.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=3D1677721= , mergeFactor=3D19, maxMergeSize=3D2147483648, maxMergeSizeForForcedMerge= =3D9223372036854775807, calibrateSizeByDeletes=3Dfalse, maxMergeDocs=3D2147= 483647, maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio=3D1.0] [junit4] 2> 1615520 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@1d0e5ba = lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@147e6e6),se= gFN=3Dsegments_1,generation=3D1} [junit4] 2> 1615520 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.SolrDeletionPolicy newest commit generation =3D 1 [junit4] 2> 1615520 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.s.SolrIndexSearcher Opening [Searcher@89dff7[collection1] main] [junit4] 2> 1615520 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.c.ZkStateReader Load collection config from: [/collections/control_coll= ection] [junit4] 2> 1615521 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.c.ZkStateReader path=3D[/collections/control_collection] [configName]= =3D[conf1] specified config exists in ZooKeeper [junit4] 2> 1615521 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the RestM= anager with znodeBase: /configs/conf1 [junit4] 2> 1615521 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /c= onfigs/conf1 [junit4] 2> 1615521 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 1615521 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.r.ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStorag= eIO:path=3D/configs/conf1 [junit4] 2> 1615522 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.r.ManagedResourceStorage No data found for znode /configs/conf1/_rest_man= aged.json [junit4] 2> 1615522 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using Zoo= KeeperStorageIO:path=3D/configs/conf1 [junit4] 2> 1615522 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1615522 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1615523 INFO (searcherExecutor-6327-thread-1) [ ] o.a.= s.c.SolrCore [collection1] Registered new searcher Searcher@89dff7[collecti= on1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1615523 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.u.UpdateLog Looking up max value of version field to seed version buckets [junit4] 2> 1615523 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buc= kets from index [junit4] 2> 1615523 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.u.VersionInfo No terms found for _version_, cannot seed version bucket hi= ghest value from index [junit4] 2> 1615523 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.u.UpdateLog Could not find max version in index or recent updates, using = new clock 1538256742920486912 [junit4] 2> 1615525 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.u.UpdateLog Took 2.0ms to seed version buckets with highest version 15382= 56742920486912 [junit4] 2> 1615525 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1615526 INFO (coreLoadExecutor-6326-thread-1) [ ] o.a.= s.c.CoreContainer registering core: collection1 [junit4] 2> 1615526 INFO (coreZkRegister-6320-thread-1) [ ] o.a.s.= c.ZkController Register replica - core:collection1 address:http://127.0.0.1= :42032 collection:control_collection shard:shard1 [junit4] 2> 1615526 INFO (coreZkRegister-6320-thread-1) [ ] o.a.s.= c.c.SolrZkClient makePath: /collections/control_collection/leader_elect/sha= rd1/election [junit4] 2> 1615528 INFO (coreZkRegister-6320-thread-1) [ ] o.a.s.= c.LeaderElector Joined leadership election with path: /collections/control_= collection/leader_elect/shard1/election/96141046340452356-core_node1-n_0000= 000000 [junit4] 2> 1615528 INFO (coreZkRegister-6320-thread-1) [ ] o.a.s.= c.ShardLeaderElectionContext Running the leader process for shard=3Dshard1 = and weAreReplacement=3Dfalse and leaderVoteWait=3D10000 [junit4] 2> 1615529 INFO (coreZkRegister-6320-thread-1) [ ] o.a.s.= c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1615529 INFO (coreZkRegister-6320-thread-1) [ ] o.a.s.= c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1615529 INFO (coreZkRegister-6320-thread-1) [ ] o.a.s.= c.SyncStrategy Sync replicas to http://127.0.0.1:42032/collection1/ [junit4] 2> 1615529 INFO (coreZkRegister-6320-thread-1) [ ] o.a.s.= c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1615529 INFO (OverseerStateUpdate-96141046340452356-127.0= .0.1:42032_-n_0000000000) [ ] 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> 1615529 INFO (coreZkRegister-6320-thread-1) [ ] o.a.s.= c.SyncStrategy http://127.0.0.1:42032/collection1/ has no replicas [junit4] 2> 1615530 INFO (coreZkRegister-6320-thread-1) [ ] o.a.s.= c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 1615542 INFO (coreZkRegister-6320-thread-1) [ ] o.a.s.= c.ShardLeaderElectionContextBase Creating leader registration node /collect= ions/control_collection/leaders/shard1/leader after winning as /collections= /control_collection/leader_elect/shard1/election/96141046340452356-core_nod= e1-n_0000000000 [junit4] 2> 1615543 INFO (coreZkRegister-6320-thread-1) [ ] o.a.s.= c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:42032/co= llection1/ shard1 [junit4] 2> 1615543 INFO (OverseerStateUpdate-96141046340452356-127.0= .0.1:42032_-n_0000000000) [ ] 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:42032", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 1615644 INFO (zkCallback-1959-thread-1) [ ] o.a.s.c.c.= ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected typ= e:NodeDataChanged path:/clusterstate.json], has occurred - updating... (liv= e nodes size: [1]) [junit4] 2> 1615693 INFO (coreZkRegister-6320-thread-1) [ ] o.a.s.= c.ZkController We are http://127.0.0.1:42032/collection1/ and leader is htt= p://127.0.0.1:42032/collection1/ [junit4] 2> 1615694 INFO (coreZkRegister-6320-thread-1) [ ] o.a.s.= c.ZkController No LogReplay needed for core=3Dcollection1 baseURL=3Dhttp://= 127.0.0.1:42032 [junit4] 2> 1615694 INFO (coreZkRegister-6320-thread-1) [ ] o.a.s.= c.ZkController I am the leader, no recovery necessary [junit4] 2> 1615694 INFO (coreZkRegister-6320-thread-1) [ ] o.a.s.= c.ZkController publishing state=3Dactive [junit4] 2> 1615694 INFO (coreZkRegister-6320-thread-1) [ ] o.a.s.= c.ZkController numShards not found on descriptor - reading it from system p= roperty [junit4] 2> 1615695 INFO (OverseerStateUpdate-96141046340452356-127.0= .0.1:42032_-n_0000000000) [ ] 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:42032", [junit4] 2> "node_name":"127.0.0.1:42032_", [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> 1615695 INFO (OverseerStateUpdate-96141046340452356-127.0= .0.1:42032_-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Update state numS= hards=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:42032", [junit4] 2> "node_name":"127.0.0.1:42032_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1615796 INFO (zkCallback-1959-thread-2) [ ] o.a.s.c.c.= ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected typ= e:NodeDataChanged path:/clusterstate.json], has occurred - updating... (liv= e nodes size: [1]) [junit4] 2> 1615877 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1615877 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect = to ZooKeeper [junit4] 2> 1615878 INFO (zkCallback-1963-thread-1) [ ] o.a.s.c.c.= ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1a= 68539 name:ZooKeeperConnection Watcher:127.0.0.1:46250/solr got event Watch= edEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1615878 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeep= er [junit4] 2> 1615878 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1615878 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKee= per...=20 [junit4] 2> 1615879 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 1615879 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper.= .. (0) -> (1) [junit4] 2> 1615880 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false c= ause connection loss:false [junit4] 2> 1615944 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home= /jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/= solr.cloud.HttpPartitionTest_40E288E62AAF1C35-001/shard-1-001/cores/collect= ion1 [junit4] 2> 1615945 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in di= rectory /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/= test/J1/temp/solr.cloud.HttpPartitionTest_40E288E62AAF1C35-001/shard-1-001 [junit4] 2> 1615946 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 1615946 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHan= dler@55a751{/,null,AVAILABLE} [junit4] 2> 1615947 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1153662{HT= TP/1.1,[http/1.1]}{127.0.0.1:42804} [junit4] 2> 1615947 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.e.j.s.Server Started @1617270ms [junit4] 2> 1615947 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext= =3D/, solrconfig=3Dsolrconfig.xml, solr.data.dir=3D/home/jenkins/workspace/= Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPart= itionTest_40E288E62AAF1C35-001/tempDir-001/jetty1, hostPort=3D38852, coreRo= otDirectory=3D/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr= -core/test/J1/temp/solr.cloud.HttpPartitionTest_40E288E62AAF1C35-001/shard-= 1-001/cores} [junit4] 2> 1615947 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): jdk.= internal.loader.ClassLoaders$AppClassLoader@864e92 [junit4] 2> 1615947 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for dire= ctory: '/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/= test/J1/temp/solr.cloud.HttpPartitionTest_40E288E62AAF1C35-001/shard-1-001' [junit4] 2> 1615948 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (N= oInitialContextEx) [junit4] 2> 1615948 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' = (could not find system property or JNDI) [junit4] 2> 1615948 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1615948 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect = to ZooKeeper [junit4] 2> 1615949 INFO (zkCallback-1964-thread-1) [ ] o.a.s.c.c.= ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6b= a2af name:ZooKeeperConnection Watcher:127.0.0.1:46250/solr got event Watche= dEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1615949 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeep= er [junit4] 2> 1615950 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1615950 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome = (not found in ZooKeeper) [junit4] 2> 1615950 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from= /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1= /temp/solr.cloud.HttpPartitionTest_40E288E62AAF1C35-001/shard-1-001/solr.xm= l [junit4] 2> 1615954 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root dir= ectory: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/= test/J1/temp/solr.cloud.HttpPartitionTest_40E288E62AAF1C35-001/shard-1-001/= cores [junit4] 2> 1615954 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.CoreContainer New CoreContainer 19671704 [junit4] 2> 1615954 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [in= stanceDir=3D/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-c= ore/test/J1/temp/solr.cloud.HttpPartitionTest_40E288E62AAF1C35-001/shard-1-= 001] [junit4] 2> 1615954 WARN (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkin= s/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cl= oud.HttpPartitionTest_40E288E62AAF1C35-001/shard-1-001/lib to classpath: /h= ome/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/te= mp/solr.cloud.HttpPartitionTest_40E288E62AAF1C35-001/shard-1-001/lib [junit4] 2> 1615954 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeo= ut : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxC= onnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadI= dleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 1615955 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTT= P client with params: socketTimeout=3D340000&connTimeout=3D45000&retry=3Dtr= ue [junit4] 2> 1615955 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLogge= rFactory [junit4] 2> 1615955 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.sl= f4j.impl.Log4jLoggerFactory)] [junit4] 2> 1615956 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.ZkContainer Zookeeper client=3D127.0.0.1:46250/sol= r [junit4] 2> 1615956 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1615956 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1615956 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect = to ZooKeeper [junit4] 2> 1615957 INFO (zkCallback-1967-thread-1) [ ] o.a.s.c.c.= ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1d= d166c name:ZooKeeperConnection Watcher:127.0.0.1:46250 got event WatchedEve= nt state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1615957 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeep= er [junit4] 2> 1615958 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1615958 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.ZkController Added new OnReconnect listener org.ap= ache.solr.cloud.ZkController$$Lambda$183/8568906@fec9fb [junit4] 2> 1615959 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect = to ZooKeeper [junit4] 2> 1615959 INFO (zkCallback-1968-thread-1) [ ] o.a.s.c.c.= ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@8b= b6ad name:ZooKeeperConnection Watcher:127.0.0.1:46250/solr got event Watche= dEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1615959 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeep= er [junit4] 2> 1615962 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKee= per...=20 [junit4] 2> 1615962 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 1615963 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper.= .. (0) -> (1) [junit4] 2> 1615964 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 1615964 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.LeaderElector Joined leadership election with path= : /overseer_elect/election/96141046340452360-127.0.0.1:38852_-n_0000000001 [junit4] 2> 1615964 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.LeaderElector Watching path /overseer_elect/electi= on/96141046340452356-127.0.0.1:42032_-n_0000000000 to know if I could be th= e leader [junit4] 2> 1615964 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.ZkController Publish node=3D127.0.0.1:38852_ as DO= WN [junit4] 2> 1615965 INFO (OverseerStateUpdate-96141046340452356-127.0= .0.1:42032_-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize= : 1, message =3D { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:38852_"} current state version: 3 [junit4] 2> 1615965 INFO (OverseerStateUpdate-96141046340452356-127.0= .0.1:42032_-n_0000000000) [ ] o.a.s.c.o.NodeMutator DownNode state invok= ed for node: 127.0.0.1:38852_ [junit4] 2> 1616067 INFO (zkCallback-1959-thread-2) [ ] o.a.s.c.c.= ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected typ= e:NodeDataChanged path:/clusterstate.json], has occurred - updating... (liv= e nodes size: [1]) [junit4] 2> 1616067 INFO (zkCallback-1963-thread-1) [ ] o.a.s.c.c.= ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected typ= e:NodeDataChanged path:/clusterstate.json], has occurred - updating... (liv= e nodes size: [1]) [junit4] 2> 1616069 INFO (zkCallback-1968-thread-1) [ ] o.a.s.c.c.= ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected typ= e:NodeDataChanged path:/clusterstate.json], has occurred - updating... (liv= e nodes size: [1]) [junit4] 2> 1616965 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/l= ive_nodes/127.0.0.1:38852_ [junit4] 2> 1616966 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:388= 52_ [junit4] 2> 1616967 INFO (zkCallback-1959-thread-2) [ ] o.a.s.c.c.= ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:No= deChildrenChanged path:/live_nodes], has occurred - updating... (live nodes= size: [1]) [junit4] 2> 1616967 INFO (zkCallback-1968-thread-1) [ ] o.a.s.c.c.= ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:No= deChildrenChanged path:/live_nodes], has occurred - updating... (live nodes= size: [1]) [junit4] 2> 1616967 INFO (zkCallback-1963-thread-1) [ ] o.a.s.c.c.= ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:No= deChildrenChanged path:/live_nodes], has occurred - updating... (live nodes= size: [1]) [junit4] 2> 1616967 INFO (zkCallback-1959-thread-2) [ ] o.a.s.c.c.= ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1616967 INFO (zkCallback-1968-thread-1) [ ] o.a.s.c.c.= ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1616967 INFO (zkCallback-1963-thread-1) [ ] o.a.s.c.c.= ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1616993 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skippin= g setup for authorization module. [junit4] 2> 1616993 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1616993 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions= underneath /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-c= ore/test/J1/temp/solr.cloud.HttpPartitionTest_40E288E62AAF1C35-001/shard-1-= 001/cores [junit4] 2> 1616994 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {schema=3Ds= chema.xml, dataDir=3Ddata/, collection=3Dcollection1, loadOnStartup=3Dtrue,= coreNodeName=3D, transient=3Dfalse, name=3Dcollection1, shard=3D, config= =3Dsolrconfig.xml, configSetProperties=3Dconfigsetprops.json} [junit4] 2> 1616994 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /h= ome/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/te= mp/solr.cloud.HttpPartitionTest_40E288E62AAF1C35-001/shard-1-001/cores/coll= ection1 [junit4] 2> 1616994 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 1616995 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.s.SolrDispatchFilter user.dir=3D/home/jenkins/worksp= ace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1 [junit4] 2> 1616995 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.ZkController publishing state=3Ddown [junit4] 2> 1616995 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1616995 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.ZkController numShards not found on descriptor - reading it from system= property [junit4] 2> 1616996 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.ZkController Collection collection1 not visible yet, but flagging it so= a watch is registered when it becomes visible [junit4] 2> 1616997 INFO (OverseerStateUpdate-96141046340452356-127.0= .0.1:42032_-n_0000000000) [ ] 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:38852", [junit4] 2> "node_name":"127.0.0.1:38852_", [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> 1616997 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.c.ZkStateReader Deleting data for [collection1] [junit4] 2> 1616997 INFO (OverseerStateUpdate-96141046340452356-127.0= .0.1:42032_-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Update state numS= hards=3D2 message=3D{ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:38852", [junit4] 2> "node_name":"127.0.0.1:38852_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 1616997 INFO (OverseerStateUpdate-96141046340452356-127.0= .0.1:42032_-n_0000000000) [ ] o.a.s.c.o.ClusterStateMutator building a n= ew cName: collection1 [junit4] 2> 1616997 INFO (OverseerStateUpdate-96141046340452356-127.0= .0.1:42032_-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new nod= e to shard shard=3Dshard2 [junit4] 2> 1616997 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.ZkController look for our core node name [junit4] 2> 1617098 INFO (zkCallback-1959-thread-1) [ ] o.a.s.c.c.= ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected typ= e:NodeDataChanged path:/clusterstate.json], has occurred - updating... (liv= e nodes size: [2]) [junit4] 2> 1617098 INFO (zkCallback-1968-thread-1) [ ] o.a.s.c.c.= ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected typ= e:NodeDataChanged path:/clusterstate.json], has occurred - updating... (liv= e nodes size: [2]) [junit4] 2> 1617098 INFO (zkCallback-1963-thread-1) [ ] o.a.s.c.c.= ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected typ= e:NodeDataChanged path:/clusterstate.json], has occurred - updating... (liv= e nodes size: [2]) [junit4] 2> 1617998 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1617998 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.ZkController Check for collection zkNode:collection1 [junit4] 2> 1617998 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.ZkController Collection zkNode exists [junit4] 2> 1617998 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.c.ZkStateReader Load collection config from: [/collections/collection1] [junit4] 2> 1617998 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.c.ZkStateReader path=3D[/collections/collection1] [configName]=3D[conf1= ] specified config exists in ZooKeeper [junit4] 2> 1617998 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins= /workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.clo= ud.HttpPartitionTest_40E288E62AAF1C35-001/shard-1-001/cores/collection1' [junit4] 2> 1617999 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1617999 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find syste= m property or JNDI) [junit4] 2> 1618000 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.Config loaded config solrconfig.xml with version 0=20 [junit4] 2> 1618003 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1618007 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.SolrConfig Using Lucene MatchVersion: 6.2.0 [junit4] 2> 1618014 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 1618020 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.s.IndexSchema [collection1] Schema name=3Dtest [junit4] 2> 1618112 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.s.OpenExchangeRatesOrgProvider Initialized with rates=3Dopen-exchange-rat= es.json, refreshInterval=3D1440. [junit4] 2> 1618117 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.s.IndexSchema default search field in schema is text [junit4] 2> 1618118 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.s.IndexSchema unique key field: id [junit4] 2> 1618123 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xm= l [junit4] 2> 1618125 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xm= l [junit4] 2> 1618126 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchang= e-rates.json [junit4] 2> 1618127 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchang= e-rates.json [junit4] 2> 1618128 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default= properties: Can't find resource 'configsetprops.json' in classpath or '/co= nfigs/conf1', cwd=3D/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/buil= d/solr-core/test/J1 [junit4] 2> 1618128 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.CoreContainer Creating SolrCore 'collection1' using configuration from = collection collection1 [junit4] 2> 1618128 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1618128 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/worksp= ace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.Http= PartitionTest_40E288E62AAF1C35-001/shard-1-001/cores/collection1], dataDir= =3D[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test= /J1/temp/solr.cloud.HttpPartitionTest_40E288E62AAF1C35-001/shard-1-001/core= s/collection1/data/] [junit4] 2> 1618128 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Se= rver: com.sun.jmx.mbeanserver.JmxMBeanServer@70c8d8 [junit4] 2> 1618129 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspac= e/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPa= rtitionTest_40E288E62AAF1C35-001/shard-1-001/cores/collection1/data [junit4] 2> 1618129 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.SolrCore New index directory detected: old=3Dnull new=3D/home/jenkins/w= orkspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud= .HttpPartitionTest_40E288E62AAF1C35-001/shard-1-001/cores/collection1/data/= index/ [junit4] 2> 1618129 WARN (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lu= cene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartit= ionTest_40E288E62AAF1C35-001/shard-1-001/cores/collection1/data/index' does= n't exist. Creating new index... [junit4] 2> 1618130 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspac= e/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPa= rtitionTest_40E288E62AAF1C35-001/shard-1-001/cores/collection1/data/index [junit4] 2> 1618130 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.in= dex.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=3D1000, mergeFactor= =3D24, maxMergeSize=3D9223372036854775807, maxMergeSizeForForcedMerge=3D922= 3372036854775807, calibrateSizeByDeletes=3Dfalse, maxMergeDocs=3D2147483647= , maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio=3D0.88870182399159= 25] [junit4] 2> 1618130 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@746f2b l= ockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@f17b74),segF= N=3Dsegments_1,generation=3D1} [junit4] 2> 1618130 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.SolrDeletionPolicy newest commit generation =3D 1 [junit4] 2> 1618131 INFO (OldIndexDirectoryCleanupThreadForCore-colle= ction1) [ ] o.a.s.c.SolrCore Looking for old index directories to cleanu= p for core collection1 in /home/jenkins/workspace/Lucene-Solr-6.x-Linux/sol= r/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_40E288E62AAF1C3= 5-001/shard-1-001/cores/collection1/data/ [junit4] 2> 1618131 WARN (OldIndexDirectoryCleanupThreadForCore-colle= ction1) [ ] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr= -6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_4= 0E288E62AAF1C35-001/shard-1-001/cores/collection1/data/ does not point to a= valid data directory; skipping clean-up of old index directories. [junit4] 2> 1618134 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nod= istrib" [junit4] 2> 1618134 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "ded= upe" [junit4] 2> 1618134 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFacto= ry into updateRequestProcessorChain "dedupe" [junit4] 2> 1618134 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "sto= red_sig" [junit4] 2> 1618134 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFacto= ry into updateRequestProcessorChain "stored_sig" [junit4] 2> 1618134 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dis= trib-dup-test-chain-explicit" [junit4] 2> 1618134 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dis= trib-dup-test-chain-implicit" [junit4] 2> 1618134 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFacto= ry into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1618134 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.SolrCore no updateRequestProcessorChain defined as default, creating im= plicit default [junit4] 2> 1618134 WARN (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.RequestHandlers INVALID paramSet a in requestHandler {type =3D requestH= andler,name =3D /dump,class =3D DumpRequestHandler,args =3D {defaults=3D{a= =3DA,b=3DB}}} [junit4] 2> 1618136 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1618137 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1618138 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1618139 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1618146 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/update/json/d= ocs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,= /dump,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,/c= onfig,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/ad= min/file [junit4] 2> 1618146 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.L= ocalStatsCache [junit4] 2> 1618146 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.Up= dateLog [junit4] 2> 1618146 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.u.UpdateLog Initializing UpdateLog: dataDir=3D defaultSyncLevel=3DFLUSH n= umRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 1618147 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1618147 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1618148 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.in= dex.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=3D1677721= , mergeFactor=3D19, maxMergeSize=3D2147483648, maxMergeSizeForForcedMerge= =3D9223372036854775807, calibrateSizeByDeletes=3Dfalse, maxMergeDocs=3D2147= 483647, maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio=3D1.0] [junit4] 2> 1618148 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@746f2b l= ockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@f17b74),segF= N=3Dsegments_1,generation=3D1} [junit4] 2> 1618148 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.SolrDeletionPolicy newest commit generation =3D 1 [junit4] 2> 1618148 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.s.SolrIndexSearcher Opening [Searcher@134aeef[collection1] main] [junit4] 2> 1618148 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.c.ZkStateReader Load collection config from: [/collections/collection1] [junit4] 2> 1618148 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.c.ZkStateReader path=3D[/collections/collection1] [configName]=3D[conf1= ] specified config exists in ZooKeeper [junit4] 2> 1618148 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the RestM= anager with znodeBase: /configs/conf1 [junit4] 2> 1618149 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /c= onfigs/conf1 [junit4] 2> 1618149 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 1618149 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.r.ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStorag= eIO:path=3D/configs/conf1 [junit4] 2> 1618149 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.r.ManagedResourceStorage No data found for znode /configs/conf1/_rest_man= aged.json [junit4] 2> 1618149 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using Zoo= KeeperStorageIO:path=3D/configs/conf1 [junit4] 2> 1618149 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1618149 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1618151 INFO (searcherExecutor-6338-thread-1) [ ] o.a.= s.c.SolrCore [collection1] Registered new searcher Searcher@134aeef[collect= ion1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1618151 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.u.UpdateLog Looking up max value of version field to seed version buckets [junit4] 2> 1618151 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buc= kets from index [junit4] 2> 1618151 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.u.VersionInfo No terms found for _version_, cannot seed version bucket hi= ghest value from index [junit4] 2> 1618151 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.u.UpdateLog Could not find max version in index or recent updates, using = new clock 1538256745676144640 [junit4] 2> 1618153 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 15382= 56745676144640 [junit4] 2> 1618153 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1618153 INFO (coreLoadExecutor-6337-thread-1) [ ] o.a.= s.c.CoreContainer registering core: collection1 [junit4] 2> 1618154 INFO (coreZkRegister-6332-thread-1) [ ] o.a.s.= c.ZkController Register replica - core:collection1 address:http://127.0.0.1= :38852 collection:collection1 shard:shard2 [junit4] 2> 1618154 INFO (coreZkRegister-6332-thread-1) [ ] o.a.s.= c.c.SolrZkClient makePath: /collections/collection1/leader_elect/shard2/ele= ction [junit4] 2> 1618155 INFO (coreZkRegister-6332-thread-1) [ ] o.a.s.= c.LeaderElector Joined leadership election with path: /collections/collecti= on1/leader_elect/shard2/election/96141046340452360-core_node1-n_0000000000 [junit4] 2> 1618155 INFO (coreZkRegister-6332-thread-1) [ ] o.a.s.= c.ShardLeaderElectionContext Running the leader process for shard=3Dshard2 = and weAreReplacement=3Dfalse and leaderVoteWait=3D10000 [junit4] 2> 1618156 INFO (coreZkRegister-6332-thread-1) [ ] o.a.s.= c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1618156 INFO (coreZkRegister-6332-thread-1) [ ] o.a.s.= c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1618156 INFO (coreZkRegister-6332-thread-1) [ ] o.a.s.= c.SyncStrategy Sync replicas to http://127.0.0.1:38852/collection1/ [junit4] 2> 1618156 INFO (coreZkRegister-6332-thread-1) [ ] o.a.s.= c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1618156 INFO (OverseerStateUpdate-96141046340452356-127.0= .0.1:42032_-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize= : 1, message =3D { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1"} current state version: 5 [junit4] 2> 1618156 INFO (coreZkRegister-6332-thread-1) [ ] o.a.s.= c.SyncStrategy http://127.0.0.1:38852/collection1/ has no replicas [junit4] 2> 1618157 INFO (coreZkRegister-6332-thread-1) [ ] o.a.s.= c.c.SolrZkClient makePath: /collections/collection1/leaders/shard2 [junit4] 2> 1618158 INFO (coreZkRegister-6332-thread-1) [ ] o.a.s.= c.ShardLeaderElectionContextBase Creating leader registration node /collect= ions/collection1/leaders/shard2/leader after winning as /collections/collec= tion1/leader_elect/shard2/election/96141046340452360-core_node1-n_000000000= 0 [junit4] 2> 1618159 INFO (coreZkRegister-6332-thread-1) [ ] o.a.s.= c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:38852/co= llection1/ shard2 [junit4] 2> 1618159 INFO (OverseerStateUpdate-96141046340452356-127.0= .0.1:42032_-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize= : 1, message =3D { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "base_url":"http://127.0.0.1:38852", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 5 [junit4] 2> 1618261 INFO (zkCallback-1959-thread-2) [ ] o.a.s.c.c.= ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected typ= e:NodeDataChanged path:/clusterstate.json], has occurred - updating... (liv= e nodes size: [2]) [junit4] 2> 1618261 INFO (zkCallback-1968-thread-1) [ ] o.a.s.c.c.= ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected typ= e:NodeDataChanged path:/clusterstate.json], has occurred - updating... (liv= e nodes size: [2]) [junit4] 2> 1618261 INFO (zkCallback-1963-thread-1) [ ] o.a.s.c.c.= ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected typ= e:NodeDataChanged path:/clusterstate.json], has occurred - updating... (liv= e nodes size: [2]) [junit4] 2> 1618310 INFO (coreZkRegister-6332-thread-1) [ ] o.a.s.= c.ZkController We are http://127.0.0.1:38852/collection1/ and leader is htt= p://127.0.0.1:38852/collection1/ [junit4] 2> 1618310 INFO (coreZkRegister-6332-thread-1) [ ] o.a.s.= c.ZkController No LogReplay needed for core=3Dcollection1 baseURL=3Dhttp://= 127.0.0.1:38852 [junit4] 2> 1618310 INFO (coreZkRegister-6332-thread-1) [ ] o.a.s.= c.ZkController I am the leader, no recovery necessary [junit4] 2> 1618310 INFO (coreZkRegister-6332-thread-1) [ ] o.a.s.= c.ZkController publishing state=3Dactive [junit4] 2> 1618310 INFO (coreZkRegister-6332-thread-1) [ ] o.a.s.= c.ZkController numShards not found on descriptor - reading it from system p= roperty [junit4] 2> 1618311 INFO (OverseerStateUpdate-96141046340452356-127.0= .0.1:42032_-n_0000000000) [ ] 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:38852", [junit4] 2> "node_name":"127.0.0.1:38852_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 6 [junit4] 2> 1618311 INFO (OverseerStateUpdate-96141046340452356-127.0= .0.1:42032_-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Update state numS= hards=3D2 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:38852", [junit4] 2> "node_name":"127.0.0.1:38852_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 1618413 INFO (zkCallback-1959-thread-1) [ ] o.a.s.c.c.= ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected typ= e:NodeDataChanged path:/clusterstate.json], has occurred - updating... (liv= e nodes size: [2]) [junit4] 2> 1618413 INFO (zkCallback-1963-thread-1) [ ] o.a.s.c.c.= ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected typ= e:NodeDataChanged path:/clusterstate.json], has occurred - updating... (liv= e nodes size: [2]) [junit4] 2> 1618413 INFO (zkCallback-1968-thread-1) [ ] o.a.s.c.c.= ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected typ= e:NodeDataChanged path:/clusterstate.json], has occurred - updating... (liv= e nodes size: [2]) [junit4] 2> 1618559 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home= /jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/= solr.cloud.HttpPartitionTest_40E288E62AAF1C35-001/shard-2-001/cores/collect= ion1 [junit4] 2> 1618560 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in di= rectory /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/= test/J1/temp/solr.cloud.HttpPartitionTest_40E288E62AAF1C35-001/shard-2-001 [junit4] 2> 1618560 INFO (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.e.j.s.Server jetty- [...truncated too long message...] 2> =092=09/solr/collections/c8n_1x2/state.json [junit4] 2> =092=09/solr/collections/c8n_crud_1x2/state.json [junit4] 2> =092=09/solr/collections/c8n_1x2_leader_session_loss/state= .json [junit4] 2>=20 [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2>=20 [junit4] 2> =095=09/solr/clusterstate.json [junit4] 2>=20 [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2>=20 [junit4] 2> =09140=09/solr/overseer/collection-queue-work [junit4] 2> =0967=09/solr/overseer/queue [junit4] 2> =0923=09/solr/overseer/queue-work [junit4] 2> =095=09/solr/live_nodes [junit4] 2> =095=09/solr/collections [junit4] 2>=20 [junit4] 2> 1777306 WARN (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.SocketProxy Closing 5 connections to: http://127.0= .0.1:37319/, target: http://127.0.0.1:33243/ [junit4] 2> 1777306 WARN (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.SocketProxy Closing 15 connections to: http://127.= 0.0.1:38852/, target: http://127.0.0.1:42804/ [junit4] 2> 1777306 WARN (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.SocketProxy Closing 18 connections to: http://127.= 0.0.1:42032/, target: http://127.0.0.1:35877/ [junit4] 2> 1777306 WARN (TEST-HttpPartitionTest.test-seed#[40E288E62= AAF1C35]) [ ] o.a.s.c.SocketProxy Closing 5 connections to: http://127.0= .0.1:34223/, target: http://127.0.0.1:40822/ [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DHttpPartition= Test -Dtests.method=3Dtest -Dtests.seed=3D40E288E62AAF1C35 -Dtests.multipli= er=3D3 -Dtests.slow=3Dtrue -Dtests.locale=3Dln-CG -Dtests.timezone=3DAmeric= a/Cayenne -Dtests.asserts=3Dtrue -Dtests.file.encoding=3DISO-8859-1 [junit4] FAILURE 163s J1 | HttpPartitionTest.test <<< [junit4] > Throwable #1: java.lang.AssertionError: Doc with id=3D2 no= t found in http://127.0.0.1:42032/c8n_1x2_leader_session_loss due to: Path = not found: /id; rsp=3D{doc=3Dnull} [junit4] > =09at __randomizedtesting.SeedInfo.seed([40E288E62AAF1C35:= C8B6B73C845371CD]:0) [junit4] > =09at org.apache.solr.cloud.HttpPartitionTest.assertDocExi= sts(HttpPartitionTest.java:603) [junit4] > =09at org.apache.solr.cloud.HttpPartitionTest.assertDocsEx= istInAllReplicas(HttpPartitionTest.java:558) [junit4] > =09at org.apache.solr.cloud.HttpPartitionTest.testLeaderZk= SessionLoss(HttpPartitionTest.java:506) [junit4] > =09at org.apache.solr.cloud.HttpPartitionTest.test(HttpPar= titionTest.java:119) [junit4] > =09at jdk.internal.reflect.NativeMethodAccessorImpl.invoke= 0(java.base@9-ea/Native Method) [junit4] > =09at jdk.internal.reflect.NativeMethodAccessorImpl.invoke= (java.base@9-ea/NativeMethodAccessorImpl.java:62) [junit4] > =09at jdk.internal.reflect.DelegatingMethodAccessorImpl.in= voke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43) [junit4] > =09at org.apache.solr.BaseDistributedSearchTestCase$Shards= RepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase= .java:992) [junit4] > =09at org.apache.solr.BaseDistributedSearchTestCase$Shards= RepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967) [junit4] > =09at java.lang.Thread.run(java.base@9-ea/Thread.java:843) [junit4] 2> 1777309 INFO (SUITE-HttpPartitionTest-seed#[40E288E62AAF1= C35]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/wo= rkspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.= HttpPartitionTest_40E288E62AAF1C35-001 [junit4] 2> NOTE: test params are: codec=3DAsserting(Lucene62): {range= _facet_l_dv=3DFSTOrd50, _version_=3DPostingsFormat(name=3DMemory doPackFST= =3D true), multiDefault=3DPostingsFormat(name=3DLuceneVarGapFixedInterval),= a_t=3DPostingsFormat(name=3DLuceneVarGapFixedInterval), intDefault=3DPosti= ngsFormat(name=3DMemory doPackFST=3D true), id=3DFSTOrd50, range_facet_i_dv= =3DPostingsFormat(name=3DLuceneVarGapFixedInterval), text=3DFST50, range_fa= cet_l=3DPostingsFormat(name=3DLuceneVarGapFixedInterval), timestamp=3DPosti= ngsFormat(name=3DLuceneVarGapFixedInterval)}, docValues:{range_facet_l_dv= =3DDocValuesFormat(name=3DAsserting), range_facet_i_dv=3DDocValuesFormat(na= me=3DDirect), timestamp=3DDocValuesFormat(name=3DDirect)}, maxPointsInLeafN= ode=3D1623, maxMBSortInHeap=3D6.83860260924867, sim=3DRandomSimilarity(quer= yNorm=3Dfalse,coord=3Dyes): {}, locale=3Dln-CG, timezone=3DAmerica/Cayenne [junit4] 2> NOTE: Linux 4.4.0-24-generic i386/Oracle Corporation 9-ea = (32-bit)/cpus=3D12,threads=3D1,free=3D124591896,total=3D317825024 [junit4] 2> NOTE: All tests run in this JVM: [SortByFunctionTest, Hdfs= DirectoryTest, TestIntervalFaceting, BlockJoinFacetDistribTest, CollectionR= eloadTest, TestRestoreCore, HdfsCollectionsAPIDistributedZkTest, SpellCheck= CollatorTest, ConvertedLegacyTest, DistribCursorPagingTest, ConjunctionSolr= SpellCheckerTest, OpenCloseCoreStressTest, FullHLLTest, TestUniqueKeyFieldR= esource, TestSubQueryTransformerDistrib, DirectSolrSpellCheckerTest, CSVReq= uestHandlerTest, TestLFUCache, TestBackupRepositoryFactory, TestDownShardTo= lerantSearch, DocumentBuilderTest, SolrInfoMBeanTest, AsyncMigrateRouteKeyT= est, MultiTermTest, CdcrReplicationHandlerTest, TestQuerySenderListener, Te= stMaxScoreQueryParser, BadCopyFieldTest, HdfsThreadLeakTest, TestStressVers= ions, TestDistributedStatsComponentCardinality, TestOnReconnectListenerSupp= ort, TestSolrCLIRunExample, DeleteReplicaTest, SpatialHeatmapFacetsTest, Sa= slZkACLProviderTest, TestManagedSchema, HighlighterTest, TestSolrDeletionPo= licy2, TestBM25SimilarityFactory, TestSmileRequest, DateRangeFieldTest, ZkC= LITest, TestCloudDeleteByQuery, OpenExchangeRatesOrgProviderTest, SpellingQ= ueryConverterTest, ZkSolrClientTest, SimplePostToolTest, TermsComponentTest= , TestNamedUpdateProcessors, DistributedIntervalFacetingTest, RuleEngineTes= t, TestPartialUpdateDeduplication, OutOfBoxZkACLAndCredentialsProvidersTest= , TestConfigSetProperties, MBeansHandlerTest, TestCollectionAPI, TestExactS= tatsCache, TestSystemIdResolver, TriLevelCompositeIdRoutingTest, TestFieldC= acheWithThreads, WordBreakSolrSpellCheckerTest, FastVectorHighlighterTest, = ConfigSetsAPITest, TestFieldCacheSort, TestQueryUtils, DistributedTermsComp= onentTest, AliasIntegrationTest, TestSSLRandomization, DOMUtilTest, TestNRT= Open, TestSerializedLuceneMatchVersion, TestOverriddenPrefixQueryForCustomF= ieldType, TestXmlQParser, TestStandardQParsers, TestSolrConfigHandler, Test= TolerantUpdateProcessorCloud, MinimalSchemaTest, TestFileDictionaryLookup, = DistributedFacetPivotLargeTest, TestReplicaProperties, IgnoreCommitOptimize= UpdateProcessorFactoryTest, TestCodecSupport, LeaderInitiatedRecoveryOnShar= dRestartTest, TestQueryTypes, TestGraphTermsQParserPlugin, ShardRoutingCust= omTest, TestDocBasedVersionConstraints, TestDFRSimilarityFactory, Analytics= QueryTest, TestDynamicFieldResource, TestGeoJSONResponseWriter, HdfsBasicDi= stributedZkTest, MoreLikeThisHandlerTest, RequestLoggingTest, TestMiniSolrC= loudClusterBase, LeaderElectionIntegrationTest, ShardRoutingTest, FullSolrC= loudDistribCmdsTest, ClusterStateUpdateTest, TestRandomFaceting, AutoCommit= Test, TestDistributedGrouping, TestFaceting, TestHashPartitioner, TermVecto= rComponentDistributedTest, ZkControllerTest, TestRealTimeGet, HardAutoCommi= tTest, TestCoreContainer, TestSolr4Spatial, StatsComponentTest, TestGroupin= gSearch, QueryElevationComponentTest, BasicFunctionalityTest, TestLazyCores= , DirectUpdateHandlerTest, SoftAutoCommitTest, ShowFileRequestHandlerTest, = DistributedQueryElevationComponentTest, SolrIndexSplitterTest, SignatureUpd= ateProcessorFactoryTest, CoreAdminHandlerTest, TestStressLucene, TestTrie, = SpatialFilterTest, TestCSVLoader, NoCacheHeaderTest, SchemaVersionSpecificB= ehaviorTest, TestUpdate, TestWordDelimiterFilterFactory, DefaultValueUpdate= ProcessorTest, StatelessScriptUpdateProcessorFactoryTest, DocValuesMultiTes= t, DistanceFunctionTest, TestSolrDeletionPolicy1, IndexBasedSpellCheckerTes= t, TestWriterPerf, TestOmitPositions, TestValueSourceCache, TestIndexingPer= formance, TestSolrQueryParser, SolrPluginUtilsTest, UpdateRequestProcessorF= actoryTest, TestAnalyzedSuggestions, SearchHandlerTest, TestPostingsSolrHig= hlighter, TestLuceneMatchVersion, SpellPossibilityIteratorTest, TestCharFil= ters, TestXIncludeConfig, TestLMDirichletSimilarityFactory, TimeZoneUtilsTe= st, PluginInfoTest, DateMathParserTest, TestSuggestSpellingConverter, DateF= ieldTest, ClusterStateTest, TestLRUCache, TestUtils, TestDocumentBuilder, S= liceStateTest, SystemInfoHandlerTest, UUIDFieldTest, FileUtilsTest, Distrib= utedMLTComponentTest, TestHighlightDedupGrouping, TestSimpleTrackingShardHa= ndler, CollectionStateFormat2Test, CollectionsAPISolrJTest, DistributedVers= ionInfoTest, HttpPartitionTest] [junit4] Completed [473/617 (1!)] on J1 in 163.22s, 1 test, 1 failure <<= < FAILURES! [...truncated 493 lines...] BUILD FAILED /home/jenkins/workspace/Lucene-Solr-6.x-Linux/build.xml:740: The following = error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/build.xml:684: The following = error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/build.xml:59: The following e= rror occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build.xml:233: The follo= wing error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/common-build.xml:530: Th= e following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/lucene/common-build.xml:1427:= The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/lucene/common-build.xml:984: = There were test failures: 617 suites (10 ignored), 2597 tests, 1 failure, 1= 19 ignored (72 assumptions) [seed: 40E288E62AAF1C35] Total time: 65 minutes 26 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts [WARNINGS] Skipping publisher since build result is FAILURE Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any ------=_Part_26_311064946.1466996606188 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_26_311064946.1466996606188--