Return-Path: X-Original-To: apmail-lucene-dev-archive@www.apache.org Delivered-To: apmail-lucene-dev-archive@www.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id 696CC1795A for ; Tue, 31 Mar 2015 23:50:30 +0000 (UTC) Received: (qmail 56858 invoked by uid 500); 31 Mar 2015 23:50:29 -0000 Delivered-To: apmail-lucene-dev-archive@lucene.apache.org Received: (qmail 56793 invoked by uid 500); 31 Mar 2015 23:50:29 -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 56777 invoked by uid 99); 31 Mar 2015 23:50:29 -0000 Received: from athena.apache.org (HELO athena.apache.org) (140.211.11.136) by apache.org (qpsmtpd/0.29) with ESMTP; Tue, 31 Mar 2015 23:50:29 +0000 X-ASF-Spam-Status: No, hits=0.0 required=5.0 tests=FSL_HELO_NON_FQDN_1,SPF_PASS,WEIRD_PORT X-Spam-Check-By: apache.org Received-SPF: pass (athena.apache.org: domain of jenkins@thetaphi.de designates 188.138.57.78 as permitted sender) Received: from [188.138.57.78] (HELO serv1) (188.138.57.78) by apache.org (qpsmtpd/0.29) with ESMTP; Tue, 31 Mar 2015 23:50:22 +0000 Received: from localhost.localdomain ([127.0.0.1] helo=serv1) by serv1 with esmtp (Exim 4.82) (envelope-from ) id 1Yd5ut-00046j-B1 for dev@lucene.apache.org; Tue, 31 Mar 2015 23:49:55 +0000 Date: Tue, 31 Mar 2015 23:49:40 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org Message-ID: <1552762608.203.1427845795339.JavaMail.jenkins@serv1> Subject: [JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.8.0_40) - Build # 12173 - Failure! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_202_1959334902.1427845780425" X-Jenkins-Job: Lucene-Solr-trunk-Linux X-Jenkins-Result: FAILURE X-Virus-Checked: Checked by ClamAV on apache.org ------=_Part_202_1959334902.1427845780425 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/12173/ Java: 32bit/jdk1.8.0_40 -client -XX:+UseParallelGC 1 tests failed. FAILED: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.test Error Message: Error from server at http://127.0.0.1:60061/s/compositeid_collection_with_r= outerfield_shard1_replica1: no servers hosting shard:=20 Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error= from server at http://127.0.0.1:60061/s/compositeid_collection_with_router= field_shard1_replica1: no servers hosting shard:=20 =09at __randomizedtesting.SeedInfo.seed([900E65E336ABF401:185A5A39985799F9]= :0) =09at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSo= lrClient.java:556) =09at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClie= nt.java:233) =09at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClie= nt.java:225) =09at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135= ) =09at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:943) =09at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:958) =09at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.testDeleteByIdComp= ositeRouterWithRouterField(FullSolrCloudDistribCmdsTest.java:357) =09at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.test(FullSolrCloud= DistribCmdsTest.java:146) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:62) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:497) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1627) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:836) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:872) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:886) =09at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= FixedStatement.callStatement(BaseDistributedSearchTestCase.java:960) =09at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= Statement.evaluate(BaseDistributedSearchTestCase.java:935) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:53) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:50) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:46) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:49) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:65) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:48) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:365) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:798) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:458) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:845) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(Random= izedRunner.java:747) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(Random= izedRunner.java:781) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:792) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:53) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:46) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:42) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:54) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:65) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:55) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:365) =09at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 9563 lines...] [junit4] Suite: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-trun= k-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCm= dsTest 900E65E336ABF401-001/init-core-data-001 [junit4] 2> 357604 T2635 oas.BaseDistributedSearchTestCase.initHostCon= text Setting hostContext system property: /s/ [junit4] 2> 357606 T2635 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 357606 T2636 oasc.ZkTestServer$ZKServerMain.runFromConfig = Starting server [junit4] 2> 357706 T2635 oasc.ZkTestServer.run start zk server on port= :43312 [junit4] 2> 357707 T2635 oascc.SolrZkClient.createZkCredentialsToAddAu= tomatically Using default ZkCredentialsProvider [junit4] 2> 357707 T2635 oascc.ConnectionManager.waitForConnected Wait= ing for client to connect to ZooKeeper [junit4] 2> 357710 T2643 oascc.ConnectionManager.process Watcher org.a= pache.solr.common.cloud.ConnectionManager@1214d42 name:ZooKeeperConnection = Watcher:127.0.0.1:43312 got event WatchedEvent state:SyncConnected type:Non= e path:null path:null type:None [junit4] 2> 357710 T2635 oascc.ConnectionManager.waitForConnected Clie= nt is connected to ZooKeeper [junit4] 2> 357710 T2635 oascc.SolrZkClient.createZkACLProvider Using = default ZkACLProvider [junit4] 2> 357710 T2635 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 357713 T2635 oascc.SolrZkClient.createZkCredentialsToAddAu= tomatically Using default ZkCredentialsProvider [junit4] 2> 357713 T2635 oascc.ConnectionManager.waitForConnected Wait= ing for client to connect to ZooKeeper [junit4] 2> 357714 T2646 oascc.ConnectionManager.process Watcher org.a= pache.solr.common.cloud.ConnectionManager@a27bf0 name:ZooKeeperConnection W= atcher:127.0.0.1:43312/solr got event WatchedEvent state:SyncConnected type= :None path:null path:null type:None [junit4] 2> 357714 T2635 oascc.ConnectionManager.waitForConnected Clie= nt is connected to ZooKeeper [junit4] 2> 357715 T2635 oascc.SolrZkClient.createZkACLProvider Using = default ZkACLProvider [junit4] 2> 357715 T2635 oascc.SolrZkClient.makePath makePath: /collec= tions/collection1 [junit4] 2> 357716 T2635 oascc.SolrZkClient.makePath makePath: /collec= tions/collection1/shards [junit4] 2> 357718 T2635 oascc.SolrZkClient.makePath makePath: /collec= tions/control_collection [junit4] 2> 357719 T2635 oascc.SolrZkClient.makePath makePath: /collec= tions/control_collection/shards [junit4] 2> 357720 T2635 oasc.AbstractZkTestCase.putConfig put /home/j= enkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/coll= ection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 357720 T2635 oascc.SolrZkClient.makePath makePath: /config= s/conf1/solrconfig.xml [junit4] 2> 357722 T2635 oasc.AbstractZkTestCase.putConfig put /home/j= enkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/coll= ection1/conf/schema15.xml to /configs/conf1/schema.xml [junit4] 2> 357722 T2635 oascc.SolrZkClient.makePath makePath: /config= s/conf1/schema.xml [junit4] 2> 357724 T2635 oasc.AbstractZkTestCase.putConfig put /home/j= enkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/coll= ection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/sol= rconfig.snippet.randomindexconfig.xml [junit4] 2> 357724 T2635 oascc.SolrZkClient.makePath makePath: /config= s/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 357725 T2635 oasc.AbstractZkTestCase.putConfig put /home/j= enkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/coll= ection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 357726 T2635 oascc.SolrZkClient.makePath makePath: /config= s/conf1/stopwords.txt [junit4] 2> 357727 T2635 oasc.AbstractZkTestCase.putConfig put /home/j= enkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/coll= ection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 357727 T2635 oascc.SolrZkClient.makePath makePath: /config= s/conf1/protwords.txt [junit4] 2> 357729 T2635 oasc.AbstractZkTestCase.putConfig put /home/j= enkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/coll= ection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 357729 T2635 oascc.SolrZkClient.makePath makePath: /config= s/conf1/currency.xml [junit4] 2> 357730 T2635 oasc.AbstractZkTestCase.putConfig put /home/j= enkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/coll= ection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 357730 T2635 oascc.SolrZkClient.makePath makePath: /config= s/conf1/enumsConfig.xml [junit4] 2> 357732 T2635 oasc.AbstractZkTestCase.putConfig put /home/j= enkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/coll= ection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates= .json [junit4] 2> 357732 T2635 oascc.SolrZkClient.makePath makePath: /config= s/conf1/open-exchange-rates.json [junit4] 2> 357733 T2635 oasc.AbstractZkTestCase.putConfig put /home/j= enkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/coll= ection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin= 1Accent.txt [junit4] 2> 357733 T2635 oascc.SolrZkClient.makePath makePath: /config= s/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 357734 T2635 oasc.AbstractZkTestCase.putConfig put /home/j= enkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/coll= ection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 357735 T2635 oascc.SolrZkClient.makePath makePath: /config= s/conf1/old_synonyms.txt [junit4] 2> 357736 T2635 oasc.AbstractZkTestCase.putConfig put /home/j= enkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/coll= ection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 357736 T2635 oascc.SolrZkClient.makePath makePath: /config= s/conf1/synonyms.txt [junit4] 2> 357796 T2635 oas.SolrTestCaseJ4.writeCoreProperties Writin= g core.properties file to /home/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest 90= 0E65E336ABF401-001/control-001/cores/collection1 [junit4] 2> 357797 T2635 oejs.Server.doStart jetty-9.2.9.v20150224 [junit4] 2> 357798 T2635 oejsh.ContextHandler.doStart Started o.e.j.s.= ServletContextHandler@13d6db4{/s,null,AVAILABLE} [junit4] 2> 357799 T2635 oejs.AbstractConnector.doStart Started Server= Connector@128431f{HTTP/1.1}{127.0.0.1:38272} [junit4] 2> 357799 T2635 oejs.Server.doStart Started @358685ms [junit4] 2> 357799 T2635 oascse.JettySolrRunner$1.lifeCycleStarted Jet= ty properties: {solr.data.dir=3D/home/jenkins/workspace/Lucene-Solr-trunk-L= inux/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsT= est 900E65E336ABF401-001/tempDir-001/control/data, hostContext=3D/s, hostPo= rt=3D38272, coreRootDirectory=3D/home/jenkins/workspace/Lucene-Solr-trunk-L= inux/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsT= est 900E65E336ABF401-001/control-001/cores} [junit4] 2> 357800 T2635 oass.SolrDispatchFilter.init SolrDispatchFilt= er.init()sun.misc.Launcher$AppClassLoader@e2f2a [junit4] 2> 357800 T2635 oasc.SolrResourceLoader. new SolrResour= ceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/so= lr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest 900= E65E336ABF401-001/control-001/' [junit4] 2> 357817 T2635 oasc.SolrXmlConfig.fromFile Loading container= configuration from /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/bu= ild/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest 900E65E3= 36ABF401-001/control-001/solr.xml [junit4] 2> 357822 T2635 oasc.CorePropertiesLocator. Config-defi= ned core root directory: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/so= lr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest 900= E65E336ABF401-001/control-001/cores [junit4] 2> 357822 T2635 oasc.CoreContainer. New CoreContainer 1= 822385 [junit4] 2> 357822 T2635 oasc.CoreContainer.load Loading cores into Co= reContainer [instanceDir=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/= solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest 9= 00E65E336ABF401-001/control-001/] [junit4] 2> 357822 T2635 oasc.CoreContainer.load loading shared librar= y: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/tes= t/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest 900E65E336ABF401-001/cont= rol-001/lib [junit4] 2> 357823 T2635 oasc.SolrResourceLoader.addToClassLoader WARN= Can't find (or read) directory to add to classloader: lib (resolved as: /h= ome/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/= temp/solr.cloud.FullSolrCloudDistribCmdsTest 900E65E336ABF401-001/control-0= 01/lib). [junit4] 2> 357829 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting socketTimeout to: 90000 [junit4] 2> 357829 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting urlScheme to:=20 [junit4] 2> 357829 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting connTimeout to: 15000 [junit4] 2> 357829 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting maxConnectionsPerHost to: 20 [junit4] 2> 357830 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting maxConnections to: 10000 [junit4] 2> 357830 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting corePoolSize to: 0 [junit4] 2> 357830 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting maximumPoolSize to: 2147483647 [junit4] 2> 357830 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting maxThreadIdleTime to: 5 [junit4] 2> 357831 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting sizeOfQueue to: -1 [junit4] 2> 357831 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting fairnessPolicy to: false [junit4] 2> 357831 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting useRetries to: false [junit4] 2> 357832 T2635 oasu.UpdateShardHandler. Creating Updat= eShardHandler HTTP client with params: socketTimeout=3D340000&connTimeout= =3D45000&retry=3Dtrue [junit4] 2> 357832 T2635 oasl.LogWatcher.createWatcher SLF4J impl is o= rg.slf4j.impl.Log4jLoggerFactory [junit4] 2> 357832 T2635 oasl.LogWatcher.newRegisteredLogWatcher Regis= tering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 357832 T2635 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 357833 T2635 oasc.ZkContainer.initZooKeeper Zookeeper clie= nt=3D127.0.0.1:43312/solr [junit4] 2> 357833 T2635 oasc.ZkController.checkChrootPath zkHost incl= udes chroot [junit4] 2> 357834 T2635 oascc.SolrZkClient.createZkCredentialsToAddAu= tomatically Using default ZkCredentialsProvider [junit4] 2> 357834 T2635 oascc.ConnectionManager.waitForConnected Wait= ing for client to connect to ZooKeeper [junit4] 2> 357836 T2660 oascc.ConnectionManager.process Watcher org.a= pache.solr.common.cloud.ConnectionManager@a2883b name:ZooKeeperConnection W= atcher:127.0.0.1:43312 got event WatchedEvent state:SyncConnected type:None= path:null path:null type:None [junit4] 2> 357836 T2635 oascc.ConnectionManager.waitForConnected Clie= nt is connected to ZooKeeper [junit4] 2> 357837 T2635 oascc.SolrZkClient.createZkACLProvider Using = default ZkACLProvider [junit4] 2> 357838 T2635 oascc.ConnectionManager.waitForConnected Wait= ing for client to connect to ZooKeeper [junit4] 2> 357839 T2663 oascc.ConnectionManager.process Watcher org.a= pache.solr.common.cloud.ConnectionManager@c76e22 name:ZooKeeperConnection W= atcher:127.0.0.1:43312/solr got event WatchedEvent state:SyncConnected type= :None path:null path:null type:None [junit4] 2> 357839 T2635 oascc.ConnectionManager.waitForConnected Clie= nt is connected to ZooKeeper [junit4] 2> 357840 T2635 oascc.SolrZkClient.makePath makePath: /overse= er/queue [junit4] 2> 357842 T2635 oascc.SolrZkClient.makePath makePath: /overse= er/collection-queue-work [junit4] 2> 357844 T2635 oascc.SolrZkClient.makePath makePath: /overse= er/collection-map-running [junit4] 2> 357845 T2635 oascc.SolrZkClient.makePath makePath: /overse= er/collection-map-completed [junit4] 2> 357847 T2635 oascc.SolrZkClient.makePath makePath: /overse= er/collection-map-failure [junit4] 2> 357848 T2635 oascc.SolrZkClient.makePath makePath: /live_n= odes [junit4] 2> 357849 T2635 oascc.SolrZkClient.makePath makePath: /aliase= s.json [junit4] 2> 357850 T2635 oascc.SolrZkClient.makePath makePath: /cluste= rstate.json [junit4] 2> 357851 T2635 oasc.ZkController.createEphemeralLiveNode Reg= ister node as live in ZooKeeper:/live_nodes/127.0.0.1:38272_s [junit4] 2> 357852 T2635 oascc.SolrZkClient.makePath makePath: /live_n= odes/127.0.0.1:38272_s [junit4] 2> 357853 T2635 oascc.SolrZkClient.makePath makePath: /overse= er_elect [junit4] 2> 357854 T2635 oascc.SolrZkClient.makePath makePath: /overse= er_elect/election [junit4] 2> 357855 T2635 oasc.Overseer.close Overseer (id=3Dnull) clos= ing [junit4] 2> 357856 T2635 oasc.OverseerElectionContext.runLeaderProcess= I am going to be the leader 127.0.0.1:38272_s [junit4] 2> 357857 T2635 oascc.SolrZkClient.makePath makePath: /overse= er_elect/leader [junit4] 2> 357858 T2635 oasc.Overseer.start Overseer (id=3D9357522676= 2788867-127.0.0.1:38272_s-n_0000000000) starting [junit4] 2> 357859 T2635 oascc.SolrZkClient.makePath makePath: /overse= er/queue-work [junit4] 2> 357863 T2635 oasc.OverseerAutoReplicaFailoverThread.= Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDela= y=3D10000 autoReplicaFailoverWaitAfterExpiration=3D30000 autoReplicaFailove= rBadNodeExpiration=3D60000 [junit4] 2> 357864 T2665 oasc.OverseerCollectionProcessor.run Process = current queue of collection creations [junit4] 2> 357864 T2635 oascc.ZkStateReader.createClusterStateWatcher= sAndUpdate Updating cluster state from ZooKeeper...=20 [junit4] 2> 357864 T2664 oasc.Overseer$ClusterStateUpdater.run Startin= g to work on the main queue [junit4] 2> 357866 T2635 oasc.CorePropertiesLocator.discover Looking f= or core definitions underneath /home/jenkins/workspace/Lucene-Solr-trunk-Li= nux/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTe= st 900E65E336ABF401-001/control-001/cores [junit4] 2> 357867 T2635 oasc.CoreDescriptor. CORE DESCRIPTOR: {= name=3Dcollection1, config=3Dsolrconfig.xml, transient=3Dfalse, schema=3Dsc= hema.xml, loadOnStartup=3Dtrue, instanceDir=3D/home/jenkins/workspace/Lucen= e-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrClo= udDistribCmdsTest 900E65E336ABF401-001/control-001/cores/collection1, colle= ction=3Dcontrol_collection, absoluteInstDir=3D/home/jenkins/workspace/Lucen= e-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrClo= udDistribCmdsTest 900E65E336ABF401-001/control-001/cores/collection1/, core= NodeName=3D, dataDir=3Ddata/, shard=3D} [junit4] 2> 357867 T2635 oasc.CorePropertiesLocator.discoverUnder Foun= d core collection1 in /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/= build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest 900E65= E336ABF401-001/control-001/cores/collection1/ [junit4] 2> 357868 T2635 oasc.CorePropertiesLocator.discover Found 1 c= ore definitions [junit4] 2> 357868 T2667 C:control_collection c:collection1 oasc.ZkCon= troller.publish publishing core=3Dcollection1 state=3Ddown collection=3Dcon= trol_collection [junit4] 2> 357868 T2667 C:control_collection c:collection1 oasc.ZkCon= troller.publish numShards not found on descriptor - reading it from system = property [junit4] 2> 357869 T2663 oasc.DistributedQueue$LatchWatcher.process No= deChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 357869 T2667 C:control_collection c:collection1 oasc.ZkCon= troller.waitForCoreNodeName look for our core node name [junit4] 2> 357870 T2664 oasc.Overseer$ClusterStateUpdater.run process= Message: queueSize: 1, message =3D { [junit4] 2> =09 "core":"collection1", [junit4] 2> =09 "roles":null, [junit4] 2> =09 "base_url":"http://127.0.0.1:38272/s", [junit4] 2> =09 "node_name":"127.0.0.1:38272_s", [junit4] 2> =09 "numShards":"1", [junit4] 2> =09 "state":"down", [junit4] 2> =09 "shard":null, [junit4] 2> =09 "collection":"control_collection", [junit4] 2> =09 "operation":"state"} current state version: 0 [junit4] 2> 357870 T2664 oasco.ReplicaMutator.updateState Update state= numShards=3D1 message=3D{ [junit4] 2> =09 "core":"collection1", [junit4] 2> =09 "roles":null, [junit4] 2> =09 "base_url":"http://127.0.0.1:38272/s", [junit4] 2> =09 "node_name":"127.0.0.1:38272_s", [junit4] 2> =09 "numShards":"1", [junit4] 2> =09 "state":"down", [junit4] 2> =09 "shard":null, [junit4] 2> =09 "collection":"control_collection", [junit4] 2> =09 "operation":"state"} [junit4] 2> 357871 T2664 oasco.ClusterStateMutator.createCollection bu= ilding a new cName: control_collection [junit4] 2> 357871 T2664 oasco.ReplicaMutator.updateState Assigning ne= w node to shard shard=3Dshard1 [junit4] 2> 357872 T2663 oascc.ZkStateReader$2.process A cluster state= change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/cluste= rstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 358870 T2667 C:control_collection c:collection1 oasc.ZkCon= troller.waitForShardId waiting to find shard id in clusterstate for collect= ion1 [junit4] 2> 358870 T2667 C:control_collection c:collection1 oasc.ZkCon= troller.createCollectionZkNode Check for collection zkNode:control_collecti= on [junit4] 2> 358870 T2667 C:control_collection c:collection1 oasc.ZkCon= troller.createCollectionZkNode Collection zkNode exists [junit4] 2> 358871 T2667 C:control_collection c:collection1 oascc.ZkSt= ateReader.readConfigName Load collection config from:/collections/control_c= ollection [junit4] 2> 358871 T2667 C:control_collection c:collection1 oascc.ZkSt= ateReader.readConfigName path=3D/collections/control_collection configName= =3Dconf1 specified config exists in ZooKeeper [junit4] 2> 358872 T2667 C:control_collection c:collection1 oasc.SolrR= esourceLoader. new SolrResourceLoader for directory: '/home/jenkins/w= orkspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.clo= ud.FullSolrCloudDistribCmdsTest 900E65E336ABF401-001/control-001/cores/coll= ection1/' [junit4] 2> 358883 T2667 C:control_collection c:collection1 oasc.ZkCon= troller.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 358885 T2667 C:control_collection c:collection1 oasc.Confi= g. loaded config solrconfig.xml with version 0=20 [junit4] 2> 358890 T2667 C:control_collection c:collection1 oasc.SolrC= onfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 358893 T2667 C:control_collection c:collection1 oasc.SolrC= onfig. Using Lucene MatchVersion: 6.0.0 [junit4] 2> 358900 T2667 C:control_collection c:collection1 oasc.SolrC= onfig. Loaded SolrConfig: solrconfig.xml [junit4] 2> 358901 T2667 C:control_collection c:collection1 oass.Index= Schema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 358906 T2667 C:control_collection c:collection1 oass.Index= Schema.readSchema [collection1] Schema name=3Dtest [junit4] 2> 358972 T2667 C:control_collection c:collection1 oass.Index= Schema.readSchema default search field in schema is text [junit4] 2> 358973 T2667 C:control_collection c:collection1 oass.Index= Schema.readSchema unique key field: id [junit4] 2> 358974 T2667 C:control_collection c:collection1 oass.FileE= xchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 358976 T2667 C:control_collection c:collection1 oass.FileE= xchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 358990 T2667 C:control_collection c:collection1 oasc.CoreC= ontainer.create Creating SolrCore 'collection1' using configuration from co= llection control_collection [junit4] 2> 358991 T2667 C:control_collection c:collection1 oasc.SolrC= ore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 358991 T2667 C:control_collection c:collection1 oasc.SolrC= ore. [collection1] Opening new SolrCore at /home/jenkins/workspace/Lu= cene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolr= CloudDistribCmdsTest 900E65E336ABF401-001/control-001/cores/collection1/, d= ataDir=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-co= re/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest 900E65E336ABF401-00= 1/control-001/cores/collection1/data/ [junit4] 2> 358991 T2667 C:control_collection c:collection1 oasc.JmxMo= nitoredMap. JMX monitoring is enabled. Adding Solr mbeans to JMX Serv= er: com.sun.jmx.mbeanserver.JmxMBeanServer@e6596e [junit4] 2> 358992 T2667 C:control_collection c:collection1 oasc.Cachi= ngDirectoryFactory.get return new directory for /home/jenkins/workspace/Luc= ene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrC= loudDistribCmdsTest 900E65E336ABF401-001/control-001/cores/collection1/data [junit4] 2> 358992 T2667 C:control_collection c:collection1 oasc.SolrC= ore.getNewIndexDir New index directory detected: old=3Dnull new=3D/home/jen= kins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/so= lr.cloud.FullSolrCloudDistribCmdsTest 900E65E336ABF401-001/control-001/core= s/collection1/data/index/ [junit4] 2> 358992 T2667 C:control_collection c:collection1 oasc.SolrC= ore.initIndex WARN [collection1] Solr index directory '/home/jenkins/worksp= ace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.Fu= llSolrCloudDistribCmdsTest 900E65E336ABF401-001/control-001/cores/collectio= n1/data/index' doesn't exist. Creating new index... [junit4] 2> 358993 T2667 C:control_collection c:collection1 oasc.Cachi= ngDirectoryFactory.get return new directory for /home/jenkins/workspace/Luc= ene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrC= loudDistribCmdsTest 900E65E336ABF401-001/control-001/cores/collection1/data= /index [junit4] 2> 358993 T2667 C:control_collection c:collection1 oasu.Rando= mMergePolicy. RandomMergePolicy wrapping class org.apache.lucene.inde= x.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=3D36, maxMergeAtOnc= eExplicit=3D34, maxMergedSegmentMB=3D3.912109375, floorSegmentMB=3D0.966796= 875, forceMergeDeletesPctAllowed=3D27.1888874691354, segmentsPerTier=3D40.0= , maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio=3D0.0 [junit4] 2> 358994 T2667 C:control_collection c:collection1 oasc.SolrD= eletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=3D1 [junit4] 2> =09=09commit{dir=3DMockDirectoryWrapper(RAMDirectory@16136= 62 lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@142e47d)= ,segFN=3Dsegments_1,generation=3D1} [junit4] 2> 358994 T2667 C:control_collection c:collection1 oasc.SolrD= eletionPolicy.updateCommits newest commit generation =3D 1 [junit4] 2> 358998 T2667 C:control_collection c:collection1 oasup.Upda= teRequestProcessorChain.init creating updateRequestProcessorChain "nodistri= b" [junit4] 2> 358999 T2667 C:control_collection c:collection1 oasup.Upda= teRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 358999 T2667 C:control_collection c:collection1 oasup.Upda= teRequestProcessorChain.init inserting DistributedUpdateProcessorFactory in= to updateRequestProcessorChain "dedupe" [junit4] 2> 358999 T2667 C:control_collection c:collection1 oasup.Upda= teRequestProcessorChain.init creating updateRequestProcessorChain "stored_s= ig" [junit4] 2> 358999 T2667 C:control_collection c:collection1 oasup.Upda= teRequestProcessorChain.init inserting DistributedUpdateProcessorFactory in= to updateRequestProcessorChain "stored_sig" [junit4] 2> 358999 T2667 C:control_collection c:collection1 oasup.Upda= teRequestProcessorChain.init creating updateRequestProcessorChain "distrib-= dup-test-chain-explicit" [junit4] 2> 359000 T2667 C:control_collection c:collection1 oasup.Upda= teRequestProcessorChain.init creating updateRequestProcessorChain "distrib-= dup-test-chain-implicit" [junit4] 2> 359000 T2667 C:control_collection c:collection1 oasup.Upda= teRequestProcessorChain.init inserting DistributedUpdateProcessorFactory in= to updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 359000 T2667 C:control_collection c:collection1 oasc.SolrC= ore.loadUpdateProcessorChains no updateRequestProcessorChain defined as def= ault, creating implicit default [junit4] 2> 359002 T2667 C:control_collection c:collection1 oashl.XMLL= oader.init xsltCacheLifetimeSeconds=3D60 [junit4] 2> 359002 T2667 C:control_collection c:collection1 oashl.XMLL= oader.init xsltCacheLifetimeSeconds=3D60 [junit4] 2> 359003 T2667 C:control_collection c:collection1 oashl.XMLL= oader.init xsltCacheLifetimeSeconds=3D60 [junit4] 2> 359004 T2667 C:control_collection c:collection1 oashl.XMLL= oader.init xsltCacheLifetimeSeconds=3D60 [junit4] 2> 359009 T2667 C:control_collection c:collection1 oasc.Reque= stHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,= /update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/syste= m,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logg= ing,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 359010 T2667 C:control_collection c:collection1 oasc.SolrC= ore.initStatsCache Using default statsCache cache: org.apache.solr.search.s= tats.LocalStatsCache [junit4] 2> 359010 T2667 C:control_collection c:collection1 oasu.Updat= eHandler. Using UpdateLog implementation: org.apache.solr.update.Upda= teLog [junit4] 2> 359010 T2667 C:control_collection c:collection1 oasu.Updat= eLog.init Initializing UpdateLog: dataDir=3D defaultSyncLevel=3DFLUSH numRe= cordsToKeep=3D100 maxNumLogsToKeep=3D10 [junit4] 2> 359011 T2667 C:control_collection c:collection1 oasu.Commi= tTracker. Hard AutoCommit: disabled [junit4] 2> 359011 T2667 C:control_collection c:collection1 oasu.Commi= tTracker. Soft AutoCommit: disabled [junit4] 2> 359011 T2667 C:control_collection c:collection1 oasu.Rando= mMergePolicy. RandomMergePolicy wrapping class org.apache.lucene.inde= x.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=3D25, maxMergeAtOnc= eExplicit=3D41, maxMergedSegmentMB=3D78.521484375, floorSegmentMB=3D1.29394= 53125, forceMergeDeletesPctAllowed=3D24.36552267707157, segmentsPerTier=3D4= 4.0, maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio=3D0.73694286074= 81693 [junit4] 2> 359012 T2667 C:control_collection c:collection1 oasc.SolrD= eletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=3D1 [junit4] 2> =09=09commit{dir=3DMockDirectoryWrapper(RAMDirectory@16136= 62 lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@142e47d)= ,segFN=3Dsegments_1,generation=3D1} [junit4] 2> 359012 T2667 C:control_collection c:collection1 oasc.SolrD= eletionPolicy.updateCommits newest commit generation =3D 1 [junit4] 2> 359012 T2667 C:control_collection c:collection1 oass.SolrI= ndexSearcher. Opening Searcher@20bf06[collection1] main [junit4] 2> 359013 T2667 C:control_collection c:collection1 oascc.ZkSt= ateReader.readConfigName Load collection config from:/collections/control_c= ollection [junit4] 2> 359013 T2667 C:control_collection c:collection1 oascc.ZkSt= ateReader.readConfigName path=3D/collections/control_collection configName= =3Dconf1 specified config exists in ZooKeeper [junit4] 2> 359014 T2667 C:control_collection c:collection1 oasr.Manag= edResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the R= estManager with znodeBase: /configs/conf1 [junit4] 2> 359014 T2667 C:control_collection c:collection1 oasr.Manag= edResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageI= O with znodeBase: /configs/conf1 [junit4] 2> 359014 T2667 C:control_collection c:collection1 oasr.RestM= anager.init Initializing RestManager with initArgs: {} [junit4] 2> 359014 T2667 C:control_collection c:collection1 oasr.Manag= edResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:= path=3D/configs/conf1 [junit4] 2> 359015 T2667 C:control_collection c:collection1 oasr.Manag= edResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znod= e /configs/conf1/_rest_managed.json [junit4] 2> 359015 T2667 C:control_collection c:collection1 oasr.Manag= edResourceStorage.load Loaded null at path _rest_managed.json using ZooKeep= erStorageIO:path=3D/configs/conf1 [junit4] 2> 359015 T2667 C:control_collection c:collection1 oasr.RestM= anager.init Initializing 0 registered ManagedResources [junit4] 2> 359015 T2667 C:control_collection c:collection1 oash.Repli= cationHandler.inform Commits will be reserved for 10000 [junit4] 2> 359016 T2668 C:control_collection c:collection1 oasc.SolrC= ore.registerSearcher [collection1] Registered new searcher Searcher@20bf06[= collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 359017 T2667 C:control_collection c:collection1 oasc.CoreC= ontainer.registerCore registering core: collection1 [junit4] 2> 359018 T2671 C:control_collection S:shard1 oasc.ZkControll= er.register Register replica - core:collection1 address:http://127.0.0.1:38= 272/s collection:control_collection shard:shard1 [junit4] 2> 359018 T2635 oass.SolrDispatchFilter.init user.dir=3D/home= /jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2 [junit4] 2> 359018 T2635 oass.SolrDispatchFilter.init SolrDispatchFilt= er.init() done [junit4] 2> 359018 T2671 C:control_collection S:shard1 oascc.SolrZkCli= ent.makePath makePath: /collections/control_collection/leader_elect/shard1/= election [junit4] 2> 359019 T2635 oascc.SolrZkClient.createZkCredentialsToAddAu= tomatically Using default ZkCredentialsProvider [junit4] 2> 359019 T2635 oascc.ConnectionManager.waitForConnected Wait= ing for client to connect to ZooKeeper [junit4] 2> 359020 T2674 oascc.ConnectionManager.process Watcher org.a= pache.solr.common.cloud.ConnectionManager@7f5627 name:ZooKeeperConnection W= atcher:127.0.0.1:43312/solr got event WatchedEvent state:SyncConnected type= :None path:null path:null type:None [junit4] 2> 359020 T2635 oascc.ConnectionManager.waitForConnected Clie= nt is connected to ZooKeeper [junit4] 2> 359021 T2635 oascc.SolrZkClient.createZkACLProvider Using = default ZkACLProvider [junit4] 2> 359021 T2635 oascc.ZkStateReader.createClusterStateWatcher= sAndUpdate Updating cluster state from ZooKeeper...=20 [junit4] 2> 359022 T2671 C:control_collection S:shard1 oasc.ShardLeade= rElectionContext.runLeaderProcess Running the leader process for shard shar= d1 [junit4] 2> 359023 T2635 oasc.ChaosMonkey.monkeyLog monkey: init - exp= ire sessions:false cause connection loss:false [junit4] 2> 359023 T2635 oasc.AbstractFullDistribZkTestBase.createJett= ys Creating collection1 with stateFormat=3D2 [junit4] 2> 359024 T2663 oasc.DistributedQueue$LatchWatcher.process No= deChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 359024 T2671 C:control_collection S:shard1 oasc.ShardLeade= rElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 359024 T2635 oascc.SolrZkClient.createZkCredentialsToAddAu= tomatically Using default ZkCredentialsProvider [junit4] 2> 359024 T2671 C:control_collection S:shard1 oasc.ShardLeade= rElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> 359024 T2664 oasc.Overseer$ClusterStateUpdater.run process= Message: queueSize: 1, message =3D { [junit4] 2> =09 "operation":"leader", [junit4] 2> =09 "shard":"shard1", [junit4] 2> =09 "collection":"control_collection"} current state vers= ion: 1 [junit4] 2> 359025 T2635 oascc.ConnectionManager.waitForConnected Wait= ing for client to connect to ZooKeeper [junit4] 2> ASYNC NEW_CORE C372 name=3Dcollection1 org.apache.solr.co= re.SolrCore@33f837 url=3Dhttp://127.0.0.1:38272/s/collection1 node=3D127.0.= 0.1:38272_s C372_STATE=3Dcoll:control_collection core:collection1 props:{co= re=3Dcollection1, base_url=3Dhttp://127.0.0.1:38272/s, node_name=3D127.0.0.= 1:38272_s, state=3Ddown} [junit4] 2> 359024 T2671 C:control_collection S:shard1 C372 P38272 oas= c.SyncStrategy.sync Sync replicas to http://127.0.0.1:38272/s/collection1/ [junit4] 2> 359025 T2677 oascc.ConnectionManager.process Watcher org.a= pache.solr.common.cloud.ConnectionManager@184203c name:ZooKeeperConnection = Watcher:127.0.0.1:43312/solr got event WatchedEvent state:SyncConnected typ= e:None path:null path:null type:None [junit4] 2> 359025 T2671 C:control_collection S:shard1 C372 P38272 oas= c.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 359026 T2635 oascc.ConnectionManager.waitForConnected Clie= nt is connected to ZooKeeper [junit4] 2> 359026 T2671 C:control_collection S:shard1 C372 P38272 oas= c.SyncStrategy.syncToMe http://127.0.0.1:38272/s/collection1/ has no replic= as [junit4] 2> 359026 T2635 oascc.SolrZkClient.createZkACLProvider Using = default ZkACLProvider [junit4] 2> 359026 T2671 C:control_collection S:shard1 oasc.ShardLeade= rElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:382= 72/s/collection1/ shard1 [junit4] 2> 359027 T2671 C:control_collection S:shard1 oascc.SolrZkCli= ent.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 359027 T2663 oasc.DistributedQueue$LatchWatcher.process No= deChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 359028 T2664 oasc.Overseer$ClusterStateUpdater.run process= Message: queueSize: 1, message =3D { [junit4] 2> =09 "operation":"create", [junit4] 2> =09 "name":"collection1", [junit4] 2> =09 "numShards":"3", [junit4] 2> =09 "stateFormat":"2"} current state version: 1 [junit4] 2> 359028 T2664 oasco.ClusterStateMutator.createCollection bu= ilding a new cName: collection1 [junit4] 2> 359029 T2663 oascc.ZkStateReader$2.process A cluster state= change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/cluste= rstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 359029 T2674 oascc.ZkStateReader$2.process A cluster state= change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/cluste= rstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 359032 T2664 oasc.Overseer$ClusterStateUpdater.run process= Message: queueSize: 1, message =3D { [junit4] 2> =09 "operation":"leader", [junit4] 2> =09 "shard":"shard1", [junit4] 2> =09 "collection":"control_collection", [junit4] 2> =09 "base_url":"http://127.0.0.1:38272/s", [junit4] 2> =09 "core":"collection1", [junit4] 2> =09 "state":"active"} current state version: 2 [junit4] 2> 359033 T2664 oasco.ZkStateWriter.writePendingUpdates going= to create_collection /collections/collection1/state.json [junit4] 2> 359034 T2663 oascc.ZkStateReader$2.process A cluster state= change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/cluste= rstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 359034 T2674 oascc.ZkStateReader$2.process A cluster state= change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/cluste= rstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 359035 T2663 oasc.DistributedQueue$LatchWatcher.process No= deChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 359036 T2663 oascc.ZkStateReader$2.process A cluster state= change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/cluste= rstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 359036 T2674 oascc.ZkStateReader$2.process A cluster state= change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/cluste= rstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 359081 T2671 C:control_collection S:shard1 oasc.ZkControll= er.register We are http://127.0.0.1:38272/s/collection1/ and leader is http= ://127.0.0.1:38272/s/collection1/ [junit4] 2> 359081 T2671 C:control_collection S:shard1 oasc.ZkControll= er.register No LogReplay needed for core=3Dcollection1 baseURL=3Dhttp://127= .0.0.1:38272/s [junit4] 2> 359081 T2671 C:control_collection S:shard1 oasc.ZkControll= er.checkRecovery I am the leader, no recovery necessary [junit4] 2> 359081 T2671 C:control_collection S:shard1 c:collection1 o= asc.ZkController.publish publishing core=3Dcollection1 state=3Dactive colle= ction=3Dcontrol_collection [junit4] 2> 359082 T2671 C:control_collection S:shard1 c:collection1 o= asc.ZkController.publish numShards not found on descriptor - reading it fro= m system property [junit4] 2> 359083 T2663 oasc.DistributedQueue$LatchWatcher.process No= deChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 359083 T2664 oasc.Overseer$ClusterStateUpdater.run process= Message: queueSize: 1, message =3D { [junit4] 2> =09 "core":"collection1", [junit4] 2> =09 "core_node_name":"core_node1", [junit4] 2> =09 "roles":null, [junit4] 2> =09 "base_url":"http://127.0.0.1:38272/s", [junit4] 2> =09 "node_name":"127.0.0.1:38272_s", [junit4] 2> =09 "numShards":"3", [junit4] 2> =09 "state":"active", [junit4] 2> =09 "shard":"shard1", [junit4] 2> =09 "collection":"control_collection", [junit4] 2> =09 "operation":"state"} current state version: 4 [junit4] 2> 359084 T2664 oasco.ReplicaMutator.updateState Update state= numShards=3D3 message=3D{ [junit4] 2> =09 "core":"collection1", [junit4] 2> =09 "core_node_name":"core_node1", [junit4] 2> =09 "roles":null, [junit4] 2> =09 "base_url":"http://127.0.0.1:38272/s", [junit4] 2> =09 "node_name":"127.0.0.1:38272_s", [junit4] 2> =09 "numShards":"3", [junit4] 2> =09 "state":"active", [junit4] 2> =09 "shard":"shard1", [junit4] 2> =09 "collection":"control_collection", [junit4] 2> =09 "operation":"state"} [junit4] 2> 359089 T2635 oas.SolrTestCaseJ4.writeCoreProperties Writin= g core.properties file to /home/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest 90= 0E65E336ABF401-001/shard-1-001/cores/collection1 [junit4] 2> 359090 T2635 oasc.AbstractFullDistribZkTestBase.createJett= ys create jetty 1 in directory /home/jenkins/workspace/Lucene-Solr-trunk-Li= nux/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTe= st 900E65E336ABF401-001/shard-1-001 [junit4] 2> 359091 T2635 oejs.Server.doStart jetty-9.2.9.v20150224 [junit4] 2> 359092 T2635 oejsh.ContextHandler.doStart Started o.e.j.s.= ServletContextHandler@18f40a4{/s,null,AVAILABLE} [junit4] 2> 359093 T2635 oejs.AbstractConnector.doStart Started Server= Connector@c90e4f{HTTP/1.1}{127.0.0.1:60061} [junit4] 2> 359093 T2635 oejs.Server.doStart Started @359979ms [junit4] 2> 359094 T2635 oascse.JettySolrRunner$1.lifeCycleStarted Jet= ty properties: {solr.data.dir=3D/home/jenkins/workspace/Lucene-Solr-trunk-L= inux/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsT= est 900E65E336ABF401-001/tempDir-001/jetty1, solrconfig=3Dsolrconfig.xml, h= ostContext=3D/s, hostPort=3D60061, coreRootDirectory=3D/home/jenkins/worksp= ace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/../../../../../../= ../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-cor= e/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest 900E65E336ABF401-001= /shard-1-001/cores} [junit4] 2> 359094 T2635 oass.SolrDispatchFilter.init SolrDispatchFilt= er.init()sun.misc.Launcher$AppClassLoader@e2f2a [junit4] 2> 359094 T2635 oasc.SolrResourceLoader. new SolrResour= ceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/so= lr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspac= e/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.Full= SolrCloudDistribCmdsTest 900E65E336ABF401-001/shard-1-001/' [junit4] 2> 359106 T2635 oasc.SolrXmlConfig.fromFile Loading container= configuration from /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/bu= ild/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Luc= ene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrC= loudDistribCmdsTest 900E65E336ABF401-001/shard-1-001/solr.xml [junit4] 2> 359110 T2635 oasc.CorePropertiesLocator. Config-defi= ned core root directory: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/so= lr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspac= e/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.Full= SolrCloudDistribCmdsTest 900E65E336ABF401-001/shard-1-001/cores [junit4] 2> 359111 T2635 oasc.CoreContainer. New CoreContainer 7= 451516 [junit4] 2> 359111 T2635 oasc.CoreContainer.load Loading cores into Co= reContainer [instanceDir=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/= solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/worksp= ace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.Fu= llSolrCloudDistribCmdsTest 900E65E336ABF401-001/shard-1-001/] [junit4] 2> 359111 T2635 oasc.CoreContainer.load loading shared librar= y: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/tes= t/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Li= nux/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTe= st 900E65E336ABF401-001/shard-1-001/lib [junit4] 2> 359111 T2635 oasc.SolrResourceLoader.addToClassLoader WARN= Can't find (or read) directory to add to classloader: lib (resolved as: /h= ome/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/= ../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest 90= 0E65E336ABF401-001/shard-1-001/lib). [junit4] 2> 359118 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting socketTimeout to: 90000 [junit4] 2> 359118 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting urlScheme to:=20 [junit4] 2> 359118 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting connTimeout to: 15000 [junit4] 2> 359118 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting maxConnectionsPerHost to: 20 [junit4] 2> 359119 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting maxConnections to: 10000 [junit4] 2> 359119 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting corePoolSize to: 0 [junit4] 2> 359119 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting maximumPoolSize to: 2147483647 [junit4] 2> 359119 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting maxThreadIdleTime to: 5 [junit4] 2> 359120 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting sizeOfQueue to: -1 [junit4] 2> 359120 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting fairnessPolicy to: false [junit4] 2> 359120 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting useRetries to: false [junit4] 2> 359120 T2635 oasu.UpdateShardHandler. Creating Updat= eShardHandler HTTP client with params: socketTimeout=3D340000&connTimeout= =3D45000&retry=3Dtrue [junit4] 2> 359121 T2635 oasl.LogWatcher.createWatcher SLF4J impl is o= rg.slf4j.impl.Log4jLoggerFactory [junit4] 2> 359121 T2635 oasl.LogWatcher.newRegisteredLogWatcher Regis= tering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 359121 T2635 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 359121 T2635 oasc.ZkContainer.initZooKeeper Zookeeper clie= nt=3D127.0.0.1:43312/solr [junit4] 2> 359122 T2635 oasc.ZkController.checkChrootPath zkHost incl= udes chroot [junit4] 2> 359122 T2635 oascc.SolrZkClient.createZkCredentialsToAddAu= tomatically Using default ZkCredentialsProvider [junit4] 2> 359123 T2635 oascc.ConnectionManager.waitForConnected Wait= ing for client to connect to ZooKeeper [junit4] 2> 359124 T2691 oascc.ConnectionManager.process Watcher org.a= pache.solr.common.cloud.ConnectionManager@19cbe88 name:ZooKeeperConnection = Watcher:127.0.0.1:43312 got event WatchedEvent state:SyncConnected type:Non= e path:null path:null type:None [junit4] 2> 359124 T2635 oascc.ConnectionManager.waitForConnected Clie= nt is connected to ZooKeeper [junit4] 2> 359125 T2635 oascc.SolrZkClient.createZkACLProvider Using = default ZkACLProvider [junit4] 2> 359126 T2635 oascc.ConnectionManager.waitForConnected Wait= ing for client to connect to ZooKeeper [junit4] 2> 359127 T2694 oascc.ConnectionManager.process Watcher org.a= pache.solr.common.cloud.ConnectionManager@52bd30 name:ZooKeeperConnection W= atcher:127.0.0.1:43312/solr got event WatchedEvent state:SyncConnected type= :None path:null path:null type:None [junit4] 2> 359127 T2635 oascc.ConnectionManager.waitForConnected Clie= nt is connected to ZooKeeper [junit4] 2> 359130 T2635 oascc.ZkStateReader.createClusterStateWatcher= sAndUpdate Updating cluster state from ZooKeeper...=20 [junit4] 2> 359186 T2694 oascc.ZkStateReader$2.process A cluster state= change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/cluste= rstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 359186 T2674 oascc.ZkStateReader$2.process A cluster state= change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/cluste= rstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 359186 T2663 oascc.ZkStateReader$2.process A cluster state= change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/cluste= rstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 360133 T2635 oasc.ZkController.createEphemeralLiveNode Reg= ister node as live in ZooKeeper:/live_nodes/127.0.0.1:60061_s [junit4] 2> 360134 T2635 oascc.SolrZkClient.makePath makePath: /live_n= odes/127.0.0.1:60061_s [junit4] 2> 360136 T2635 oasc.Overseer.close Overseer (id=3Dnull) clos= ing [junit4] 2> 360137 T2635 oasc.CorePropertiesLocator.discover Looking f= or core definitions underneath /home/jenkins/workspace/Lucene-Solr-trunk-Li= nux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/wo= rkspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.clou= d.FullSolrCloudDistribCmdsTest 900E65E336ABF401-001/shard-1-001/cores [junit4] 2> 360138 T2635 oasc.CoreDescriptor. CORE DESCRIPTOR: {= name=3Dcollection1, config=3Dsolrconfig.xml, transient=3Dfalse, schema=3Dsc= hema.xml, loadOnStartup=3Dtrue, instanceDir=3D/home/jenkins/workspace/Lucen= e-Solr-trunk-Linux/solr/build/solr-core/test/J2/../../../../../../../../../= home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2= /temp/solr.cloud.FullSolrCloudDistribCmdsTest 900E65E336ABF401-001/shard-1-= 001/cores/collection1, collection=3Dcollection1, absoluteInstDir=3D/home/je= nkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/../../= ../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/bu= ild/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest 900E65E3= 36ABF401-001/shard-1-001/cores/collection1/, coreNodeName=3D, dataDir=3Ddat= a/, shard=3D} [junit4] 2> 360139 T2635 oasc.CorePropertiesLocator.discoverUnder Foun= d core collection1 in /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/= build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/L= ucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.FullSol= rCloudDistribCmdsTest 900E65E336ABF401-001/shard-1-001/cores/collection1/ [junit4] 2> 360139 T2635 oasc.CorePropertiesLocator.discover Found 1 c= ore definitions [junit4] 2> 360140 T2695 C:collection1 c:collection1 oasc.ZkController= .publish publishing core=3Dcollection1 state=3Ddown collection=3Dcollection= 1 [junit4] 2> 360140 T2695 C:collection1 c:collection1 oasc.ZkController= .publish numShards not found on descriptor - reading it from system propert= y [junit4] 2> 360141 T2663 oasc.DistributedQueue$LatchWatcher.process No= deChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 360142 T2695 C:collection1 c:collection1 oasc.ZkController= .preRegister Registering watch for external collection collection1 [junit4] 2> 360142 T2695 C:collection1 c:collection1 oascc.ZkStateRead= er.addZkWatch addZkWatch collection1 [junit4] 2> 360142 T2664 oasc.Overseer$ClusterStateUpdater.run process= Message: queueSize: 1, message =3D { [junit4] 2> =09 "core":"collection1", [junit4] 2> =09 "roles":null, [junit4] 2> =09 "base_url":"http://127.0.0.1:60061/s", [junit4] 2> =09 "node_name":"127.0.0.1:60061_s", [junit4] 2> =09 "numShards":"3", [junit4] 2> =09 "state":"down", [junit4] 2> =09 "shard":null, [junit4] 2> =09 "collection":"collection1", [junit4] 2> =09 "operation":"state"} current state version: 5 [junit4] 2> 360142 T2695 C:collection1 c:collection1 oascc.ZkStateRead= er.addZkWatch Updating collection state at /collections/collection1/state.j= son from ZooKeeper...=20 [junit4] 2> 360143 T2664 oasco.ReplicaMutator.updateState Update state= numShards=3D3 message=3D{ [junit4] 2> =09 "core":"collection1", [junit4] 2> =09 "roles":null, [junit4] 2> =09 "base_url":"http://127.0.0.1:60061/s", [junit4] 2> =09 "node_name":"127.0.0.1:60061_s", [junit4] 2> =09 "numShards":"3", [junit4] 2> =09 "state":"down", [junit4] 2> =09 "shard":null, [junit4] 2> =09 "collection":"collection1", [junit4] 2> =09 "operation":"state"} [junit4] 2> 360143 T2664 oasco.ReplicaMutator.updateState Collection a= lready exists with numShards=3D3 [junit4] 2> 360143 T2664 oasco.ReplicaMutator.updateState Assigning ne= w node to shard shard=3Dshard2 [junit4] 2> 360143 T2695 C:collection1 c:collection1 oascc.ZkStateRead= er.updateWatchedCollection Updating data for collection1 to ver 0=20 [junit4] 2> 360143 T2695 C:collection1 c:collection1 oasc.ZkController= .waitForCoreNodeName look for our core node name [junit4] 2> 360245 T2664 oasco.ZkStateWriter.writePendingUpdates going= to update_collection /collections/collection1/state.json version: 0 [junit4] 2> 360246 T2694 oascc.ZkStateReader$7.process A cluster state= change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collec= tions/collection1/state.json for collection collection1 has occurred - upda= ting... (live nodes size: 2) [junit4] 2> 360246 T2694 oascc.ZkStateReader.updateWatchedCollection U= pdating data for collection1 to ver 1=20 [junit4] 2> 361144 T2695 C:collection1 c:collection1 oasc.ZkController= .waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 361144 T2695 C:collection1 c:collection1 oasc.ZkController= .createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 361145 T2695 C:collection1 c:collection1 oasc.ZkController= .createCollectionZkNode Collection zkNode exists [junit4] 2> 361145 T2695 C:collection1 c:collection1 oascc.ZkStateRead= er.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 361145 T2695 C:collection1 c:collection1 oascc.ZkStateRead= er.readConfigName path=3D/collections/collection1 configName=3Dconf1 specif= ied config exists in ZooKeeper [junit4] 2> 361145 T2695 C:collection1 c:collection1 oasc.SolrResource= Loader. new SolrResourceLoader for directory: '/home/jenkins/workspac= e/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/../../../../../../..= /../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/= test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest 900E65E336ABF401-001/s= hard-1-001/cores/collection1/' [junit4] 2> 361157 T2695 C:collection1 c:collection1 oasc.ZkController= .watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 361159 T2695 C:collection1 c:collection1 oasc.Config. loaded config solrconfig.xml with version 0=20 [junit4] 2> 361162 T2695 C:collection1 c:collection1 oasc.SolrConfig.r= efreshRequestParams current version of requestparams : -1 [junit4] 2> 361166 T2695 C:collection1 c:collection1 oasc.SolrConfig.<= init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 361173 T2695 C:collection1 c:collection1 oasc.SolrConfig.<= init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 361174 T2695 C:collection1 c:collection1 oass.IndexSchema.= readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 361179 T2695 C:collection1 c:collection1 oass.IndexSchema.= readSchema [collection1] Schema name=3Dtest [junit4] 2> 361244 T2695 C:collection1 c:collection1 oass.IndexSchema.= readSchema default search field in schema is text [junit4] 2> 361245 T2695 C:collection1 c:collection1 oass.IndexSchema.= readSchema unique key field: id [junit4] 2> 361245 T2695 C:collection1 c:collection1 oass.FileExchange= RateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 361248 T2695 C:collection1 c:collection1 oass.FileExchange= RateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 361260 T2695 C:collection1 c:collection1 oasc.CoreContaine= r.create Creating SolrCore 'collection1' using configuration from collectio= n collection1 [junit4] 2> 361260 T2695 C:collection1 c:collection1 oasc.SolrCore.ini= tDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 361261 T2695 C:collection1 c:collection1 oasc.SolrCore. [collection1] Opening new SolrCore at /home/jenkins/workspace/Lucene-So= lr-trunk-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home= /jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/tem= p/solr.cloud.FullSolrCloudDistribCmdsTest 900E65E336ABF401-001/shard-1-001/= cores/collection1/, dataDir=3D/home/jenkins/workspace/Lucene-Solr-trunk-Lin= ux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/wor= kspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud= .FullSolrCloudDistribCmdsTest 900E65E336ABF401-001/shard-1-001/cores/collec= tion1/data/ [junit4] 2> 361261 T2695 C:collection1 c:collection1 oasc.JmxMonitored= Map. JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com= .sun.jmx.mbeanserver.JmxMBeanServer@e6596e [junit4] 2> 361262 T2695 C:collection1 c:collection1 oasc.CachingDirec= toryFactory.get return new directory for /home/jenkins/workspace/Lucene-Sol= r-trunk-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/= jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp= /solr.cloud.FullSolrCloudDistribCmdsTest 900E65E336ABF401-001/shard-1-001/c= ores/collection1/data [junit4] 2> 361262 T2695 C:collection1 c:collection1 oasc.SolrCore.get= NewIndexDir New index directory detected: old=3Dnull new=3D/home/jenkins/wo= rkspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/../../../../..= /../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr= -core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest 900E65E336ABF401= -001/shard-1-001/cores/collection1/data/index/ [junit4] 2> 361262 T2695 C:collection1 c:collection1 oasc.SolrCore.ini= tIndex WARN [collection1] Solr index directory '/home/jenkins/workspace/Luc= ene-Solr-trunk-Linux/solr/build/solr-core/test/J2/../../../../../../../../.= ./home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/= J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest 900E65E336ABF401-001/shard-= 1-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 361262 T2695 C:collection1 c:collection1 oasc.CachingDirec= toryFactory.get return new directory for /home/jenkins/workspace/Lucene-Sol= r-trunk-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/= jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp= /solr.cloud.FullSolrCloudDistribCmdsTest 900E65E336ABF401-001/shard-1-001/c= ores/collection1/data/index [junit4] 2> 361263 T2695 C:collection1 c:collection1 oasu.RandomMergeP= olicy. RandomMergePolicy wrapping class org.apache.lucene.index.Tiere= dMergePolicy: [TieredMergePolicy: maxMergeAtOnce=3D36, maxMergeAtOnceExplic= it=3D34, maxMergedSegmentMB=3D3.912109375, floorSegmentMB=3D0.966796875, fo= rceMergeDeletesPctAllowed=3D27.1888874691354, segmentsPerTier=3D40.0, maxCF= SSegmentSizeMB=3D8.796093022207999E12, noCFSRatio=3D0.0 [junit4] 2> 361263 T2695 C:collection1 c:collection1 oasc.SolrDeletion= Policy.onCommit SolrDeletionPolicy.onCommit: commits: num=3D1 [junit4] 2> =09=09commit{dir=3DMockDirectoryWrapper(RAMDirectory@1f125= 9 lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@148acb5),= segFN=3Dsegments_1,generation=3D1} [junit4] 2> 361264 T2695 C:collection1 c:collection1 oasc.SolrDeletion= Policy.updateCommits newest commit generation =3D 1 [junit4] 2> 361267 T2695 C:collection1 c:collection1 oasup.UpdateReque= stProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 361268 T2695 C:collection1 c:collection1 oasup.UpdateReque= stProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 361268 T2695 C:collection1 c:collection1 oasup.UpdateReque= stProcessorChain.init inserting DistributedUpdateProcessorFactory into upda= teRequestProcessorChain "dedupe" [junit4] 2> 361268 T2695 C:collection1 c:collection1 oasup.UpdateReque= stProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 361268 T2695 C:collection1 c:collection1 oasup.UpdateReque= stProcessorChain.init inserting DistributedUpdateProcessorFactory into upda= teRequestProcessorChain "stored_sig" [junit4] 2> 361269 T2695 C:collection1 c:collection1 oasup.UpdateReque= stProcessorChain.init creating updateRequestProcessorChain "distrib-dup-tes= t-chain-explicit" [junit4] 2> 361269 T2695 C:collection1 c:collection1 oasup.UpdateReque= stProcessorChain.init creating updateRequestProcessorChain "distrib-dup-tes= t-chain-implicit" [junit4] 2> 361269 T2695 C:collection1 c:collection1 oasup.UpdateReque= stProcessorChain.init inserting DistributedUpdateProcessorFactory into upda= teRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 361269 T2695 C:collection1 c:collection1 oasc.SolrCore.loa= dUpdateProcessorChains no updateRequestProcessorChain defined as default, c= reating implicit default [junit4] 2> 361271 T2695 C:collection1 c:collection1 oashl.XMLLoader.i= nit xsltCacheLifetimeSeconds=3D60 [junit4] 2> 361272 T2695 C:collection1 c:collection1 oashl.XMLLoader.i= nit xsltCacheLifetimeSeconds=3D60 [junit4] 2> 361273 T2695 C:collection1 c:collection1 oashl.XMLLoader.i= nit xsltCacheLifetimeSeconds=3D60 [junit4] 2> 361274 T2695 C:collection1 c:collection1 oashl.XMLLoader.i= nit xsltCacheLifetimeSeconds=3D60 [junit4] 2> 361281 T2695 C:collection1 c:collection1 oasc.RequestHandl= ers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,/update= /csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/repl= ication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/up= date/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 361282 T2695 C:collection1 c:collection1 oasc.SolrCore.ini= tStatsCache Using default statsCache cache: org.apache.solr.search.stats.Lo= calStatsCache [junit4] 2> 361282 T2695 C:collection1 c:collection1 oasu.UpdateHandle= r. Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 361282 T2695 C:collection1 c:collection1 oasu.UpdateLog.in= it Initializing UpdateLog: dataDir=3D defaultSyncLevel=3DFLUSH numRecordsTo= Keep=3D100 maxNumLogsToKeep=3D10 [junit4] 2> 361283 T2695 C:collection1 c:collection1 oasu.CommitTracke= r. Hard AutoCommit: disabled [junit4] 2> 361283 T2695 C:collection1 c:collection1 oasu.CommitTracke= r. Soft AutoCommit: disabled [junit4] 2> 361284 T2695 C:collection1 c:collection1 oasu.RandomMergeP= olicy. RandomMergePolicy wrapping class org.apache.lucene.index.Tiere= dMergePolicy: [TieredMergePolicy: maxMergeAtOnce=3D25, maxMergeAtOnceExplic= it=3D41, maxMergedSegmentMB=3D78.521484375, floorSegmentMB=3D1.2939453125, = forceMergeDeletesPctAllowed=3D24.36552267707157, segmentsPerTier=3D44.0, ma= xCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio=3D0.7369428607481693 [junit4] 2> 361285 T2695 C:collection1 c:collection1 oasc.SolrDeletion= Policy.onInit SolrDeletionPolicy.onInit: commits: num=3D1 [junit4] 2> =09=09commit{dir=3DMockDirectoryWrapper(RAMDirectory@1f125= 9 lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@148acb5),= segFN=3Dsegments_1,generation=3D1} [junit4] 2> 361285 T2695 C:collection1 c:collection1 oasc.SolrDeletion= Policy.updateCommits newest commit generation =3D 1 [junit4] 2> 361285 T2695 C:collection1 c:collection1 oass.SolrIndexSea= rcher. Opening Searcher@8c3b19[collection1] main [junit4] 2> 361285 T2695 C:collection1 c:collection1 oascc.ZkStateRead= er.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 361286 T2695 C:collection1 c:collection1 oascc.ZkStateRead= er.readConfigName path=3D/collections/collection1 configName=3Dconf1 specif= ied config exists in ZooKeeper [junit4] 2> 361286 T2695 C:collection1 c:collection1 oasr.ManagedResou= rceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestMana= ger with znodeBase: /configs/conf1 [junit4] 2> 361287 T2695 C:collection1 c:collection1 oasr.ManagedResou= rceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with = znodeBase: /configs/conf1 [junit4] 2> 361287 T2695 C:collection1 c:collection1 oasr.RestManager.= init Initializing RestManager with initArgs: {} [junit4] 2> 361287 T2695 C:collection1 c:collection1 oasr.ManagedResou= rceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=3D= /configs/conf1 [junit4] 2> 361288 T2695 C:collection1 c:collection1 oasr.ManagedResou= rceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /conf= igs/conf1/_rest_managed.json [junit4] 2> 361288 T2695 C:collection1 c:collection1 oasr.ManagedResou= rceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStora= geIO:path=3D/configs/conf1 [junit4] 2> 361288 T2695 C:collection1 c:collection1 oasr.RestManager.= init Initializing 0 registered ManagedResources [junit4] 2> 361288 T2695 C:collection1 c:collection1 oash.ReplicationH= andler.inform Commits will be reserved for 10000 [junit4] 2> 361289 T2696 C:collection1 c:collection1 oasc.SolrCore.reg= isterSearcher [collection1] Registered new searcher Searcher@8c3b19[collect= ion1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 361290 T2695 C:collection1 c:collection1 oasc.CoreContaine= r.registerCore registering core: collection1 [junit4] 2> 361290 T2699 C:collection1 S:shard2 oasc.ZkController.regi= ster Register replica - core:collection1 address:http://127.0.0.1:60061/s c= ollection:collection1 shard:shard2 [junit4] 2> 361291 T2635 oass.SolrDispatchFilter.init user.dir=3D/home= /jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2 [junit4] 2> 361291 T2635 oass.SolrDispatchFilter.init SolrDispatchFilt= er.init() done [junit4] 2> 361291 T2699 C:collection1 S:shard2 oascc.SolrZkClient.mak= ePath makePath: /collections/collection1/leader_elect/shard2/election [junit4] 2> 361294 T2699 C:collection1 S:shard2 oasc.ShardLeaderElecti= onContext.runLeaderProcess Running the leader process for shard shard2 [junit4] 2> 361295 T2663 oasc.DistributedQueue$LatchWatcher.process No= deChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 361296 T2699 C:collection1 S:shard2 oasc.ShardLeaderElecti= onContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 361296 T2699 C:collection1 S:shard2 oasc.ShardLeaderElecti= onContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> 361296 T2664 oasc.Overseer$ClusterStateUpdater.run process= Message: queueSize: 1, message =3D { [junit4] 2> =09 "operation":"leader", [junit4] 2> =09 "shard":"shard2", [junit4] 2> =09 "collection":"collection1"} current state version: 5 [junit4] 2> ASYNC NEW_CORE C373 name=3Dcollection1 org.apache.solr.co= re.SolrCore@1ac1574 url=3Dhttp://127.0.0.1:60061/s/collection1 node=3D127.0= .0.1:60061_s C373_STATE=3Dcoll:collection1 core:collection1 props:{core=3Dc= ollection1, base_url=3Dhttp://127.0.0.1:60061/s, node_name=3D127.0.0.1:6006= 1_s, state=3Ddown} [junit4] 2> 361296 T2699 C:collection1 S:shard2 C373 P60061 oasc.SyncS= trategy.sync Sync replicas to http://127.0.0.1:60061/s/collection1/ [junit4] 2> 361297 T2699 C:collection1 S:shard2 C373 P60061 oasc.SyncS= trategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 361298 T2699 C:collection1 S:shard2 C373 P60061 oasc.SyncS= trategy.syncToMe http://127.0.0.1:60061/s/collection1/ has no replicas [junit4] 2> 361298 T2664 oasco.ZkStateWriter.writePendingUpdates going= to update_collection /collections/collection1/state.json version: 1 [junit4] 2> 361298 T2699 C:collection1 S:shard2 oasc.ShardLeaderElecti= onContext.runLeaderProcess I am the new leader: http://127.0.0.1:60061/s/co= llection1/ shard2 [junit4] 2> 361298 T2699 C:collection1 S:shard2 oascc.SolrZkClient.mak= ePath makePath: /collections/collection1/leaders/shard2 [junit4] 2> 361298 T2694 oascc.ZkStateReader$7.process A cluster state= change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collec= tions/collection1/state.json for collection collection1 has occurred - upda= ting... (live nodes size: 2) [junit4] 2> 361299 T2694 oascc.ZkStateReader.updateWatchedCollection U= pdating data for collection1 to ver 2=20 [junit4] 2> 361301 T2663 oasc.DistributedQueue$LatchWatcher.process No= deChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 361302 T2664 oasc.Overseer$ClusterStateUpdater.run process= Message: queueSize: 1, message =3D { [junit4] 2> =09 "operation":"leader", [junit4] 2> =09 "shard":"shard2", [junit4] 2> =09 "collection":"collection1", [junit4] 2> =09 "base_url":"http://127.0.0.1:60061/s", [junit4] 2> =09 "core":"collection1", [junit4] 2> =09 "state":"active"} current state version: 5 [junit4] 2> 361303 T2664 oasco.ZkStateWriter.writePendingUpdates going= to update_collection /collections/collection1/state.json version: 2 [junit4] 2> 361303 T2694 oascc.ZkStateReader$7.process A cluster state= change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collec= tions/collection1/state.json for collection collection1 has occurred - upda= ting... (live nodes size: 2) [junit4] 2> 361304 T2694 oascc.ZkStateReader.updateWatchedCollection U= pdating data for collection1 to ver 3=20 [junit4] 2> 361351 T2635 oas.SolrTestCaseJ4.writeCoreProperties Writin= g core.properties file to /home/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest 90= 0E65E336ABF401-001/shard-2-001/cores/collection1 [junit4] 2> 361352 T2699 C:collection1 S:shard2 oasc.ZkController.regi= ster We are http://127.0.0.1:60061/s/collection1/ and leader is http://127.= 0.0.1:60061/s/collection1/ [junit4] 2> 361352 T2699 C:collection1 S:shard2 oasc.ZkController.regi= ster No LogReplay needed for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:= 60061/s [junit4] 2> 361352 T2699 C:collection1 S:shard2 oasc.ZkController.chec= kRecovery I am the leader, no recovery necessary [junit4] 2> 361352 T2635 oasc.AbstractFullDistribZkTestBase.createJett= ys create jetty 2 in directory /home/jenkins/workspace/Lucene-Solr-trunk-Li= nux/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTe= st 900E65E336ABF401-001/shard-2-001 [junit4] 2> 361353 T2699 C:collection1 S:shard2 c:collection1 oasc.ZkC= ontroller.publish publishing core=3Dcollection1 state=3Dactive collection= =3Dcollection1 [junit4] 2> 361353 T2699 C:collection1 S:shard2 c:collection1 oasc.ZkC= ontroller.publish numShards not found on descriptor - reading it from syste= m property [junit4] 2> 361353 T2635 oejs.Server.doStart jetty-9.2.9.v20150224 [junit4] 2> 361354 T2663 oasc.DistributedQueue$LatchWatcher.process No= deChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 361354 T2635 oejsh.ContextHandler.doStart Started o.e.j.s.= ServletContextHandler@15d360c{/s,null,AVAILABLE} [junit4] 2> 361355 T2664 oasc.Overseer$ClusterStateUpdater.run process= Message: queueSize: 1, message =3D { [junit4] 2> =09 "core":"collection1", [junit4] 2> =09 "core_node_name":"core_node1", [junit4] 2> =09 "roles":null, [junit4] 2> =09 "base_url":"http://127.0.0.1:60061/s", [junit4] 2> =09 "node_name":"127.0.0.1:60061_s", [junit4] 2> =09 "numShards":"3", [junit4] 2> =09 "state":"active", [junit4] 2> =09 "shard":"shard2", [junit4] 2> =09 "collection":"collection1", [junit4] 2> =09 "operation":"state"} current state version: 5 [junit4] 2> 361355 T2635 oejs.AbstractConnector.doStart Started Server= Connector@1a0b537{HTTP/1.1}{127.0.0.1:49811} [junit4] 2> 361356 T2635 oejs.Server.doStart Started @362242ms [junit4] 2> 361356 T2664 oasco.ReplicaMutator.updateState Update state= numShards=3D3 message=3D{ [junit4] 2> =09 "core":"collection1", [junit4] 2> =09 "core_node_name":"core_node1", [junit4] 2> =09 "roles":null, [junit4] 2> =09 "base_url":"http://127.0.0.1:60061/s", [junit4] 2> =09 "node_name":"127.0.0.1:60061_s", [junit4] 2> =09 "numShards":"3", [junit4] 2> =09 "state":"active", [junit4] 2> =09 "shard":"shard2", [junit4] 2> =09 "collection":"collection1", [junit4] 2> =09 "operation":"state"} [junit4] 2> 361356 T2635 oascse.JettySolrRunner$1.lifeCycleStarted Jet= ty properties: {solr.data.dir=3D/home/jenkins/workspace/Lucene-Solr-trunk-L= inux/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsT= est 900E65E336ABF401-001/tempDir-001/jetty2, solrconfig=3Dsolrconfig.xml, h= ostContext=3D/s, hostPort=3D49811, coreRootDirectory=3D/home/jenkins/worksp= ace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/../../../../../../= ../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-cor= e/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest 900E65E336ABF401-001= /shard-2-001/cores} [junit4] 2> 361357 T2635 oass.SolrDispatchFilter.init SolrDispatchFilt= er.init()sun.misc.Launcher$AppClassLoader@e2f2a [junit4] 2> 361357 T2699 C:collection1 S:shard2 oascc.ZkStateReader.up= dateWatchedCollection Updating data for collection1 to ver 3=20 [junit4] 2> 361357 T2664 oasco.ZkStateWriter.writePendingUpdates going= to update_collection /collections/collection1/state.json version: 3 [junit4] 2> 361357 T2635 oasc.SolrResourceLoader. new SolrResour= ceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/so= lr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspac= e/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.Full= SolrCloudDistribCmdsTest 900E65E336ABF401-001/shard-2-001/' [junit4] 2> 361358 T2694 oascc.ZkStateReader$7.process A cluster state= change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collec= tions/collection1/state.json for collection collection1 has occurred - upda= ting... (live nodes size: 2) [junit4] 2> 361359 T2694 oascc.ZkStateReader.updateWatchedCollection U= pdating data for collection1 to ver 4=20 [junit4] 2> 361371 T2635 oasc.SolrXmlConfig.fromFile Loading container= configuration from /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/bu= ild/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Luc= ene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrC= loudDistribCmdsTest 900E65E336ABF401-001/shard-2-001/solr.xml [junit4] 2> 361375 T2635 oasc.CorePropertiesLocator. Config-defi= ned core root directory: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/so= lr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspac= e/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.Full= SolrCloudDistribCmdsTest 900E65E336ABF401-001/shard-2-001/cores [junit4] 2> 361375 T2635 oasc.CoreContainer. New CoreContainer 3= 0176363 [junit4] 2> 361376 T2635 oasc.CoreContainer.load Loading cores into Co= reContainer [instanceDir=3D/home/jenkins/workspace/Lucene-Solr-trunk-Linux/= solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/worksp= ace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.Fu= llSolrCloudDistribCmdsTest 900E65E336ABF401-001/shard-2-001/] [junit4] 2> 361376 T2635 oasc.CoreContainer.load loading shared librar= y: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/tes= t/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Li= nux/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTe= st 900E65E336ABF401-001/shard-2-001/lib [junit4] 2> 361376 T2635 oasc.SolrResourceLoader.addToClassLoader WARN= Can't find (or read) directory to add to classloader: lib (resolved as: /h= ome/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/= ../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/s= olr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest 90= 0E65E336ABF401-001/shard-2-001/lib). [junit4] 2> 361383 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting socketTimeout to: 90000 [junit4] 2> 361384 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting urlScheme to:=20 [junit4] 2> 361384 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting connTimeout to: 15000 [junit4] 2> 361384 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting maxConnectionsPerHost to: 20 [junit4] 2> 361384 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting maxConnections to: 10000 [junit4] 2> 361385 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting corePoolSize to: 0 [junit4] 2> 361385 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting maximumPoolSize to: 2147483647 [junit4] 2> 361385 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting maxThreadIdleTime to: 5 [junit4] 2> 361385 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting sizeOfQueue to: -1 [junit4] 2> 361386 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting fairnessPolicy to: false [junit4] 2> 361386 T2635 oashc.HttpShardHandlerFactory.getParameter Se= tting useRetries to: false [junit4] 2> 361386 T2635 oasu.UpdateShardHandler. Creating Updat= eShardHandler HTTP client with params: socketTimeout=3D340000&connTimeout= =3D45000&retry=3Dtrue [junit4] 2> 361387 T2635 oasl.LogWatcher.createWatcher SLF4J impl is o= rg.slf4j.impl.Log4jLoggerFactory [junit4] 2> 361387 T2635 oasl.LogWatcher.newRegisteredLogWatcher Regis= tering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 361387 T2635 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 361388 T2635 oasc.ZkContainer.initZooKeeper Zookeeper clie= nt=3D127.0.0.1:43312/solr [junit4] 2> 361388 T2635 oasc.ZkController.checkChrootPath zkHost incl= udes chroot [junit4] 2> 361388 T2635 oascc.SolrZkClient.createZkCredentialsToAddAu= tomatically Using default ZkCredentialsProvider [junit4] 2> 361389 T2635 oascc.ConnectionManager.waitForConnected Wait= ing for client to connect to ZooKeeper [junit4] 2> 361390 T2713 oascc.ConnectionManager.process Watcher org.a= pache.solr.common.cloud.ConnectionManager@462d8e name:ZooKeeperConnection W= atcher:127.0.0.1:43312 got event WatchedEvent state:SyncConnected type:None= path:null path:null type:None [junit4] 2> 361391 T2635 oascc.ConnectionManager.waitForConnected Clie= nt is connected to ZooKeeper [junit4] 2> 361391 T2635 oascc.SolrZkClient.createZkACLProvider Using = default ZkACLProvider [junit4] 2> 361393 T2635 oascc.ConnectionManager.waitForConnected Wait= ing for client to connect to ZooKeeper [junit4] 2> 361394 T2716 oascc.ConnectionManager.process Watcher org.a= pache.solr.common.cloud.ConnectionManager@1a61910 name:ZooKeeperConnection = Watcher:127.0.0.1:43312/solr got event WatchedEvent state:SyncConnected typ= e:None path:null path:null type:None [junit4] 2> 361394 T2635 oascc.ConnectionManager.waitForConnected Clie= nt is connected to ZooKeeper [junit4] 2> 361397 T2635 oascc.ZkStateReader.createClusterStateWatcher= sAndUpdate Updating cluster state from ZooKeeper...=20 [junit4] 2> 362401 T2635 oasc.ZkController.createEphemeralLiveNode Reg= ister node as live in ZooKeeper:/live_nodes/127.0.0.1:49811_s [junit4] 2> 362402 T2635 oascc.SolrZkClient.makePath makePath: /live_n= odes/127.0.0.1:49811_s [junit4] 2> 362403 T2635 oasc.Overseer.close Overseer (id=3Dnull) clos= ing [junit4] 2> 362405 T2635 oasc.CorePropertiesLocator.discover Looking f= or core definitions underneath /home/jenkins/workspace/Lucene-Solr-trunk-Li= nux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/wo= rkspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.clou= d.FullSolrCloudDistribCmdsTest 900E65E336ABF401-001/shard-2-001/cores [junit4] 2> 362406 T2635 oasc.CoreDescriptor. CORE DESCRIPTOR: {= name=3Dcollection1, config=3Dsolrconfig.xml, transient=3Dfalse, schema=3Dsc= hema.xml, loadOnStartup=3Dtrue, instanceDir=3D/home/jenkins/workspace/Lucen= e-Solr-trunk-Linux/solr/build/solr-core/test/J2/../../../../../../../../../= home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2= /temp/solr.cloud.FullSolrCloudDistribCmdsTest 900E65E336ABF401-001/shard-2-= 001/cores/collection1, collection=3Dcollection1, absoluteInstDir=3D/home/je= nkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/../../= ../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/bu= ild/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest 900E65E3= 36ABF401-001/shard-2-001/cores/collection1/, coreNodeName=3D, dataDir=3Ddat= a/, shard=3D} [junit4] 2> 362407 T2635 oasc.CorePropertiesLocator.discoverUnder Foun= d core collection1 in /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/= build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/L= ucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.FullSol= rCloudDistribCmdsTest 900E65E336ABF401-001/shard-2-001/cores/collection1/ [junit4] 2> 362407 T2635 oasc.CorePropertiesLocator.discover Found 1 c= ore definitions [junit4] 2> 362408 T2717 C:collection1 c:collection1 oasc.ZkController= .publish publishing core=3Dcollection1 state=3Ddown collection=3Dcollection= 1 [junit4] 2> 362408 T2717 C:collection1 c:collection1 oasc.ZkController= .publish numShards not found on descriptor - reading it from system propert= y [junit4] 2> 362409 T2663 oasc.DistributedQueue$LatchWatcher.process No= deChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 362410 T2717 C:collection1 c:collection1 oasc.ZkController= .preRegister Registering watch for external collection collection1 [junit4] 2> 362410 T2664 oasc.Overseer$ClusterStateUpdater.run process= Message: queueSize: 1, message =3D { [junit4] 2> =09 "core":"collection1", [junit4] 2> =09 "roles":null, [junit4] 2> =09 "base_url":"http://127.0.0.1:49811/s", [junit4] 2> =09 "node_name":"127.0.0.1:49811_s", [junit4] 2> =09 "numShards":"3", [junit4] 2> =09 "state":"down", [junit4] 2> =09 "shard":null, [junit4] 2> =09 "collection":"collection1", [junit4] 2> =09 "operation":"state"} current state version: 5 [junit4] 2> 362410 T2717 C:collection1 c:collection1 oascc.ZkStateRead= er.addZkWatch addZkWatch collection1 [junit4] 2> 362411 T2664 oasco.ReplicaMutator.updateState Update state= numShards=3D3 message=3D{ [junit4] 2> =09 "core":"collection1", [junit4] 2> =09 "roles":null, [junit4] 2> =09 "base_url":"http://127.0.0.1:49811/s", [junit4] 2> =09 "node_name":"127.0.0.1:49811_s", [junit4] 2> =09 "numShards":"3", [junit4] 2> =09 "state":"down", [junit4] 2> =09 "shard":null, [junit4] 2> =09 "collection":"collection1", [junit4] 2> =09 "operation":"state"} [junit4] 2> 362411 T2717 C:collection1 c:collection1 oascc.ZkStateRead= er.addZkWatch Updating collection state at /collections/collection1/state.j= son from ZooKeeper...=20 [junit4] 2> 362411 T2664 oasco.ReplicaMutator.updateState Collection a= lready exists with numShards=3D3 [junit4] 2> 362411 T2664 oasco.ReplicaMutator.updateState Assigning ne= w node to shard shard=3Dshard3 [junit4] 2> 362412 T2717 C:collection1 c:collection1 oascc.ZkStateRead= er.updateWatchedCollection Updating data for collection1 to ver 4=20 [junit4] 2> 362412 T2717 C:collection1 c:collection1 oasc.ZkController= .waitForCoreNodeName look for our core node name [junit4] 2> 362412 T2664 oasco.ZkStateWriter.writePendingUpdates going= to update_collection /collections/collection1/state.json version: 4 [junit4] 2> 362412 T2716 oascc.ZkStateReader$7.process A cluster state= change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collec= tions/collection1/state.json for collection collection1 has occurred - upda= ting... (live nodes size: 3) [junit4] 2> 362412 T2694 oascc.ZkStateReader$7.process A cluster state= change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collec= tions/collection1/state.json for collection collection1 has occurred - upda= ting... (live nodes size: 3) [junit4] 2> 362413 T2716 oascc.ZkStateReader.updateWatchedCollection U= pdating data for collection1 to ver 5=20 [junit4] 2> 362413 T2694 oascc.ZkStateReader.updateWatchedCollection U= pdating data for collection1 to ver 5=20 [junit4] 2> 363412 T2717 C:collection1 c:collection1 oasc.ZkController= .waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 363412 T2717 C:collection1 c:collection1 oasc.ZkController= .createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 363413 T2717 C:collection1 c:collection1 oasc.ZkController= .createCollectionZkNode Collection zkNode exists [junit4] 2> 363413 T2717 C:collection1 c:collection1 oascc.ZkStateRead= er.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 363414 T2717 C:collection1 c:collection1 oascc.ZkStateRead= er.readConfigName path=3D/collections/collection1 configName=3Dconf1 specif= ied config exists in ZooKeeper [junit4] 2> 363414 T2717 C:collection1 c:collection1 oasc.SolrResource= Loader. new SolrResourceLoader for directory: '/home/jenkins/workspac= e/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/../../../../../../..= /../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/= test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest 900E65E336ABF401-001/s= hard-2-001/cores/collection1/' [junit4] 2> 363428 T2717 C:collection1 c:collection1 oasc.ZkController= .watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 363430 T2717 C:collection1 c:collection1 oasc.Config. loaded config solrconfig.xml with version 0=20 [junit4] 2> 363436 T2717 C:collection1 c:collection1 oasc.SolrConfig.r= efreshRequestParams current version of requestparams : -1 [junit4] 2> 363439 T2717 C:collection1 c:collection1 oasc.SolrConfig.<= init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 363447 T2717 C:collection1 c:collection1 oasc.SolrConfig.<= init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 363448 T2717 C:collection1 c:collection1 oass.IndexSchema.= readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 363452 T2717 C:collection1 c:collection1 oass.IndexSchema.= readSchema [collection1] Schema name=3Dtest [junit4] 2> 363529 T2717 C:collection1 c:collection1 oass.IndexSchema.= readSchema default search field in schema is text [junit4] 2> 363530 T2717 C:collection1 c:collection1 oass.IndexSchema.= readSchema unique key field: id [junit4] 2> 363531 T2717 C:collection1 c:collection1 oass.FileExchange= RateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 363533 T2717 C:collection1 c:collection1 oass.FileExchange= RateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 363545 T2717 C:collection1 c:collection1 oasc.CoreContaine= r.create Creating SolrCore 'collection1' using configuration from collectio= n collection1 [junit4] 2> 363545 T2717 C:c [...truncated too long message...] tingsFormat(name=3DLuceneVarGapDocFreqInterval), 15grand2_s=3DPostingsForma= t(name=3DDirect), 8child4_s=3DPostingsFormat(name=3DAsserting), 23parent_f1= _s=3DPostingsFormat(name=3DLuceneVarGapDocFreqInterval), 5child2_s=3DPostin= gsFormat(name=3DLuceneVarGapDocFreqInterval), 13grand4_s=3DPostingsFormat(n= ame=3DDirect), 29parent_f1_s=3DPostingsFormat(name=3DLuceneVarGapDocFreqInt= erval), 33parent_f1_s=3DPostingsFormat(name=3DAsserting), 39parent_f1_s=3DP= ostingsFormat(name=3DDirect), 12parent_f1_s=3DPostingsFormat(name=3DLuceneV= arGapDocFreqInterval), 22parent_f1_s=3DPostingsFormat(name=3DAsserting), 12= grand1_s=3DPostingsFormat(name=3DDirect), 11grand6_s=3DPostingsFormat(name= =3DDirect), 2child1_s=3DPostingsFormat(name=3DLuceneVarGapDocFreqInterval),= 3parent_f2_s=3DPostingsFormat(name=3DDirect), 32parent_f1_s=3DTestBloomFil= teredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=3D128))= ), 10grand3_s=3DPostingsFormat(name=3DDirect), 6grand3_s=3DTestBloomFiltere= dLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=3D128))), 3= 1parent_f2_s=3DPostingsFormat(name=3DLuceneVarGapDocFreqInterval), 41parent= _f2_s=3DPostingsFormat(name=3DAsserting), 11parent_f2_s=3DTestBloomFiltered= LucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=3D128))), 21= parent_f2_s=3DPostingsFormat(name=3DDirect), 7grand4_s=3DTestBloomFilteredL= ucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=3D128))), 3gr= and0_s=3DTestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucen= e50(blocksize=3D128))), 8grand1_s=3DTestBloomFilteredLucenePostings(BloomFi= lteringPostingsFormat(Lucene50(blocksize=3D128))), 9grand2_s=3DPostingsForm= at(name=3DLuceneVarGapDocFreqInterval), 9parent_f1_s=3DTestBloomFilteredLuc= enePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=3D128))), 14gra= nd9_s=3DPostingsFormat(name=3DAsserting), 14grand11_s=3DPostingsFormat(name= =3DLuceneVarGapDocFreqInterval), routefield_s=3DPostingsFormat(name=3DLucen= eVarGapDocFreqInterval), 13parent_f2_s=3DPostingsFormat(name=3DLuceneVarGap= DocFreqInterval), 23parent_f2_s=3DPostingsFormat(name=3DAsserting), 8child5= _s=3DPostingsFormat(name=3DLuceneVarGapDocFreqInterval), 15grand1_s=3DTestB= loomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize= =3D128))), 5child1_s=3DPostingsFormat(name=3DDirect), 14grand0_s=3DTestBloo= mFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=3D1= 28))), 33parent_f2_s=3DTestBloomFilteredLucenePostings(BloomFilteringPostin= gsFormat(Lucene50(blocksize=3D128))), 13grand3_s=3DTestBloomFilteredLuceneP= ostings(BloomFilteringPostingsFormat(Lucene50(blocksize=3D128))), 22parent_= f2_s=3DTestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene5= 0(blocksize=3D128))), 4child0_s=3DPostingsFormat(name=3DAsserting), 11grand= 5_s=3DTestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50= (blocksize=3D128))), 12grand2_s=3DTestBloomFilteredLucenePostings(BloomFilt= eringPostingsFormat(Lucene50(blocksize=3D128))), 12parent_f2_s=3DPostingsFo= rmat(name=3DAsserting), 10grand4_s=3DTestBloomFilteredLucenePostings(BloomF= ilteringPostingsFormat(Lucene50(blocksize=3D128))), 32parent_f2_s=3DPosting= sFormat(name=3DDirect), 21parent_f1_s=3DTestBloomFilteredLucenePostings(Blo= omFilteringPostingsFormat(Lucene50(blocksize=3D128))), 8grand2_s=3DPostings= Format(name=3DDirect), 9grand1_s=3DPostingsFormat(name=3DDirect), 41parent_= f1_s=3DPostingsFormat(name=3DLuceneVarGapDocFreqInterval), 12grand8_s=3DPos= tingsFormat(name=3DLuceneVarGapDocFreqInterval), 40parent_f2_s=3DTestBloomF= ilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=3D128= ))), 13grand7_s=3DTestBloomFilteredLucenePostings(BloomFilteringPostingsFor= mat(Lucene50(blocksize=3D128))), 14parent_f1_s=3DTestBloomFilteredLucenePos= tings(BloomFilteringPostingsFormat(Lucene50(blocksize=3D128))), 14grand10_s= =3DPostingsFormat(name=3DAsserting), 20parent_f2_s=3DPostingsFormat(name=3D= LuceneVarGapDocFreqInterval), 34parent_f1_s=3DPostingsFormat(name=3DLuceneV= arGapDocFreqInterval), 6child4_s=3DPostingsFormat(name=3DDirect), 15grand9_= s=3DTestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(b= locksize=3D128))), 6grand0_s=3DPostingsFormat(name=3DDirect), 7child3_s=3DP= ostingsFormat(name=3DDirect), 15grand0_s=3DPostingsFormat(name=3DAsserting)= , 8child6_s=3DPostingsFormat(name=3DDirect), 5child0_s=3DTestBloomFilteredL= ucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=3D128))), 14g= rand1_s=3DPostingsFormat(name=3DAsserting), 8parent_f1_s=3DPostingsFormat(n= ame=3DAsserting), title_lettertok=3DPostingsFormat(name=3DLuceneVarGapDocFr= eqInterval), 15grand13_s=3DPostingsFormat(name=3DDirect), 27parent_f2_s=3DP= ostingsFormat(name=3DAsserting), 2parent_f2_s=3DTestBloomFilteredLucenePost= ings(BloomFilteringPostingsFormat(Lucene50(blocksize=3D128))), 4child1_s=3D= PostingsFormat(name=3DLuceneVarGapDocFreqInterval), 11grand4_s=3DPostingsFo= rmat(name=3DAsserting), 16parent_f2_s=3DPostingsFormat(name=3DAsserting), 1= 0grand5_s=3DPostingsFormat(name=3DAsserting), 36parent_f2_s=3DPostingsForma= t(name=3DDirect), 13grand10_s=3DPostingsFormat(name=3DLuceneVarGapDocFreqIn= terval), 7grand6_s=3DPostingsFormat(name=3DLuceneVarGapDocFreqInterval), 18= parent_f1_s=3DPostingsFormat(name=3DLuceneVarGapDocFreqInterval), 9grand8_s= =3DTestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(bl= ocksize=3D128))), 8grand3_s=3DPostingsFormat(name=3DLuceneVarGapDocFreqInte= rval), 6parent_f1_s=3DPostingsFormat(name=3DDirect), 25parent_f1_s=3DTestBl= oomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize= =3D128))), 12grand9_s=3DPostingsFormat(name=3DDirect), 0parent_f1_s=3DPosti= ngsFormat(name=3DAsserting), 3grand2_s=3DPostingsFormat(name=3DLuceneVarGap= DocFreqInterval), 9grand0_s=3DTestBloomFilteredLucenePostings(BloomFilterin= gPostingsFormat(Lucene50(blocksize=3D128))), 4parent_f2_s=3DPostingsFormat(= name=3DLuceneVarGapDocFreqInterval), 13grand6_s=3DPostingsFormat(name=3DAss= erting), 6child5_s=3DTestBloomFilteredLucenePostings(BloomFilteringPostings= Format(Lucene50(blocksize=3D128))), 7child2_s=3DTestBloomFilteredLucenePost= ings(BloomFilteringPostingsFormat(Lucene50(blocksize=3D128))), 5grand4_s=3D= PostingsFormat(name=3DLuceneVarGapDocFreqInterval), 38parent_f1_s=3DPosting= sFormat(name=3DLuceneVarGapDocFreqInterval), 6grand1_s=3DPostingsFormat(nam= e=3DLuceneVarGapDocFreqInterval), 15grand8_s=3DPostingsFormat(name=3DAssert= ing), 8child7_s=3DTestBloomFilteredLucenePostings(BloomFilteringPostingsFor= mat(Lucene50(blocksize=3D128))), 16parent_f1_s=3DTestBloomFilteredLucenePos= tings(BloomFilteringPostingsFormat(Lucene50(blocksize=3D128))), 14grand2_s= =3DPostingsFormat(name=3DLuceneVarGapDocFreqInterval), 8parent_f2_s=3DPosti= ngsFormat(name=3DLuceneVarGapDocFreqInterval), 15grand14_s=3DTestBloomFilte= redLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=3D128))),= 13grand5_s=3DPostingsFormat(name=3DLuceneVarGapDocFreqInterval), 29parent_= f2_s=3DPostingsFormat(name=3DDirect), 2parent_f1_s=3DPostingsFormat(name=3D= Direct), 4child2_s=3DPostingsFormat(name=3DDirect), rnd_b=3DPostingsFormat(= name=3DLuceneVarGapDocFreqInterval), 11grand3_s=3DPostingsFormat(name=3DLuc= eneVarGapDocFreqInterval), 27parent_f1_s=3DPostingsFormat(name=3DLuceneVarG= apDocFreqInterval), title_stemmed=3DPostingsFormat(name=3DAsserting), 12gra= nd0_s=3DPostingsFormat(name=3DLuceneVarGapDocFreqInterval), 10grand6_s=3DPo= stingsFormat(name=3DLuceneVarGapDocFreqInterval), 2child0_s=3DPostingsForma= t(name=3DDirect), 13grand11_s=3DPostingsFormat(name=3DDirect), 36parent_f1_= s=3DTestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(b= locksize=3D128)))}, docValues:{}, sim=3DDefaultSimilarity, locale=3Dja, tim= ezone=3DAtlantic/Cape_Verde [junit4] 2> NOTE: Linux 3.13.0-46-generic i386/Oracle Corporation 1.8.= 0_40 (32-bit)/cpus=3D12,threads=3D1,free=3D60864792,total=3D256901120 [junit4] 2> NOTE: All tests run in this JVM: [ResponseLogComponentTest= , NotRequiredUniqueKeyTest, OpenExchangeRatesOrgProviderTest, SpatialRPTFie= ldTypeTest, TestFieldCollectionResource, MultiThreadedOCPTest, HdfsBasicDis= tributedZk2Test, SortByFunctionTest, PreAnalyzedUpdateProcessorTest, TestAd= dFieldRealTimeGet, TestRequestStatusCollectionAPI, PolyFieldTest, CachingDi= rectoryFactoryTest, StatsComponentTest, DeleteReplicaTest, LoggingHandlerTe= st, TestLuceneMatchVersion, TestTolerantSearch, TestQueryTypes, XsltUpdateR= equestHandlerTest, TestNoOpRegenerator, SoftAutoCommitTest, TestSolrQueryPa= rser, LeaderElectionTest, TestUtils, TestJsonFacets, DeleteInactiveReplicaT= est, XmlUpdateRequestHandlerTest, DocValuesTest, CloudMLTQParserTest, Synon= ymTokenizerTest, FacetPivotSmallTest, HdfsLockFactoryTest, OverseerTest, Te= stExactStatsCache, TestMissingGroups, TestObjectReleaseTracker, TestGroupin= gSearch, TestRecovery, TestStressRecovery, DistanceUnitsTest, DOMUtilTest, = ConnectionManagerTest, TestSchemaSimilarityResource, OverriddenZkACLAndCred= entialsProvidersTest, AssignTest, NumericFieldsTest, OutputWriterTest, Test= FieldSortValues, TestSort, SOLR749Test, FullSolrCloudDistribCmdsTest] [junit4] Completed on J2 in 53.43s, 1 test, 1 error <<< FAILURES! [...truncated 1073 lines...] BUILD FAILED /home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:519: The followin= g error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:467: The followin= g error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:61: The following= error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/extra-targets.xml:39: The f= ollowing error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:191: The fol= lowing error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:510: = The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:135= 1: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:958= : There were test failures: 479 suites, 1901 tests, 1 error, 48 ignored (20= assumptions) Total time: 41 minutes 47 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 32bit/jdk1.8.0_40 -client -XX:+= UseParallelGC Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any ------=_Part_202_1959334902.1427845780425 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_202_1959334902.1427845780425--