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 1ECF7D053 for ; Sat, 18 May 2013 16:57:15 +0000 (UTC) Received: (qmail 48165 invoked by uid 500); 18 May 2013 16:57:13 -0000 Delivered-To: apmail-lucene-dev-archive@lucene.apache.org Received: (qmail 48114 invoked by uid 500); 18 May 2013 16:57:13 -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 48106 invoked by uid 99); 18 May 2013 16:57:13 -0000 Received: from nike.apache.org (HELO nike.apache.org) (192.87.106.230) by apache.org (qpsmtpd/0.29) with ESMTP; Sat, 18 May 2013 16:57:13 +0000 X-ASF-Spam-Status: No, hits=0.0 required=5.0 tests=NORMAL_HTTP_TO_IP,SPF_PASS,WEIRD_PORT X-Spam-Check-By: apache.org Received-SPF: pass (nike.apache.org: domain of jenkins@thetaphi.de designates 188.138.97.18 as permitted sender) Received: from [188.138.97.18] (HELO mail.sd-datasolutions.de) (188.138.97.18) by apache.org (qpsmtpd/0.29) with ESMTP; Sat, 18 May 2013 16:56:51 +0000 Received: from serv1 (localhost.localdomain [127.0.0.1]) by mail.sd-datasolutions.de (Postfix) with ESMTP id EEB0814AA080; Sat, 18 May 2013 16:56:30 +0000 (UTC) Date: Sat, 18 May 2013 16:56:12 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org, jpountz@apache.org Message-ID: <1110566884.189.1368896190964.JavaMail.jenkins@serv1.sd-datasolutions.de> In-Reply-To: <1167557218.177.1368870839699.JavaMail.jenkins@serv1.sd-datasolutions.de> References: <1167557218.177.1368870839699.JavaMail.jenkins@serv1.sd-datasolutions.de> Subject: [JENKINS] Lucene-Solr-trunk-Windows (64bit/jdk1.7.0_21) - Build # 2834 - Still Failing! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_188_1300250285.1368896172292" X-Jenkins-Job: Lucene-Solr-trunk-Windows X-Jenkins-Result: FAILURE X-Virus-Checked: Checked by ClamAV on apache.org ------=_Part_188_1300250285.1368896172292 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/2834/ Java: 64bit/jdk1.7.0_21 -XX:+UseCompressedOops -XX:+UseParallelGC 1 tests failed. REGRESSION: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testDist= ribSearch Error Message: There are still nodes recoverying - waited for 230 seconds Stack Trace: java.lang.AssertionError: There are still nodes recoverying - waited for 23= 0 seconds =09at __randomizedtesting.SeedInfo.seed([7DA14641F86B0A99:FC47C8598F346AA5]= :0) =09at org.junit.Assert.fail(Assert.java:93) =09at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFi= nish(AbstractDistribZkTestBase.java:173) =09at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFi= nish(AbstractDistribZkTestBase.java:131) =09at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFi= nish(AbstractDistribZkTestBase.java:126) =09at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollections= API(CollectionsAPIDistributedZkTest.java:512) =09at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.doTest(Collecti= onsAPIDistributedZkTest.java:146) =09at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseD= istributedSearchTestCase.java:815) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:57) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:601) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1559) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(Random= izedRunner.java:79) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:737) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:773) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:787) =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.TestRuleFieldCacheSanity$1.evaluate(TestRuleFi= eldCacheSanity.java:51) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:46) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRul= e$1.evaluate(SystemPropertiesInvariantRule.java:55) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:49) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:70) =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:358) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:782) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:442) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:746) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(Random= izedRunner.java:648) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(Random= izedRunner.java:682) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:693) =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 org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:42) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRul= e$1.evaluate(SystemPropertiesInvariantRule.java:55) =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 org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:43) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:70) =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:358) =09at java.lang.Thread.run(Thread.java:722) Build Log: [...truncated 9550 lines...] [junit4:junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTes= t [junit4:junit4] 2> 1454146 T4190 oas.BaseDistributedSearchTestCase.initHo= stContext Setting hostContext system property: / [junit4:junit4] 2> 1454151 T4190 oas.SolrTestCaseJ4.setUp ###Starting tes= tDistribSearch [junit4:junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Luce= ne-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-CollectionsAP= IDistributedZkTest-1368894995842 [junit4:junit4] 2> 1454153 T4190 oasc.ZkTestServer.run STARTING ZK TEST S= ERVER [junit4:junit4] 2> 1454154 T4191 oasc.ZkTestServer$ZKServerMain.runFromCo= nfig Starting server [junit4:junit4] 2> 1454254 T4190 oasc.ZkTestServer.run start zk server on= port:60418 [junit4:junit4] 2> 1454255 T4190 oascc.ConnectionManager.waitForConnected= Waiting for client to connect to ZooKeeper [junit4:junit4] 2> 1454268 T4197 oascc.ConnectionManager.process Watcher = org.apache.solr.common.cloud.ConnectionManager@7d4aa62 name:ZooKeeperConnec= tion Watcher:127.0.0.1:60418 got event WatchedEvent state:SyncConnected typ= e:None path:null path:null type:None [junit4:junit4] 2> 1454269 T4190 oascc.ConnectionManager.waitForConnected= Client is connected to ZooKeeper [junit4:junit4] 2> 1454270 T4190 oascc.SolrZkClient.makePath makePath: /s= olr [junit4:junit4] 2> 1454277 T4192 oazs.NIOServerCnxn.doIO WARN caught end = of stream exception EndOfStreamException: Unable to read additional data fr= om client sessionid 0x13eb87ffd8d0000, likely client has closed socket [junit4:junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxn.doIO(N= IOServerCnxn.java:220) [junit4:junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxnFactory= .run(NIOServerCnxnFactory.java:208) [junit4:junit4] 2> =09at java.lang.Thread.run(Thread.java:722) [junit4:junit4] 2>=20 [junit4:junit4] 2> 1454278 T4190 oascc.ConnectionManager.waitForConnected= Waiting for client to connect to ZooKeeper [junit4:junit4] 2> 1454282 T4199 oascc.ConnectionManager.process Watcher = org.apache.solr.common.cloud.ConnectionManager@4114cc1a name:ZooKeeperConne= ction Watcher:127.0.0.1:60418/solr got event WatchedEvent state:SyncConnect= ed type:None path:null path:null type:None [junit4:junit4] 2> 1454282 T4190 oascc.ConnectionManager.waitForConnected= Client is connected to ZooKeeper [junit4:junit4] 2> 1454282 T4190 oascc.SolrZkClient.makePath makePath: /c= ollections/collection1 [junit4:junit4] 2> 1454287 T4190 oascc.SolrZkClient.makePath makePath: /c= ollections/collection1/shards [junit4:junit4] 2> 1454293 T4190 oascc.SolrZkClient.makePath makePath: /c= ollections/control_collection [junit4:junit4] 2> 1454297 T4190 oascc.SolrZkClient.makePath makePath: /c= ollections/control_collection/shards [junit4:junit4] 2> 1454302 T4190 oasc.AbstractZkTestCase.putConfig put C:= \Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-cor= e\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/so= lrconfig.xml [junit4:junit4] 2> 1454303 T4190 oascc.SolrZkClient.makePath makePath: /c= onfigs/conf1/solrconfig.xml [junit4:junit4] 2> 1454312 T4190 oasc.AbstractZkTestCase.putConfig put C:= \Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-cor= e\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml [junit4:junit4] 2> 1454313 T4190 oascc.SolrZkClient.makePath makePath: /c= onfigs/conf1/schema.xml [junit4:junit4] 2> 1454319 T4190 oasc.AbstractZkTestCase.putConfig put C:= \Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-cor= e\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopword= s.txt [junit4:junit4] 2> 1454320 T4190 oascc.SolrZkClient.makePath makePath: /c= onfigs/conf1/stopwords.txt [junit4:junit4] 2> 1454325 T4190 oasc.AbstractZkTestCase.putConfig put C:= \Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-cor= e\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protword= s.txt [junit4:junit4] 2> 1454326 T4190 oascc.SolrZkClient.makePath makePath: /c= onfigs/conf1/protwords.txt [junit4:junit4] 2> 1454330 T4190 oasc.AbstractZkTestCase.putConfig put C:= \Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-cor= e\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.= xml [junit4:junit4] 2> 1454331 T4190 oascc.SolrZkClient.makePath makePath: /c= onfigs/conf1/currency.xml [junit4:junit4] 2> 1454336 T4190 oasc.AbstractZkTestCase.putConfig put C:= \Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-cor= e\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/con= f1/open-exchange-rates.json [junit4:junit4] 2> 1454336 T4190 oascc.SolrZkClient.makePath makePath: /c= onfigs/conf1/open-exchange-rates.json [junit4:junit4] 2> 1454341 T4190 oasc.AbstractZkTestCase.putConfig put C:= \Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-cor= e\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/= conf1/mapping-ISOLatin1Accent.txt [junit4:junit4] 2> 1454342 T4190 oascc.SolrZkClient.makePath makePath: /c= onfigs/conf1/mapping-ISOLatin1Accent.txt [junit4:junit4] 2> 1454347 T4190 oasc.AbstractZkTestCase.putConfig put C:= \Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-cor= e\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_s= ynonyms.txt [junit4:junit4] 2> 1454348 T4190 oascc.SolrZkClient.makePath makePath: /c= onfigs/conf1/old_synonyms.txt [junit4:junit4] 2> 1454353 T4190 oasc.AbstractZkTestCase.putConfig put C:= \Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-cor= e\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.= txt [junit4:junit4] 2> 1454353 T4190 oascc.SolrZkClient.makePath makePath: /c= onfigs/conf1/synonyms.txt [junit4:junit4] 2> 1454361 T4192 oazs.NIOServerCnxn.doIO WARN caught end = of stream exception EndOfStreamException: Unable to read additional data fr= om client sessionid 0x13eb87ffd8d0001, likely client has closed socket [junit4:junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxn.doIO(N= IOServerCnxn.java:220) [junit4:junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxnFactory= .run(NIOServerCnxnFactory.java:208) [junit4:junit4] 2> =09at java.lang.Thread.run(Thread.java:722) [junit4:junit4] 2>=20 [junit4:junit4] 2> 1454812 T4190 oejs.Server.doStart jetty-8.1.10.v201303= 12 [junit4:junit4] 2> 1454819 T4190 oejs.AbstractConnector.doStart Started S= electChannelConnector@127.0.0.1:60425 [junit4:junit4] 2> 1454820 T4190 oass.SolrDispatchFilter.init SolrDispatc= hFilter.init() [junit4:junit4] 2> 1454821 T4190 oasc.SolrResourceLoader.locateSolrHome J= NDI not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 1454821 T4190 oasc.SolrResourceLoader.locateSolrHome u= sing system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene= -Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Co= llectionsAPIDistributedZkTest-controljetty-1368894996050 [junit4:junit4] 2> 1454821 T4190 oasc.CoreContainer$Initializer.initializ= e looking for solr config file: C:\Users\JenkinsSlave\workspace\Lucene-Solr= -trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Collect= ionsAPIDistributedZkTest-controljetty-1368894996050\solr.xml [junit4:junit4] 2> 1454822 T4190 oasc.CoreContainer. New CoreContai= ner 542349926 [junit4:junit4] 2> 1454822 T4190 oasc.CoreContainer.load Loading CoreCont= ainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-W= indows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPID= istributedZkTest-controljetty-1368894996050\' [junit4:junit4] 2> 1454823 T4190 oasc.SolrResourceLoader. new SolrR= esourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-t= runk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Collectio= nsAPIDistributedZkTest-controljetty-1368894996050\' [junit4:junit4] 2> 1454914 T4190 oasc.SolrCoreDiscoverer.walkFromHere Loo= king for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\org.apache.solr.cloud.CollectionsAPIDistribut= edZkTest-controljetty-1368894996050 [junit4:junit4] 2> 1454917 T4190 oasc.SolrCoreDiscoverer.walkFromHere Loo= king for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\org.apache.solr.cloud.CollectionsAPIDistribut= edZkTest-controljetty-1368894996050\collection1 [junit4:junit4] 2> 1454918 T4190 oasc.SolrCoreDiscoverer.walkFromHere Loo= king for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\org.apache.solr.cloud.CollectionsAPIDistribut= edZkTest-controljetty-1368894996050\collection1\conf [junit4:junit4] 2> 1455144 T4190 oasc.SolrCoreDiscoverer.walkFromHere Loo= king for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\org.apache.solr.cloud.CollectionsAPIDistribut= edZkTest-controljetty-1368894996050\collection1\conf\xslt [junit4:junit4] 2> 1455152 T4190 oasc.SolrCoreDiscoverer.walkFromHere Loo= king for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\org.apache.solr.cloud.CollectionsAPIDistribut= edZkTest-controljetty-1368894996050\collection1\lib [junit4:junit4] 2> 1455155 T4190 oasc.SolrCoreDiscoverer.walkFromHere Loo= king for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\org.apache.solr.cloud.CollectionsAPIDistribut= edZkTest-controljetty-1368894996050\collection1\lib\classes [junit4:junit4] 2> 1455162 T4190 oasc.SolrCoreDiscoverer.addCore Discover= ed properties file C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Window= s\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistri= butedZkTest-controljetty-1368894996050\conf\core.properties, adding to core= s [junit4:junit4] 2> 1455178 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting socketTimeout to: 0 [junit4:junit4] 2> 1455179 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting urlScheme to: http:// [junit4:junit4] 2> 1455179 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting connTimeout to: 0 [junit4:junit4] 2> 1455179 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 1455180 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting corePoolSize to: 0 [junit4:junit4] 2> 1455181 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 1455181 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 1455182 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting sizeOfQueue to: -1 [junit4:junit4] 2> 1455182 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting fairnessPolicy to: false [junit4:junit4] 2> 1455182 T4190 oascsi.HttpClientUtil.createClient Creat= ing new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D100= 00&socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 1455190 T4190 oasl.LogWatcher.newRegisteredLogWatcher = Registering Log Listener [junit4:junit4] 2> 1455191 T4190 oasc.ZkContainer.initZooKeeper Zookeeper= client=3D127.0.0.1:60418/solr [junit4:junit4] 2> 1455191 T4190 oasc.ZkController.checkChrootPath zkHost= includes chroot [junit4:junit4] 2> 1455193 T4190 oascc.ConnectionManager.waitForConnected= Waiting for client to connect to ZooKeeper [junit4:junit4] 2> 1455207 T4210 oascc.ConnectionManager.process Watcher = org.apache.solr.common.cloud.ConnectionManager@81b5252 name:ZooKeeperConnec= tion Watcher:127.0.0.1:60418 got event WatchedEvent state:SyncConnected typ= e:None path:null path:null type:None [junit4:junit4] 2> 1455208 T4190 oascc.ConnectionManager.waitForConnected= Client is connected to ZooKeeper [junit4:junit4] 2> 1455212 T4190 oascsi.HttpClientUtil.createClient Creat= ing new http client, config:maxConnections=3D500&maxConnectionsPerHost=3D16= &socketTimeout=3D120000&connTimeout=3D15000 [junit4:junit4] 2> 1455212 T4192 oazs.NIOServerCnxn.doIO WARN caught end = of stream exception EndOfStreamException: Unable to read additional data fr= om client sessionid 0x13eb87ffd8d0002, likely client has closed socket [junit4:junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxn.doIO(N= IOServerCnxn.java:220) [junit4:junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxnFactory= .run(NIOServerCnxnFactory.java:208) [junit4:junit4] 2> =09at java.lang.Thread.run(Thread.java:722) [junit4:junit4] 2>=20 [junit4:junit4] 2> 1455218 T4190 oascc.ConnectionManager.waitForConnected= Waiting for client to connect to ZooKeeper [junit4:junit4] 2> 1455222 T4212 oascc.ConnectionManager.process Watcher = org.apache.solr.common.cloud.ConnectionManager@204b81f6 name:ZooKeeperConne= ction Watcher:127.0.0.1:60418/solr got event WatchedEvent state:SyncConnect= ed type:None path:null path:null type:None [junit4:junit4] 2> 1455222 T4190 oascc.ConnectionManager.waitForConnected= Client is connected to ZooKeeper [junit4:junit4] 2> 1455225 T4190 oascc.SolrZkClient.makePath makePath: /o= verseer/queue [junit4:junit4] 2> 1455233 T4190 oascc.SolrZkClient.makePath makePath: /o= verseer/collection-queue-work [junit4:junit4] 2> 1455239 T4190 oascc.SolrZkClient.makePath makePath: /l= ive_nodes [junit4:junit4] 2> 1455242 T4190 oasc.ZkController.createEphemeralLiveNod= e Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60425_ [junit4:junit4] 2> 1455245 T4190 oascc.SolrZkClient.makePath makePath: /l= ive_nodes/127.0.0.1:60425_ [junit4:junit4] 2> 1455249 T4190 oascc.SolrZkClient.makePath makePath: /o= verseer_elect/election [junit4:junit4] 2> 1455259 T4190 oascc.SolrZkClient.makePath makePath: /o= verseer_elect/leader [junit4:junit4] 2> 1455266 T4190 oasc.Overseer.start Overseer (id=3D89711= 902448222211-127.0.0.1:60425_-n_0000000000) starting [junit4:junit4] 2> 1455271 T4190 oascc.SolrZkClient.makePath makePath: /o= verseer/queue-work [junit4:junit4] 2> 1455284 T4214 oasc.OverseerCollectionProcessor.run Pro= cess current queue of collection creations [junit4:junit4] 2> 1455285 T4190 oascc.SolrZkClient.makePath makePath: /c= lusterstate.json [junit4:junit4] 2> 1455288 T4190 oascc.SolrZkClient.makePath makePath: /a= liases.json [junit4:junit4] 2> 1455292 T4190 oascc.ZkStateReader.createClusterStateWa= tchersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 1455295 T4213 oasc.Overseer$ClusterStateUpdater.run St= arting to work on the main queue [junit4:junit4] 2> 1455299 T4190 oass.SolrDispatchFilter.init user.dir=3D= C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-c= ore\test\J0 [junit4:junit4] 2> 1455299 T4190 oass.SolrDispatchFilter.init SolrDispatc= hFilter.init() done [junit4:junit4] 2> 1455299 T4190 oascsi.HttpClientUtil.createClient Creat= ing new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32= &followRedirects=3Dfalse [junit4:junit4] 2> 1455304 T4190 oascsi.HttpClientUtil.createClient Creat= ing new http client, config: [junit4:junit4] 2> 1455306 T4190 oascc.ConnectionManager.waitForConnected= Waiting for client to connect to ZooKeeper [junit4:junit4] 2> 1455310 T4217 oascc.ConnectionManager.process Watcher = org.apache.solr.common.cloud.ConnectionManager@7877705e name:ZooKeeperConne= ction Watcher:127.0.0.1:60418/solr got event WatchedEvent state:SyncConnect= ed type:None path:null path:null type:None [junit4:junit4] 2> 1455311 T4190 oascc.ConnectionManager.waitForConnected= Client is connected to ZooKeeper [junit4:junit4] 2> 1455312 T4190 oascc.ZkStateReader.createClusterStateWa= tchersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 1455315 T4190 oasc.ChaosMonkey.monkeyLog monkey: init = - expire sessions:true cause connection loss:true [junit4:junit4] 2> 1455792 T4190 oejs.Server.doStart jetty-8.1.10.v201303= 12 [junit4:junit4] 2> 1455796 T4190 oejs.AbstractConnector.doStart Started S= electChannelConnector@127.0.0.1:60437 [junit4:junit4] 2> 1455798 T4190 oass.SolrDispatchFilter.init SolrDispatc= hFilter.init() [junit4:junit4] 2> 1455798 T4190 oasc.SolrResourceLoader.locateSolrHome J= NDI not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 1455799 T4190 oasc.SolrResourceLoader.locateSolrHome u= sing system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene= -Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Co= llectionsAPIDistributedZkTest-jetty1-1368894997005 [junit4:junit4] 2> 1455799 T4190 oasc.CoreContainer$Initializer.initializ= e looking for solr config file: C:\Users\JenkinsSlave\workspace\Lucene-Solr= -trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Collect= ionsAPIDistributedZkTest-jetty1-1368894997005\solr.xml [junit4:junit4] 2> 1455799 T4190 oasc.CoreContainer. New CoreContai= ner 2012367928 [junit4:junit4] 2> 1455800 T4190 oasc.CoreContainer.load Loading CoreCont= ainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-W= indows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPID= istributedZkTest-jetty1-1368894997005\' [junit4:junit4] 2> 1455800 T4190 oasc.SolrResourceLoader. new SolrR= esourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-t= runk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Collectio= nsAPIDistributedZkTest-jetty1-1368894997005\' [junit4:junit4] 2> 1455900 T4190 oasc.SolrCoreDiscoverer.walkFromHere Loo= king for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\org.apache.solr.cloud.CollectionsAPIDistribut= edZkTest-jetty1-1368894997005 [junit4:junit4] 2> 1455903 T4190 oasc.SolrCoreDiscoverer.walkFromHere Loo= king for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\org.apache.solr.cloud.CollectionsAPIDistribut= edZkTest-jetty1-1368894997005\collection1 [junit4:junit4] 2> 1455905 T4190 oasc.SolrCoreDiscoverer.walkFromHere Loo= king for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\org.apache.solr.cloud.CollectionsAPIDistribut= edZkTest-jetty1-1368894997005\collection1\conf [junit4:junit4] 2> 1456167 T4190 oasc.SolrCoreDiscoverer.walkFromHere Loo= king for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\org.apache.solr.cloud.CollectionsAPIDistribut= edZkTest-jetty1-1368894997005\collection1\conf\xslt [junit4:junit4] 2> 1456175 T4190 oasc.SolrCoreDiscoverer.walkFromHere Loo= king for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\org.apache.solr.cloud.CollectionsAPIDistribut= edZkTest-jetty1-1368894997005\collection1\lib [junit4:junit4] 2> 1456179 T4190 oasc.SolrCoreDiscoverer.walkFromHere Loo= king for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\org.apache.solr.cloud.CollectionsAPIDistribut= edZkTest-jetty1-1368894997005\collection1\lib\classes [junit4:junit4] 2> 1456185 T4190 oasc.SolrCoreDiscoverer.addCore Discover= ed properties file C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Window= s\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistri= butedZkTest-jetty1-1368894997005\conf\core.properties, adding to cores [junit4:junit4] 2> 1456204 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting socketTimeout to: 0 [junit4:junit4] 2> 1456204 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting urlScheme to: http:// [junit4:junit4] 2> 1456205 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting connTimeout to: 0 [junit4:junit4] 2> 1456205 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 1456207 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting corePoolSize to: 0 [junit4:junit4] 2> 1456208 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 1456208 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 1456208 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting sizeOfQueue to: -1 [junit4:junit4] 2> 1456209 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting fairnessPolicy to: false [junit4:junit4] 2> 1456209 T4190 oascsi.HttpClientUtil.createClient Creat= ing new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D100= 00&socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 1456216 T4190 oasl.LogWatcher.newRegisteredLogWatcher = Registering Log Listener [junit4:junit4] 2> 1456217 T4190 oasc.ZkContainer.initZooKeeper Zookeeper= client=3D127.0.0.1:60418/solr [junit4:junit4] 2> 1456217 T4190 oasc.ZkController.checkChrootPath zkHost= includes chroot [junit4:junit4] 2> 1456219 T4190 oascc.ConnectionManager.waitForConnected= Waiting for client to connect to ZooKeeper [junit4:junit4] 2> 1456236 T4228 oascc.ConnectionManager.process Watcher = org.apache.solr.common.cloud.ConnectionManager@3f827a9 name:ZooKeeperConnec= tion Watcher:127.0.0.1:60418 got event WatchedEvent state:SyncConnected typ= e:None path:null path:null type:None [junit4:junit4] 2> 1456237 T4190 oascc.ConnectionManager.waitForConnected= Client is connected to ZooKeeper [junit4:junit4] 2> 1456241 T4190 oascsi.HttpClientUtil.createClient Creat= ing new http client, config:maxConnections=3D500&maxConnectionsPerHost=3D16= &socketTimeout=3D120000&connTimeout=3D15000 [junit4:junit4] 2> 1456241 T4192 oazs.NIOServerCnxn.doIO WARN caught end = of stream exception EndOfStreamException: Unable to read additional data fr= om client sessionid 0x13eb87ffd8d0005, likely client has closed socket [junit4:junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxn.doIO(N= IOServerCnxn.java:220) [junit4:junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxnFactory= .run(NIOServerCnxnFactory.java:208) [junit4:junit4] 2> =09at java.lang.Thread.run(Thread.java:722) [junit4:junit4] 2>=20 [junit4:junit4] 2> 1456247 T4190 oascc.ConnectionManager.waitForConnected= Waiting for client to connect to ZooKeeper [junit4:junit4] 2> 1456252 T4230 oascc.ConnectionManager.process Watcher = org.apache.solr.common.cloud.ConnectionManager@449c95d5 name:ZooKeeperConne= ction Watcher:127.0.0.1:60418/solr got event WatchedEvent state:SyncConnect= ed type:None path:null path:null type:None [junit4:junit4] 2> 1456253 T4190 oascc.ConnectionManager.waitForConnected= Client is connected to ZooKeeper [junit4:junit4] 2> 1456262 T4190 oascc.ZkStateReader.createClusterStateWa= tchersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 1457368 T4190 oasc.ZkController.createEphemeralLiveNod= e Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60437_ [junit4:junit4] 2> 1457371 T4190 oascc.SolrZkClient.makePath makePath: /l= ive_nodes/127.0.0.1:60437_ [junit4:junit4] 2> 1457376 T4217 oascc.ZkStateReader$3.process Updating l= ive nodes... (2) [junit4:junit4] 2> 1457377 T4230 oascc.ZkStateReader$3.process Updating l= ive nodes... (2) [junit4:junit4] 2> 1457377 T4212 oascc.ZkStateReader$3.process Updating l= ive nodes... (2) [junit4:junit4] 2> 1457386 T4190 oass.SolrDispatchFilter.init user.dir=3D= C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-c= ore\test\J0 [junit4:junit4] 2> 1457386 T4190 oass.SolrDispatchFilter.init SolrDispatc= hFilter.init() done [junit4:junit4] 2> 1457386 T4190 oascsi.HttpClientUtil.createClient Creat= ing new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32= &followRedirects=3Dfalse [junit4:junit4] 2> 1457897 T4190 oejs.Server.doStart jetty-8.1.10.v201303= 12 [junit4:junit4] 2> 1457902 T4190 oejs.AbstractConnector.doStart Started S= electChannelConnector@127.0.0.1:60446 [junit4:junit4] 2> 1457903 T4190 oass.SolrDispatchFilter.init SolrDispatc= hFilter.init() [junit4:junit4] 2> 1457904 T4190 oasc.SolrResourceLoader.locateSolrHome J= NDI not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 1457904 T4190 oasc.SolrResourceLoader.locateSolrHome u= sing system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene= -Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Co= llectionsAPIDistributedZkTest-jetty2-1368894999080 [junit4:junit4] 2> 1457904 T4190 oasc.CoreContainer$Initializer.initializ= e looking for solr config file: C:\Users\JenkinsSlave\workspace\Lucene-Solr= -trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Collect= ionsAPIDistributedZkTest-jetty2-1368894999080\solr.xml [junit4:junit4] 2> 1457904 T4190 oasc.CoreContainer. New CoreContai= ner 1372288854 [junit4:junit4] 2> 1457906 T4190 oasc.CoreContainer.load Loading CoreCont= ainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-W= indows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPID= istributedZkTest-jetty2-1368894999080\' [junit4:junit4] 2> 1457906 T4190 oasc.SolrResourceLoader. new SolrR= esourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-t= runk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Collectio= nsAPIDistributedZkTest-jetty2-1368894999080\' [junit4:junit4] 2> 1458007 T4190 oasc.SolrCoreDiscoverer.walkFromHere Loo= king for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\org.apache.solr.cloud.CollectionsAPIDistribut= edZkTest-jetty2-1368894999080 [junit4:junit4] 2> 1458010 T4190 oasc.SolrCoreDiscoverer.walkFromHere Loo= king for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\org.apache.solr.cloud.CollectionsAPIDistribut= edZkTest-jetty2-1368894999080\collection1 [junit4:junit4] 2> 1458012 T4190 oasc.SolrCoreDiscoverer.walkFromHere Loo= king for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\org.apache.solr.cloud.CollectionsAPIDistribut= edZkTest-jetty2-1368894999080\collection1\conf [junit4:junit4] 2> 1458270 T4190 oasc.SolrCoreDiscoverer.walkFromHere Loo= king for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\org.apache.solr.cloud.CollectionsAPIDistribut= edZkTest-jetty2-1368894999080\collection1\conf\xslt [junit4:junit4] 2> 1458280 T4190 oasc.SolrCoreDiscoverer.walkFromHere Loo= king for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\org.apache.solr.cloud.CollectionsAPIDistribut= edZkTest-jetty2-1368894999080\collection1\lib [junit4:junit4] 2> 1458283 T4190 oasc.SolrCoreDiscoverer.walkFromHere Loo= king for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\org.apache.solr.cloud.CollectionsAPIDistribut= edZkTest-jetty2-1368894999080\collection1\lib\classes [junit4:junit4] 2> 1458290 T4190 oasc.SolrCoreDiscoverer.addCore Discover= ed properties file C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Window= s\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistri= butedZkTest-jetty2-1368894999080\conf\core.properties, adding to cores [junit4:junit4] 2> 1458312 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting socketTimeout to: 0 [junit4:junit4] 2> 1458312 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting urlScheme to: http:// [junit4:junit4] 2> 1458313 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting connTimeout to: 0 [junit4:junit4] 2> 1458313 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 1458313 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting corePoolSize to: 0 [junit4:junit4] 2> 1458314 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 1458314 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 1458314 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting sizeOfQueue to: -1 [junit4:junit4] 2> 1458315 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting fairnessPolicy to: false [junit4:junit4] 2> 1458315 T4190 oascsi.HttpClientUtil.createClient Creat= ing new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D100= 00&socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 1458341 T4190 oasl.LogWatcher.newRegisteredLogWatcher = Registering Log Listener [junit4:junit4] 2> 1458341 T4190 oasc.ZkContainer.initZooKeeper Zookeeper= client=3D127.0.0.1:60418/solr [junit4:junit4] 2> 1458342 T4190 oasc.ZkController.checkChrootPath zkHost= includes chroot [junit4:junit4] 2> 1458344 T4190 oascc.ConnectionManager.waitForConnected= Waiting for client to connect to ZooKeeper [junit4:junit4] 2> 1458366 T4242 oascc.ConnectionManager.process Watcher = org.apache.solr.common.cloud.ConnectionManager@8efde9d name:ZooKeeperConnec= tion Watcher:127.0.0.1:60418 got event WatchedEvent state:SyncConnected typ= e:None path:null path:null type:None [junit4:junit4] 2> 1458368 T4190 oascc.ConnectionManager.waitForConnected= Client is connected to ZooKeeper [junit4:junit4] 2> 1458372 T4190 oascsi.HttpClientUtil.createClient Creat= ing new http client, config:maxConnections=3D500&maxConnectionsPerHost=3D16= &socketTimeout=3D120000&connTimeout=3D15000 [junit4:junit4] 2> 1458372 T4192 oazs.NIOServerCnxn.doIO WARN caught end = of stream exception EndOfStreamException: Unable to read additional data fr= om client sessionid 0x13eb87ffd8d0007, likely client has closed socket [junit4:junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxn.doIO(N= IOServerCnxn.java:220) [junit4:junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxnFactory= .run(NIOServerCnxnFactory.java:208) [junit4:junit4] 2> =09at java.lang.Thread.run(Thread.java:722) [junit4:junit4] 2>=20 [junit4:junit4] 2> 1458379 T4190 oascc.ConnectionManager.waitForConnected= Waiting for client to connect to ZooKeeper [junit4:junit4] 2> 1458384 T4244 oascc.ConnectionManager.process Watcher = org.apache.solr.common.cloud.ConnectionManager@2821180 name:ZooKeeperConnec= tion Watcher:127.0.0.1:60418/solr got event WatchedEvent state:SyncConnecte= d type:None path:null path:null type:None [junit4:junit4] 2> 1458385 T4190 oascc.ConnectionManager.waitForConnected= Client is connected to ZooKeeper [junit4:junit4] 2> 1458395 T4190 oascc.ZkStateReader.createClusterStateWa= tchersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 1459500 T4190 oasc.ZkController.createEphemeralLiveNod= e Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60446_ [junit4:junit4] 2> 1459504 T4190 oascc.SolrZkClient.makePath makePath: /l= ive_nodes/127.0.0.1:60446_ [junit4:junit4] 2> 1459510 T4230 oascc.ZkStateReader$3.process Updating l= ive nodes... (3) [junit4:junit4] 2> 1459510 T4244 oascc.ZkStateReader$3.process Updating l= ive nodes... (3) [junit4:junit4] 2> 1459511 T4212 oascc.ZkStateReader$3.process Updating l= ive nodes... (3) [junit4:junit4] 2> 1459513 T4217 oascc.ZkStateReader$3.process Updating l= ive nodes... (3) [junit4:junit4] 2> 1459525 T4190 oass.SolrDispatchFilter.init user.dir=3D= C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-c= ore\test\J0 [junit4:junit4] 2> 1459525 T4190 oass.SolrDispatchFilter.init SolrDispatc= hFilter.init() done [junit4:junit4] 2> 1459526 T4190 oascsi.HttpClientUtil.createClient Creat= ing new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32= &followRedirects=3Dfalse [junit4:junit4] 2> 1460024 T4190 oejs.Server.doStart jetty-8.1.10.v201303= 12 [junit4:junit4] 2> 1460030 T4190 oejs.AbstractConnector.doStart Started S= electChannelConnector@127.0.0.1:60455 [junit4:junit4] 2> 1460031 T4190 oass.SolrDispatchFilter.init SolrDispatc= hFilter.init() [junit4:junit4] 2> 1460031 T4190 oasc.SolrResourceLoader.locateSolrHome J= NDI not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 1460032 T4190 oasc.SolrResourceLoader.locateSolrHome u= sing system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene= -Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Co= llectionsAPIDistributedZkTest-jetty3-1368895001224 [junit4:junit4] 2> 1460032 T4190 oasc.CoreContainer$Initializer.initializ= e looking for solr config file: C:\Users\JenkinsSlave\workspace\Lucene-Solr= -trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Collect= ionsAPIDistributedZkTest-jetty3-1368895001224\solr.xml [junit4:junit4] 2> 1460032 T4190 oasc.CoreContainer. New CoreContai= ner 449242523 [junit4:junit4] 2> 1460033 T4190 oasc.CoreContainer.load Loading CoreCont= ainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-W= indows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPID= istributedZkTest-jetty3-1368895001224\' [junit4:junit4] 2> 1460033 T4190 oasc.SolrResourceLoader. new SolrR= esourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-t= runk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Collectio= nsAPIDistributedZkTest-jetty3-1368895001224\' [junit4:junit4] 2> 1460153 T4190 oasc.SolrCoreDiscoverer.walkFromHere Loo= king for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\org.apache.solr.cloud.CollectionsAPIDistribut= edZkTest-jetty3-1368895001224 [junit4:junit4] 2> 1460155 T4190 oasc.SolrCoreDiscoverer.walkFromHere Loo= king for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\org.apache.solr.cloud.CollectionsAPIDistribut= edZkTest-jetty3-1368895001224\collection1 [junit4:junit4] 2> 1460156 T4190 oasc.SolrCoreDiscoverer.walkFromHere Loo= king for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\org.apache.solr.cloud.CollectionsAPIDistribut= edZkTest-jetty3-1368895001224\collection1\conf [junit4:junit4] 2> 1460381 T4190 oasc.SolrCoreDiscoverer.walkFromHere Loo= king for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\org.apache.solr.cloud.CollectionsAPIDistribut= edZkTest-jetty3-1368895001224\collection1\conf\xslt [junit4:junit4] 2> 1460389 T4190 oasc.SolrCoreDiscoverer.walkFromHere Loo= king for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\org.apache.solr.cloud.CollectionsAPIDistribut= edZkTest-jetty3-1368895001224\collection1\lib [junit4:junit4] 2> 1460393 T4190 oasc.SolrCoreDiscoverer.walkFromHere Loo= king for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\org.apache.solr.cloud.CollectionsAPIDistribut= edZkTest-jetty3-1368895001224\collection1\lib\classes [junit4:junit4] 2> 1460399 T4190 oasc.SolrCoreDiscoverer.addCore Discover= ed properties file C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Window= s\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistri= butedZkTest-jetty3-1368895001224\conf\core.properties, adding to cores [junit4:junit4] 2> 1460418 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting socketTimeout to: 0 [junit4:junit4] 2> 1460420 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting urlScheme to: http:// [junit4:junit4] 2> 1460421 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting connTimeout to: 0 [junit4:junit4] 2> 1460421 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 1460422 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting corePoolSize to: 0 [junit4:junit4] 2> 1460422 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 1460423 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 1460424 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting sizeOfQueue to: -1 [junit4:junit4] 2> 1460424 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting fairnessPolicy to: false [junit4:junit4] 2> 1460425 T4190 oascsi.HttpClientUtil.createClient Creat= ing new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D100= 00&socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 1460438 T4190 oasl.LogWatcher.newRegisteredLogWatcher = Registering Log Listener [junit4:junit4] 2> 1460439 T4190 oasc.ZkContainer.initZooKeeper Zookeeper= client=3D127.0.0.1:60418/solr [junit4:junit4] 2> 1460439 T4190 oasc.ZkController.checkChrootPath zkHost= includes chroot [junit4:junit4] 2> 1460441 T4190 oascc.ConnectionManager.waitForConnected= Waiting for client to connect to ZooKeeper [junit4:junit4] 2> 1460464 T4256 oascc.ConnectionManager.process Watcher = org.apache.solr.common.cloud.ConnectionManager@62b4964c name:ZooKeeperConne= ction Watcher:127.0.0.1:60418 got event WatchedEvent state:SyncConnected ty= pe:None path:null path:null type:None [junit4:junit4] 2> 1460465 T4190 oascc.ConnectionManager.waitForConnected= Client is connected to ZooKeeper [junit4:junit4] 2> 1460470 T4190 oascsi.HttpClientUtil.createClient Creat= ing new http client, config:maxConnections=3D500&maxConnectionsPerHost=3D16= &socketTimeout=3D120000&connTimeout=3D15000 [junit4:junit4] 2> 1460471 T4192 oazs.NIOServerCnxn.doIO WARN caught end = of stream exception EndOfStreamException: Unable to read additional data fr= om client sessionid 0x13eb87ffd8d0009, likely client has closed socket [junit4:junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxn.doIO(N= IOServerCnxn.java:220) [junit4:junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxnFactory= .run(NIOServerCnxnFactory.java:208) [junit4:junit4] 2> =09at java.lang.Thread.run(Thread.java:722) [junit4:junit4] 2>=20 [junit4:junit4] 2> 1460482 T4190 oascc.ConnectionManager.waitForConnected= Waiting for client to connect to ZooKeeper [junit4:junit4] 2> 1460487 T4258 oascc.ConnectionManager.process Watcher = org.apache.solr.common.cloud.ConnectionManager@3144a233 name:ZooKeeperConne= ction Watcher:127.0.0.1:60418/solr got event WatchedEvent state:SyncConnect= ed type:None path:null path:null type:None [junit4:junit4] 2> 1460487 T4190 oascc.ConnectionManager.waitForConnected= Client is connected to ZooKeeper [junit4:junit4] 2> 1460500 T4190 oascc.ZkStateReader.createClusterStateWa= tchersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 1461608 T4190 oasc.ZkController.createEphemeralLiveNod= e Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60455_ [junit4:junit4] 2> 1461619 T4190 oascc.SolrZkClient.makePath makePath: /l= ive_nodes/127.0.0.1:60455_ [junit4:junit4] 2> 1461660 T4258 oascc.ZkStateReader$3.process Updating l= ive nodes... (4) [junit4:junit4] 2> 1461660 T4230 oascc.ZkStateReader$3.process Updating l= ive nodes... (4) [junit4:junit4] 2> 1461660 T4244 oascc.ZkStateReader$3.process Updating l= ive nodes... (4) [junit4:junit4] 2> 1461661 T4212 oascc.ZkStateReader$3.process Updating l= ive nodes... (4) [junit4:junit4] 2> 1461661 T4217 oascc.ZkStateReader$3.process Updating l= ive nodes... (4) [junit4:junit4] 2> 1461676 T4190 oass.SolrDispatchFilter.init user.dir=3D= C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-c= ore\test\J0 [junit4:junit4] 2> 1461677 T4190 oass.SolrDispatchFilter.init SolrDispatc= hFilter.init() done [junit4:junit4] 2> 1461678 T4190 oascsi.HttpClientUtil.createClient Creat= ing new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32= &followRedirects=3Dfalse [junit4:junit4] 2> 1462412 T4190 oejs.Server.doStart jetty-8.1.10.v201303= 12 [junit4:junit4] 2> 1462418 T4190 oejs.AbstractConnector.doStart Started S= electChannelConnector@127.0.0.1:60464 [junit4:junit4] 2> 1462420 T4190 oass.SolrDispatchFilter.init SolrDispatc= hFilter.init() [junit4:junit4] 2> 1462422 T4190 oasc.SolrResourceLoader.locateSolrHome J= NDI not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 1462423 T4190 oasc.SolrResourceLoader.locateSolrHome u= sing system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene= -Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Co= llectionsAPIDistributedZkTest-jetty4-1368895003373 [junit4:junit4] 2> 1462423 T4190 oasc.CoreContainer$Initializer.initializ= e looking for solr config file: C:\Users\JenkinsSlave\workspace\Lucene-Solr= -trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Collect= ionsAPIDistributedZkTest-jetty4-1368895003373\solr.xml [junit4:junit4] 2> 1462424 T4190 oasc.CoreContainer. New CoreContai= ner 568834315 [junit4:junit4] 2> 1462425 T4190 oasc.CoreContainer.load Loading CoreCont= ainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-W= indows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPID= istributedZkTest-jetty4-1368895003373\' [junit4:junit4] 2> 1462425 T4190 oasc.SolrResourceLoader. new SolrR= esourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-t= runk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Collectio= nsAPIDistributedZkTest-jetty4-1368895003373\' [junit4:junit4] 2> 1462577 T4190 oasc.SolrCoreDiscoverer.walkFromHere Loo= king for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\org.apache.solr.cloud.CollectionsAPIDistribut= edZkTest-jetty4-1368895003373 [junit4:junit4] 2> 1462580 T4190 oasc.SolrCoreDiscoverer.walkFromHere Loo= king for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\org.apache.solr.cloud.CollectionsAPIDistribut= edZkTest-jetty4-1368895003373\collection1 [junit4:junit4] 2> 1462582 T4190 oasc.SolrCoreDiscoverer.walkFromHere Loo= king for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\org.apache.solr.cloud.CollectionsAPIDistribut= edZkTest-jetty4-1368895003373\collection1\conf [junit4:junit4] 2> 1462815 T4190 oasc.SolrCoreDiscoverer.walkFromHere Loo= king for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\org.apache.solr.cloud.CollectionsAPIDistribut= edZkTest-jetty4-1368895003373\collection1\conf\xslt [junit4:junit4] 2> 1462817 T4190 oasc.SolrCoreDiscoverer.walkFromHere Loo= king for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\org.apache.solr.cloud.CollectionsAPIDistribut= edZkTest-jetty4-1368895003373\collection1\lib [junit4:junit4] 2> 1462817 T4190 oasc.SolrCoreDiscoverer.walkFromHere Loo= king for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\org.apache.solr.cloud.CollectionsAPIDistribut= edZkTest-jetty4-1368895003373\collection1\lib\classes [junit4:junit4] 2> 1462825 T4190 oasc.SolrCoreDiscoverer.addCore Discover= ed properties file C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Window= s\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistri= butedZkTest-jetty4-1368895003373\conf\core.properties, adding to cores [junit4:junit4] 2> 1462854 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting socketTimeout to: 0 [junit4:junit4] 2> 1462854 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting urlScheme to: http:// [junit4:junit4] 2> 1462855 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting connTimeout to: 0 [junit4:junit4] 2> 1462855 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 1462856 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting corePoolSize to: 0 [junit4:junit4] 2> 1462857 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 1462857 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 1462859 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting sizeOfQueue to: -1 [junit4:junit4] 2> 1462859 T4190 oashc.HttpShardHandlerFactory.getParamet= er Setting fairnessPolicy to: false [junit4:junit4] 2> 1462860 T4190 oascsi.HttpClientUtil.createClient Creat= ing new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D100= 00&socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 1462874 T4190 oasl.LogWatcher.newRegisteredLogWatcher = Registering Log Listener [junit4:junit4] 2> 1462875 T4190 oasc.ZkContainer.initZooKeeper Zookeeper= client=3D127.0.0.1:60418/solr [junit4:junit4] 2> 1462875 T4190 oasc.ZkController.checkChrootPath zkHost= includes chroot [junit4:junit4] 2> 1462893 T4190 oascc.ConnectionManager.waitForConnected= Waiting for client to connect to ZooKeeper [junit4:junit4] 2> 1462939 T4270 oascc.ConnectionManager.process Watcher = org.apache.solr.common.cloud.ConnectionManager@10938485 name:ZooKeeperConne= ction Watcher:127.0.0.1:60418 got event WatchedEvent state:SyncConnected ty= pe:None path:null path:null type:None [junit4:junit4] 2> 1462939 T4190 oascc.ConnectionManager.waitForConnected= Client is connected to ZooKeeper [junit4:junit4] 2> 1462960 T4190 oascsi.HttpClientUtil.createClient Creat= ing new http client, config:maxConnections=3D500&maxConnectionsPerHost=3D16= &socketTimeout=3D120000&connTimeout=3D15000 [junit4:junit4] 2> 1462971 T4190 oascc.ConnectionManager.waitForConnected= Waiting for client to connect to ZooKeeper [junit4:junit4] 2> 1462979 T4272 oascc.ConnectionManager.process Watcher = org.apache.solr.common.cloud.ConnectionManager@4d0f0cd0 name:ZooKeeperConne= ction Watcher:127.0.0.1:60418/solr got event WatchedEvent state:SyncConnect= ed type:None path:null path:null type:None [junit4:junit4] 2> 1462980 T4190 oascc.ConnectionManager.waitForConnected= Client is connected to ZooKeeper [junit4:junit4] 2> 1462993 T4190 oascc.ZkStateReader.createClusterStateWa= tchersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 1464101 T4190 oasc.ZkController.createEphemeralLiveNod= e Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60464_ [junit4:junit4] 2> 1464108 T4190 oascc.SolrZkClient.makePath makePath: /l= ive_nodes/127.0.0.1:60464_ [junit4:junit4] 2> 1464118 T4272 oascc.ZkStateReader$3.process Updating l= ive nodes... (5) [junit4:junit4] 2> 1464118 T4258 oascc.ZkStateReader$3.process Updating l= ive nodes... (5) [junit4:junit4] 2> 1464119 T4230 oascc.ZkStateReader$3.process Updating l= ive nodes... (5) [junit4:junit4] 2> 1464119 T4212 oascc.ZkStateReader$3.process Updating l= ive nodes... (5) [junit4:junit4] 2> 1464119 T4217 oascc.ZkStateReader$3.process Updating l= ive nodes... (5) [junit4:junit4] 2> 1464119 T4244 oascc.ZkStateReader$3.process Updating l= ive nodes... (5) [junit4:junit4] 2> 1464132 T4190 oass.SolrDispatchFilter.init user.dir=3D= C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-c= ore\test\J0 [junit4:junit4] 2> 1464132 T4190 oass.SolrDispatchFilter.init SolrDispatc= hFilter.init() done [junit4:junit4] 2> 1464133 T4190 oascsi.HttpClientUtil.createClient Creat= ing new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32= &followRedirects=3Dfalse [junit4:junit4] 2> 1464138 T4190 oascsi.HttpClientUtil.createClient Creat= ing new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32= &followRedirects=3Dfalse [junit4:junit4] 2> 1464147 T4222 oasha.CollectionsHandler.handleCreateAct= ion Creating Collection : numShards=3D2&name=3Dnodes_used_collection&replic= ationFactor=3D2&action=3DCREATE&wt=3Djavabin&version=3D2 [junit4:junit4] 2> 1464150 T4212 oasc.DistributedQueue$LatchChildWatcher.= process Watcher fired on path: /overseer/collection-queue-work state: SyncC= onnected type NodeChildrenChanged [junit4:junit4] 2> 1464155 T4214 oasc.OverseerCollectionProcessor.run Ove= rseer Collection Processor: Get the message id:/overseer/collection-queue-w= ork/qn-0000000000 message:{ [junit4:junit4] 2> =09 "operation":"createcollection", [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "maxShardsPerNode":null, [junit4:junit4] 2> =09 "createNodeSet":null, [junit4:junit4] 2> =09 "name":"nodes_used_collection", [junit4:junit4] 2> =09 "replicationFactor":"2"} [junit4:junit4] 2> 1464155 T4214 oasc.OverseerCollectionProcessor.createC= ollection Creating shard nodes_used_collection_shard1_replica1 as part of s= lice shard1 of collection nodes_used_collection on 127.0.0.1:60464_ [junit4:junit4] 2> 1464157 T4214 oasc.OverseerCollectionProcessor.createC= ollection Creating shard nodes_used_collection_shard1_replica2 as part of s= lice shard1 of collection nodes_used_collection on 127.0.0.1:60437_ [junit4:junit4] 2> 1464159 T4214 oasc.OverseerCollectionProcessor.createC= ollection Creating shard nodes_used_collection_shard2_replica1 as part of s= lice shard2 of collection nodes_used_collection on 127.0.0.1:60455_ [junit4:junit4] 2> 1464160 T4214 oasc.OverseerCollectionProcessor.createC= ollection Creating shard nodes_used_collection_shard2_replica2 as part of s= lice shard2 of collection nodes_used_collection on 127.0.0.1:60425_ [junit4:junit4] 2> 1464163 T4266 oasc.CoreContainer.create Creating SolrC= ore 'nodes_used_collection_shard1_replica1' using instanceDir: C:\Users\Jen= kinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\= .\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-136889500337= 3\nodes_used_collection_shard1_replica1 [junit4:junit4] 2> 1464164 T4266 oasc.ZkController.createCollectionZkNode= Check for collection zkNode:nodes_used_collection [junit4:junit4] 2> 1464164 T4252 oasc.CoreContainer.create Creating SolrC= ore 'nodes_used_collection_shard2_replica1' using instanceDir: C:\Users\Jen= kinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\= .\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-136889500122= 4\nodes_used_collection_shard2_replica1 [junit4:junit4] 2> 1464165 T4252 oasc.ZkController.createCollectionZkNode= Check for collection zkNode:nodes_used_collection [junit4:junit4] 2> 1464165 T4223 oasc.CoreContainer.create Creating SolrC= ore 'nodes_used_collection_shard1_replica2' using instanceDir: C:\Users\Jen= kinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\= .\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-136889499700= 5\nodes_used_collection_shard1_replica2 [junit4:junit4] 2> 1464166 T4223 oasc.ZkController.createCollectionZkNode= Check for collection zkNode:nodes_used_collection [junit4:junit4] 2> 1464166 T4266 oasc.ZkController.createCollectionZkNode= Creating collection in ZooKeeper:nodes_used_collection [junit4:junit4] 2> 1464167 T4266 oasc.ZkController.getConfName Looking fo= r collection configName [junit4:junit4] 2> 1464168 T4252 oasc.ZkController.createCollectionZkNode= Creating collection in ZooKeeper:nodes_used_collection [junit4:junit4] 2> 1464170 T4252 oasc.ZkController.getConfName Looking fo= r collection configName [junit4:junit4] 2> 1464170 T4223 oasc.ZkController.createCollectionZkNode= Creating collection in ZooKeeper:nodes_used_collection [junit4:junit4] 2> 1464171 T4223 oasc.ZkController.getConfName Looking fo= r collection configName [junit4:junit4] 2> 1464171 T4206 oasc.CoreContainer.create Creating SolrC= ore 'nodes_used_collection_shard2_replica2' using instanceDir: C:\Users\Jen= kinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\= .\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-136889= 4996050\nodes_used_collection_shard2_replica2 [junit4:junit4] 2> 1464173 T4206 oasc.ZkController.createCollectionZkNode= Check for collection zkNode:nodes_used_collection [junit4:junit4] 2> 1464175 T4266 oasc.ZkController.getConfName Only one c= onfig set found in zk - using it:conf1 [junit4:junit4] 2> 1464175 T4266 oascc.SolrZkClient.makePath makePath: /c= ollections/nodes_used_collection [junit4:junit4] 2> 1464176 T4223 oasc.ZkController.getConfName Only one c= onfig set found in zk - using it:conf1 [junit4:junit4] 2> 1464176 T4223 oascc.SolrZkClient.makePath makePath: /c= ollections/nodes_used_collection [junit4:junit4] 2> 1464176 T4252 oasc.ZkController.getConfName Only one c= onfig set found in zk - using it:conf1 [junit4:junit4] 2> 1464179 T4252 oascc.SolrZkClient.makePath makePath: /c= ollections/nodes_used_collection [junit4:junit4] 2> 1464176 T4206 oasc.ZkController.createCollectionZkNode= Creating collection in ZooKeeper:nodes_used_collection [junit4:junit4] 2> 1464180 T4206 oasc.ZkController.getConfName Looking fo= r collection configName [junit4:junit4] 2> 1464187 T4223 oasc.ZkController.readConfigName Load co= llection config from:/collections/nodes_used_collection [junit4:junit4] 2> 1464188 T4266 oasc.ZkController.readConfigName Load co= llection config from:/collections/nodes_used_collection [junit4:junit4] 2> 1464190 T4252 oasc.ZkController.readConfigName Load co= llection config from:/collections/nodes_used_collection [junit4:junit4] 2> 1464190 T4206 oascc.SolrZkClient.makePath makePath: /c= ollections/nodes_used_collection [junit4:junit4] 2> 1464194 T4266 oasc.SolrResourceLoader. new SolrR= esourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-t= runk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Collectio= nsAPIDistributedZkTest-jetty4-1368895003373\nodes_used_collection_shard1_re= plica1\' [junit4:junit4] 2> 1464194 T4223 oasc.SolrResourceLoader. new SolrR= esourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-t= runk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Collectio= nsAPIDistributedZkTest-jetty1-1368894997005\nodes_used_collection_shard1_re= plica2\' [junit4:junit4] 2> 1464201 T4252 oasc.SolrResourceLoader. new SolrR= esourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-t= runk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Collectio= nsAPIDistributedZkTest-jetty3-1368895001224\nodes_used_collection_shard2_re= plica1\' [junit4:junit4] 2> 1464206 T4206 oasc.ZkController.readConfigName Load co= llection config from:/collections/nodes_used_collection [junit4:junit4] 2> 1464215 T4206 oasc.SolrResourceLoader. new SolrR= esourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-t= runk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Collectio= nsAPIDistributedZkTest-controljetty-1368894996050\nodes_used_collection_sha= rd2_replica2\' [junit4:junit4] 2> 1464312 T4223 oasc.SolrConfig. Using Lucene Matc= hVersion: LUCENE_50 [junit4:junit4] 2> 1464336 T4252 oasc.SolrConfig. Using Lucene Matc= hVersion: LUCENE_50 [junit4:junit4] 2> 1464416 T4206 oasc.SolrConfig. Using Lucene Matc= hVersion: LUCENE_50 [junit4:junit4] 2> 1464445 T4223 oasc.SolrConfig. Loaded SolrConfig= : solrconfig.xml [junit4:junit4] 2> 1464462 T4223 oass.IndexSchema.readSchema Reading Solr= Schema from schema.xml [junit4:junit4] 2> 1464469 T4223 oass.IndexSchema.readSchema [nodes_used_= collection_shard1_replica2] Schema name=3Dtest [junit4:junit4] 2> 1464470 T4266 oasc.SolrConfig. Using Lucene Matc= hVersion: LUCENE_50 [junit4:junit4] 2> 1464576 T4206 oasc.SolrConfig. Loaded SolrConfig= : solrconfig.xml [junit4:junit4] 2> 1464594 T4206 oass.IndexSchema.readSchema Reading Solr= Schema from schema.xml [junit4:junit4] 2> 1464602 T4206 oass.IndexSchema.readSchema [nodes_used_= collection_shard2_replica2] Schema name=3Dtest [junit4:junit4] 2> 1464640 T4252 oasc.SolrConfig. Loaded SolrConfig= : solrconfig.xml [junit4:junit4] 2> 1464664 T4266 oasc.SolrConfig. Loaded SolrConfig= : solrconfig.xml [junit4:junit4] 2> 1464686 T4252 oass.IndexSchema.readSchema Reading Solr= Schema from schema.xml [junit4:junit4] 2> 1464693 T4252 oass.IndexSchema.readSchema [nodes_used_= collection_shard2_replica1] Schema name=3Dtest [junit4:junit4] 2> 1464696 T4266 oass.IndexSchema.readSchema Reading Solr= Schema from schema.xml [junit4:junit4] 2> 1464701 T4266 oass.IndexSchema.readSchema [nodes_used_= collection_shard1_replica1] Schema name=3Dtest [junit4:junit4] 2> 1465445 T4223 oass.OpenExchangeRatesOrgProvider.init I= nitialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 1465506 T4223 oass.IndexSchema.readSchema default sear= ch field in schema is text [junit4:junit4] 2> 1465510 T4223 oass.IndexSchema.readSchema unique key f= ield: id [junit4:junit4] 2> 1465832 T4223 oass.FileExchangeRateProvider.reload Rel= oading exchange rates from file currency.xml [junit4:junit4] 2> 1465956 T4223 oass.FileExchangeRateProvider.reload Rel= oading exchange rates from file currency.xml [junit4:junit4] 2> 1466083 T4223 oass.OpenExchangeRatesOrgProvider.reload= Reloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 1466118 T4223 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Unknown key IMPORTANT NOTE [junit4:junit4] 2> 1466121 T4223 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Expected key, got STRING [junit4:junit4] 2> 1466121 T4223 oass.OpenExchangeRatesOrgProvider.reload= Reloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 1466242 T4223 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Unknown key IMPORTANT NOTE [junit4:junit4] 2> 1466242 T4223 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Expected key, got STRING [junit4:junit4] 2> 1466243 T4223 oasc.SolrCore.initDirectoryFactory org.a= pache.solr.core.MockDirectoryFactory [junit4:junit4] 2> 1466244 T4223 oasc.SolrCore. [nodes_used_collect= ion_shard1_replica2] Opening new SolrCore at C:\Users\JenkinsSlave\workspac= e\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.= cloud.CollectionsAPIDistributedZkTest-jetty1-1368894997005\nodes_used_colle= ction_shard1_replica2\, dataDir=3DC:\Users\JenkinsSlave\workspace\Lucene-So= lr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Colle= ctionsAPIDistributedZkTest-jetty1-1368894997005\nodes_used_collection_shard= 1_replica2\data\ [junit4:junit4] 2> 1466244 T4223 oasc.JmxMonitoredMap. JMX monitori= ng is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@24769ece [junit4:junit4] 2> 1466245 T4223 oasc.SolrCore.initDirectoryFactory org.a= pache.solr.core.MockDirectoryFactory [junit4:junit4] 2> 1466246 T4223 oasc.CachingDirectoryFactory.get return = new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistrib= utedZkTest-jetty1-1368894997005\nodes_used_collection_shard1_replica2\data\ [junit4:junit4] 2> 1466247 T4223 oasc.SolrCore.getNewIndexDir New index d= irectory detected: old=3Dnull new=3DC:\Users\JenkinsSlave\workspace\Lucene-= Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Col= lectionsAPIDistributedZkTest-jetty1-1368894997005\nodes_used_collection_sha= rd1_replica2\data\index/ [junit4:junit4] 2> 1466247 T4223 oasc.SolrCore.initIndex WARN [nodes_used= _collection_shard1_replica2] Solr index directory 'C:\Users\JenkinsSlave\wo= rkspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache= .solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1368894997005\nodes_used= _collection_shard1_replica2\data\index' doesn't exist. Creating new index..= . [junit4:junit4] 2> 1466248 T4223 oasc.CachingDirectoryFactory.get return = new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistrib= utedZkTest-jetty1-1368894997005\nodes_used_collection_shard1_replica2\data\= index [junit4:junit4] 2> 1466258 T4223 oasc.SolrDeletionPolicy.onCommit SolrDel= etionPolicy.onCommit: commits: num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@75b6382d lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@16506dc0),segFN=3Dsegments_1,generation=3D1} [junit4:junit4] 2> 1466259 T4223 oasc.SolrDeletionPolicy.updateCommits ne= west commit generation =3D 1 [junit4:junit4] 2> 1466265 T4223 oasup.UpdateRequestProcessorChain.init i= nserting DistributedUpdateProcessorFactory into updateRequestProcessorChain= "dedupe" [junit4:junit4] 2> 1466266 T4223 oasup.UpdateRequestProcessorChain.init i= nserting DistributedUpdateProcessorFactory into updateRequestProcessorChain= "stored_sig" [junit4:junit4] 2> 1466267 T4223 oasup.UpdateRequestProcessorChain.init i= nserting DistributedUpdateProcessorFactory into updateRequestProcessorChain= "distrib-dup-test-chain-implicit" [junit4:junit4] 2> 1466269 T4223 oasc.RequestHandlers.initHandlersFromCon= fig created standard: solr.StandardRequestHandler [junit4:junit4] 2> 1466270 T4223 oasc.RequestHandlers.initHandlersFromCon= fig created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 1466270 T4223 oasc.RequestHandlers.initHandlersFromCon= fig adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 1466271 T4223 oasc.RequestHandlers.initHandlersFromCon= fig created /replication: solr.ReplicationHandler [junit4:junit4] 2> 1466272 T4223 oasc.RequestHandlers.initHandlersFromCon= fig created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 1466273 T4223 oasc.RequestHandlers.initHandlersFromCon= fig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 1466344 T4252 oass.OpenExchangeRatesOrgProvider.init I= nitialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 1466363 T4252 oass.IndexSchema.readSchema default sear= ch field in schema is text [junit4:junit4] 2> 1466370 T4252 oass.IndexSchema.readSchema unique key f= ield: id [junit4:junit4] 2> 1466428 T4223 oashl.XMLLoader.init xsltCacheLifetimeSe= conds=3D60 [junit4:junit4] 2> 1466571 T4223 oass.SolrIndexSearcher. Opening Se= archer@7128a60c main [junit4:junit4] 2> 1466573 T4223 oasu.UpdateHandler.clearLog Clearing tlo= g files, tlogDir=3DC:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Window= s\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistri= butedZkTest-jetty1-1368894997005\nodes_used_collection_shard1_replica2\data= \tlog [junit4:junit4] 2> 1466575 T4223 oasu.CommitTracker. Hard AutoCommi= t: disabled [junit4:junit4] 2> 1466576 T4223 oasu.CommitTracker. Soft AutoCommi= t: disabled [junit4:junit4] 2> 1466582 T4266 oass.OpenExchangeRatesOrgProvider.init I= nitialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 1466592 T4266 oass.IndexSchema.readSchema default sear= ch field in schema is text [junit4:junit4] 2> 1466595 T4266 oass.IndexSchema.readSchema unique key f= ield: id [junit4:junit4] 2> 1466616 T4223 oasc.ZkController.publish publishing cor= e=3Dnodes_used_collection_shard1_replica2 state=3Ddown [junit4:junit4] 2> 1466617 T4278 oasc.SolrCore.registerSearcher [nodes_us= ed_collection_shard1_replica2] Registered new searcher Searcher@7128a60c ma= in{StandardDirectoryReader(segments_1:1)} [junit4:junit4] 2> 1466635 T4252 oass.FileExchangeRateProvider.reload Rel= oading exchange rates from file currency.xml [junit4:junit4] 2> 1466643 T4266 oass.FileExchangeRateProvider.reload Rel= oading exchange rates from file currency.xml [junit4:junit4] 2> 1466656 T4266 oass.FileExchangeRateProvider.reload Rel= oading exchange rates from file currency.xml [junit4:junit4] 2> 1466661 T4252 oass.FileExchangeRateProvider.reload Rel= oading exchange rates from file currency.xml [junit4:junit4] 2> 1466668 T4206 oass.OpenExchangeRatesOrgProvider.init I= nitialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 1466671 T4266 oass.OpenExchangeRatesOrgProvider.reload= Reloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 1466677 T4206 oass.IndexSchema.readSchema default sear= ch field in schema is text [junit4:junit4] 2> 1466678 T4252 oass.OpenExchangeRatesOrgProvider.reload= Reloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 1466682 T4206 oass.IndexSchema.readSchema unique key f= ield: id [junit4:junit4] 2> 1466684 T4266 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Unknown key IMPORTANT NOTE [junit4:junit4] 2> 1466686 T4266 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Expected key, got STRING [junit4:junit4] 2> 1466687 T4266 oass.OpenExchangeRatesOrgProvider.reload= Reloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 1466685 T4252 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Unknown key IMPORTANT NOTE [junit4:junit4] 2> 1466689 T4252 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Expected key, got STRING [junit4:junit4] 2> 1466690 T4252 oass.OpenExchangeRatesOrgProvider.reload= Reloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 1466691 T4266 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Unknown key IMPORTANT NOTE [junit4:junit4] 2> 1466692 T4266 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Expected key, got STRING [junit4:junit4] 2> 1466696 T4266 oasc.SolrCore.initDirectoryFactory org.a= pache.solr.core.MockDirectoryFactory [junit4:junit4] 2> 1466697 T4266 oasc.SolrCore. [nodes_used_collect= ion_shard1_replica1] Opening new SolrCore at C:\Users\JenkinsSlave\workspac= e\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.= cloud.CollectionsAPIDistributedZkTest-jetty4-1368895003373\nodes_used_colle= ction_shard1_replica1\, dataDir=3DC:\Users\JenkinsSlave\workspace\Lucene-So= lr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Colle= ctionsAPIDistributedZkTest-jetty4-1368895003373\nodes_used_collection_shard= 1_replica1\data\ [junit4:junit4] 2> 1466697 T4266 oasc.JmxMonitoredMap. JMX monitori= ng is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@24769ece [junit4:junit4] 2> 1466696 T4252 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Unknown key IMPORTANT NOTE [junit4:junit4] 2> 1466698 T4252 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Expected key, got STRING [junit4:junit4] 2> 1466698 T4252 oasc.SolrCore.initDirectoryFactory org.a= pache.solr.core.MockDirectoryFactory [junit4:junit4] 2> 1466698 T4266 oasc.SolrCore.initDirectoryFactory org.a= pache.solr.core.MockDirectoryFactory [junit4:junit4] 2> 1466699 T4252 oasc.SolrCore. [nodes_used_collect= ion_shard2_replica1] Opening new SolrCore at C:\Users\JenkinsSlave\workspac= e\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.= cloud.CollectionsAPIDistributedZkTest-jetty3-1368895001224\nodes_used_colle= ction_shard2_replica1\, dataDir=3DC:\Users\JenkinsSlave\workspace\Lucene-So= lr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Colle= ctionsAPIDistributedZkTest-jetty3-1368895001224\nodes_used_collection_shard= 2_replica1\data\ [junit4:junit4] 2> 1466700 T4252 oasc.JmxMonitoredMap. JMX monitori= ng is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@24769ece [junit4:junit4] 2> 1466700 T4266 oasc.CachingDirectoryFactory.get return = new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistrib= utedZkTest-jetty4-1368895003373\nodes_used_collection_shard1_replica1\data\ [junit4:junit4] 2> 1466701 T4266 oasc.SolrCore.getNewIndexDir New index d= irectory detected: old=3Dnull new=3DC:\Users\JenkinsSlave\workspace\Lucene-= Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Col= lectionsAPIDistributedZkTest-jetty4-1368895003373\nodes_used_collection_sha= rd1_replica1\data\index/ [junit4:junit4] 2> 1466701 T4266 oasc.SolrCore.initIndex WARN [nodes_used= _collection_shard1_replica1] Solr index directory 'C:\Users\JenkinsSlave\wo= rkspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache= .solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1368895003373\nodes_used= _collection_shard1_replica1\data\index' doesn't exist. Creating new index..= . [junit4:junit4] 2> 1466701 T4252 oasc.SolrCore.initDirectoryFactory org.a= pache.solr.core.MockDirectoryFactory [junit4:junit4] 2> 1466702 T4266 oasc.CachingDirectoryFactory.get return = new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistrib= utedZkTest-jetty4-1368895003373\nodes_used_collection_shard1_replica1\data\= index [junit4:junit4] 2> 1466706 T4252 oasc.CachingDirectoryFactory.get return = new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistrib= utedZkTest-jetty3-1368895001224\nodes_used_collection_shard2_replica1\data\ [junit4:junit4] 2> 1466708 T4252 oasc.SolrCore.getNewIndexDir New index d= irectory detected: old=3Dnull new=3DC:\Users\JenkinsSlave\workspace\Lucene-= Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Col= lectionsAPIDistributedZkTest-jetty3-1368895001224\nodes_used_collection_sha= rd2_replica1\data\index/ [junit4:junit4] 2> 1466708 T4252 oasc.SolrCore.initIndex WARN [nodes_used= _collection_shard2_replica1] Solr index directory 'C:\Users\JenkinsSlave\wo= rkspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache= .solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1368895001224\nodes_used= _collection_shard2_replica1\data\index' doesn't exist. Creating new index..= . [junit4:junit4] 2> 1466710 T4252 oasc.CachingDirectoryFactory.get return = new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistrib= utedZkTest-jetty3-1368895001224\nodes_used_collection_shard2_replica1\data\= index [junit4:junit4] 2> 1466715 T4206 oass.FileExchangeRateProvider.reload Rel= oading exchange rates from file currency.xml [junit4:junit4] 2> 1466716 T4266 oasc.SolrDeletionPolicy.onCommit SolrDel= etionPolicy.onCommit: commits: num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4a8b1458 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@64caa55a),segFN=3Dsegments_1,generation=3D1} [junit4:junit4] 2> 1466717 T4266 oasc.SolrDeletionPolicy.updateCommits ne= west commit generation =3D 1 [junit4:junit4] 2> 1466722 T4266 oasup.UpdateRequestProcessorChain.init i= nserting DistributedUpdateProcessorFactory into updateRequestProcessorChain= "dedupe" [junit4:junit4] 2> 1466722 T4266 oasup.UpdateRequestProcessorChain.init i= nserting DistributedUpdateProcessorFactory into updateRequestProcessorChain= "stored_sig" [junit4:junit4] 2> 1466723 T4266 oasup.UpdateRequestProcessorChain.init i= nserting DistributedUpdateProcessorFactory into updateRequestProcessorChain= "distrib-dup-test-chain-implicit" [junit4:junit4] 2> 1466723 T4252 oasc.SolrDeletionPolicy.onCommit SolrDel= etionPolicy.onCommit: commits: num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4e335015 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@1d66b0d3),segFN=3Dsegments_1,generation=3D1} [junit4:junit4] 2> 1466724 T4252 oasc.SolrDeletionPolicy.updateCommits ne= west commit generation =3D 1 [junit4:junit4] 2> 1466724 T4266 oasc.RequestHandlers.initHandlersFromCon= fig created standard: solr.StandardRequestHandler [junit4:junit4] 2> 1466727 T4266 oasc.RequestHandlers.initHandlersFromCon= fig created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 1466727 T4266 oasc.RequestHandlers.initHandlersFromCon= fig adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 1466728 T4266 oasc.RequestHandlers.initHandlersFromCon= fig created /replication: solr.ReplicationHandler [junit4:junit4] 2> 1466729 T4266 oasc.RequestHandlers.initHandlersFromCon= fig created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 1466730 T4266 oasc.RequestHandlers.initHandlersFromCon= fig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 1466732 T4252 oasup.UpdateRequestProcessorChain.init i= nserting DistributedUpdateProcessorFactory into updateRequestProcessorChain= "dedupe" [junit4:junit4] 2> 1466733 T4252 oasup.UpdateRequestProcessorChain.init i= nserting DistributedUpdateProcessorFactory into updateRequestProcessorChain= "stored_sig" [junit4:junit4] 2> 1466735 T4252 oasup.UpdateRequestProcessorChain.init i= nserting DistributedUpdateProcessorFactory into updateRequestProcessorChain= "distrib-dup-test-chain-implicit" [junit4:junit4] 2> 1466737 T4252 oasc.RequestHandlers.initHandlersFromCon= fig created standard: solr.StandardRequestHandler [junit4:junit4] 2> 1466738 T4252 oasc.RequestHandlers.initHandlersFromCon= fig created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 1466738 T4252 oasc.RequestHandlers.initHandlersFromCon= fig adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 1466739 T4252 oasc.RequestHandlers.initHandlersFromCon= fig created /replication: solr.ReplicationHandler [junit4:junit4] 2> 1466740 T4252 oasc.RequestHandlers.initHandlersFromCon= fig created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 1466741 T4252 oasc.RequestHandlers.initHandlersFromCon= fig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 1466750 T4252 oashl.XMLLoader.init xsltCacheLifetimeSe= conds=3D60 [junit4:junit4] 2> 1466751 T4266 oashl.XMLLoader.init xsltCacheLifetimeSe= conds=3D60 [junit4:junit4] 2> 1466759 T4252 oass.SolrIndexSearcher. Opening Se= archer@2f196c29 main [junit4:junit4] 2> 1466759 T4252 oasu.UpdateHandler.clearLog Clearing tlo= g files, tlogDir=3DC:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Window= s\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistri= butedZkTest-jetty3-1368895001224\nodes_used_collection_shard2_replica1\data= \tlog [junit4:junit4] 2> 1466761 T4252 oasu.CommitTracker. Hard AutoCommi= t: disabled [junit4:junit4] 2> 1466762 T4252 oasu.CommitTracker. Soft AutoCommi= t: disabled [junit4:junit4] 2> 1466764 T4266 oass.SolrIndexSearcher. Opening Se= archer@778a5549 main [junit4:junit4] 2> 1466767 T4279 oasc.SolrCore.registerSearcher [nodes_us= ed_collection_shard2_replica1] Registered new searcher Searcher@2f196c29 ma= in{StandardDirectoryReader(segments_1:1)} [junit4:junit4] 2> 1466767 T4266 oasu.UpdateHandler.clearLog Clearing tlo= g files, tlogDir=3DC:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Window= s\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistri= butedZkTest-jetty4-1368895003373\nodes_used_collection_shard1_replica1\data= \tlog [junit4:junit4] 2> 1466769 T4266 oasu.CommitTracker. Hard AutoCommi= t: disabled [junit4:junit4] 2> 1466770 T4266 oasu.CommitTracker. Soft AutoCommi= t: disabled [junit4:junit4] 2> 1466770 T4206 oass.FileExchangeRateProvider.reload Rel= oading exchange rates from file currency.xml [junit4:junit4] 2> 1466775 T4252 oasc.ZkController.publish publishing cor= e=3Dnodes_used_collection_shard2_replica1 state=3Ddown [junit4:junit4] 2> 1466777 T4280 oasc.SolrCore.registerSearcher [nodes_us= ed_collection_shard1_replica1] Registered new searcher Searcher@778a5549 ma= in{StandardDirectoryReader(segments_1:1)} [junit4:junit4] 2> 1466778 T4266 oasc.ZkController.publish publishing cor= e=3Dnodes_used_collection_shard1_replica1 state=3Ddown [junit4:junit4] 2> 1466779 T4206 oass.OpenExchangeRatesOrgProvider.reload= Reloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 1466783 T4206 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Unknown key IMPORTANT NOTE [junit4:junit4] 2> 1466783 T4206 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Expected key, got STRING [junit4:junit4] 2> 1466784 T4206 oass.OpenExchangeRatesOrgProvider.reload= Reloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 1466785 T4206 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Unknown key IMPORTANT NOTE [junit4:junit4] 2> 1466785 T4206 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Expected key, got STRING [junit4:junit4] 2> 1466786 T4206 oasc.SolrCore.initDirectoryFactory org.a= pache.solr.core.MockDirectoryFactory [junit4:junit4] 2> 1466786 T4206 oasc.SolrCore. [nodes_used_collect= ion_shard2_replica2] Opening new SolrCore at C:\Users\JenkinsSlave\workspac= e\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.= cloud.CollectionsAPIDistributedZkTest-controljetty-1368894996050\nodes_used= _collection_shard2_replica2\, dataDir=3DC:\Users\JenkinsSlave\workspace\Luc= ene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud= .CollectionsAPIDistributedZkTest-controljetty-1368894996050\nodes_used_coll= ection_shard2_replica2\data\ [junit4:junit4] 2> 1466786 T4206 oasc.JmxMonitoredMap. JMX monitori= ng is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@24769ece [junit4:junit4] 2> 1466787 T4206 oasc.SolrCore.initDirectoryFactory org.a= pache.solr.core.MockDirectoryFactory [junit4:junit4] 2> 1466788 T4206 oasc.CachingDirectoryFactory.get return = new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistrib= utedZkTest-controljetty-1368894996050\nodes_used_collection_shard2_replica2= \data\ [junit4:junit4] 2> 1466788 T4206 oasc.SolrCore.getNewIndexDir New index d= irectory detected: old=3Dnull new=3DC:\Users\JenkinsSlave\workspace\Lucene-= Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Col= lectionsAPIDistributedZkTest-controljetty-1368894996050\nodes_used_collecti= on_shard2_replica2\data\index/ [junit4:junit4] 2> 1466788 T4206 oasc.SolrCore.initIndex WARN [nodes_used= _collection_shard2_replica2] Solr index directory 'C:\Users\JenkinsSlave\wo= rkspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache= .solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1368894996050\node= s_used_collection_shard2_replica2\data\index' doesn't exist. Creating new i= ndex... [junit4:junit4] 2> 1466789 T4206 oasc.CachingDirectoryFactory.get return = new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistrib= utedZkTest-controljetty-1368894996050\nodes_used_collection_shard2_replica2= \data\index [junit4:junit4] 2> 1466795 T4206 oasc.SolrDeletionPolicy.onCommit SolrDel= etionPolicy.onCommit: commits: num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@202ba2e lockFactory=3Dorg.apache.lucene.store.NativeFSLock= Factory@61cda73d),segFN=3Dsegments_1,generation=3D1} [junit4:junit4] 2> 1466796 T4206 oasc.SolrDeletionPolicy.updateCommits ne= west commit generation =3D 1 [junit4:junit4] 2> 1466803 T4206 oasup.UpdateRequestProcessorChain.init i= nserting DistributedUpdateProcessorFactory into updateRequestProcessorChain= "dedupe" [junit4:junit4] 2> 1466803 T4206 oasup.UpdateRequestProcessorChain.init i= nserting DistributedUpdateProcessorFactory into updateRequestProcessorChain= "stored_sig" [junit4:junit4] 2> 1466804 T4206 oasup.UpdateRequestProcessorChain.init i= nserting DistributedUpdateProcessorFactory into updateRequestProcessorChain= "distrib-dup-test-chain-implicit" [junit4:junit4] 2> 1466805 T4206 oasc.RequestHandlers.initHandlersFromCon= fig created standard: solr.StandardRequestHandler [junit4:junit4] 2> 1466806 T4206 oasc.RequestHandlers.initHandlersFromCon= fig created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 1466806 T4206 oasc.RequestHandlers.initHandlersFromCon= fig adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 1466806 T4206 oasc.RequestHandlers.initHandlersFromCon= fig created /replication: solr.ReplicationHandler [junit4:junit4] 2> 1466807 T4206 oasc.RequestHandlers.initHandlersFromCon= fig created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 1466809 T4206 oasc.RequestHandlers.initHandlersFromCon= fig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 1466818 T4206 oashl.XMLLoader.init xsltCacheLifetimeSe= conds=3D60 [junit4:junit4] 2> 1466825 T4213 oascc.ZkStateReader.updateClusterState U= pdating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 1466826 T4213 oasc.Overseer$ClusterStateUpdater.update= State Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "core_node_name":null, [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "shard":"shard1", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "state":"down", [junit4:junit4] 2> =09 "shard_state":"active", [junit4:junit4] 2> =09 "core":"nodes_used_collection_shard1_replica2", [junit4:junit4] 2> =09 "collection":"nodes_used_collection", [junit4:junit4] 2> =09 "shard_range":null, [junit4:junit4] 2> =09 "node_name":"127.0.0.1:60437_", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:60437"} [junit4:junit4] 2> 1466826 T4213 oasc.Overseer$ClusterStateUpdater.create= Collection Create collection nodes_used_collection with numShards 2 [junit4:junit4] 2> 1466830 T4206 oass.SolrIndexSearcher. Opening Se= archer@25a29ce8 main [junit4:junit4] 2> 1466831 T4206 oasu.UpdateHandler.clearLog Clearing tlo= g files, tlogDir=3DC:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Window= s\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistri= butedZkTest-controljetty-1368894996050\nodes_used_collection_shard2_replica= 2\data\tlog [junit4:junit4] 2> 1466835 T4206 oasu.CommitTracker. Hard AutoCommi= t: disabled [junit4:junit4] 2> 1466835 T4206 oasu.CommitTracker. Soft AutoCommi= t: disabled [junit4:junit4] 2> 1466838 T4213 oasc.Overseer$ClusterStateUpdater.update= State Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "core_node_name":null, [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "shard":"shard2", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "state":"down", [junit4:junit4] 2> =09 "shard_state":"active", [junit4:junit4] 2> =09 "core":"nodes_used_collection_shard2_replica1", [junit4:junit4] 2> =09 "collection":"nodes_used_collection", [junit4:junit4] 2> =09 "shard_range":null, [junit4:junit4] 2> =09 "node_name":"127.0.0.1:60455_", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:60455"} [junit4:junit4] 2> 1466839 T4281 oasc.SolrCore.registerSearcher [nodes_us= ed_collection_shard2_replica2] Registered new searcher Searcher@25a29ce8 ma= in{StandardDirectoryReader(segments_1:1)} [junit4:junit4] 2> 1466840 T4206 oasc.ZkController.publish publishing cor= e=3Dnodes_used_collection_shard2_replica2 state=3Ddown [junit4:junit4] 2> 1466848 T4213 oasc.Overseer$ClusterStateUpdater.update= State Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "core_node_name":null, [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "shard":"shard1", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "state":"down", [junit4:junit4] 2> =09 "shard_state":"active", [junit4:junit4] 2> =09 "core":"nodes_used_collection_shard1_replica1", [junit4:junit4] 2> =09 "collection":"nodes_used_collection", [junit4:junit4] 2> =09 "shard_range":null, [junit4:junit4] 2> =09 "node_name":"127.0.0.1:60464_", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:60464"} [junit4:junit4] 2> 1466858 T4213 oasc.Overseer$ClusterStateUpdater.update= State Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "core_node_name":null, [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "shard":"shard2", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "state":"down", [junit4:junit4] 2> =09 "shard_state":"active", [junit4:junit4] 2> =09 "core":"nodes_used_collection_shard2_replica2", [junit4:junit4] 2> =09 "collection":"nodes_used_collection", [junit4:junit4] 2> =09 "shard_range":null, [junit4:junit4] 2> =09 "node_name":"127.0.0.1:60425_", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:60425"} [junit4:junit4] 2> 1466869 T4272 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1466870 T4217 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1466870 T4212 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1466870 T4244 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1466870 T4230 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1466870 T4258 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1467624 T4223 oasc.CoreContainer.registerCore register= ing core: nodes_used_collection_shard1_replica2 [junit4:junit4] 2> 1467624 T4223 oasc.ZkController.register Register repl= ica - core:nodes_used_collection_shard1_replica2 address:http://127.0.0.1:6= 0437 collection:nodes_used_collection shard:shard1 [junit4:junit4] 2> 1467626 T4223 oascc.SolrZkClient.makePath makePath: /c= ollections/nodes_used_collection/leader_elect/shard1/election [junit4:junit4] 2> 1467643 T4223 oasc.ShardLeaderElectionContext.runLeade= rProcess Running the leader process. [junit4:junit4] 2> 1467653 T4223 oasc.ShardLeaderElectionContext.waitForR= eplicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 = timeoutin=3D179999 [junit4:junit4] 2> 1467771 T4252 oasc.CoreContainer.registerCore register= ing core: nodes_used_collection_shard2_replica1 [junit4:junit4] 2> 1467771 T4252 oasc.ZkController.register Register repl= ica - core:nodes_used_collection_shard2_replica1 address:http://127.0.0.1:6= 0455 collection:nodes_used_collection shard:shard2 [junit4:junit4] 2> 1467772 T4266 oasc.CoreContainer.registerCore register= ing core: nodes_used_collection_shard1_replica1 [junit4:junit4] 2> 1467772 T4266 oasc.ZkController.register Register repl= ica - core:nodes_used_collection_shard1_replica1 address:http://127.0.0.1:6= 0464 collection:nodes_used_collection shard:shard1 [junit4:junit4] 2> 1467773 T4252 oascc.SolrZkClient.makePath makePath: /c= ollections/nodes_used_collection/leader_elect/shard2/election [junit4:junit4] 2> 1467790 T4252 oasc.ShardLeaderElectionContext.runLeade= rProcess Running the leader process. [junit4:junit4] 2> 1467796 T4252 oasc.ShardLeaderElectionContext.waitForR= eplicasToComeUp Waiting until we see more replicas up: total=3D2 found=3D1 = timeoutin=3D180000 [junit4:junit4] 2> 1467833 T4206 oasc.CoreContainer.registerCore register= ing core: nodes_used_collection_shard2_replica2 [junit4:junit4] 2> 1467833 T4206 oasc.ZkController.register Register repl= ica - core:nodes_used_collection_shard2_replica2 address:http://127.0.0.1:6= 0425 collection:nodes_used_collection shard:shard2 [junit4:junit4] 2> 1468152 T4223 oasc.ShardLeaderElectionContext.waitForR= eplicasToComeUp Enough replicas found to continue. [junit4:junit4] 2> 1468152 T4223 oasc.ShardLeaderElectionContext.runLeade= rProcess I may be the new leader - try and sync [junit4:junit4] 2> 1468152 T4223 oasc.SyncStrategy.sync Sync replicas to = http://127.0.0.1:60437/nodes_used_collection_shard1_replica2/ [junit4:junit4] 2> 1468153 T4223 oasu.PeerSync.sync PeerSync: core=3Dnode= s_used_collection_shard1_replica2 url=3Dhttp://127.0.0.1:60437 START replic= as=3D[http://127.0.0.1:60464/nodes_used_collection_shard1_replica1/] nUpdat= es=3D100 [junit4:junit4] 2> 1468153 T4223 oasu.PeerSync.sync PeerSync: core=3Dnode= s_used_collection_shard1_replica2 url=3Dhttp://127.0.0.1:60437 DONE. We ha= ve no versions. sync failed. [junit4:junit4] 2> 1468153 T4223 oasc.SyncStrategy.syncReplicas Leader's = attempt to sync with shard failed, moving to the next canidate [junit4:junit4] 2> 1468154 T4223 oasc.ShardLeaderElectionContext.runLeade= rProcess We failed sync, but we have no versions - we can't sync in that ca= se - we were active before, so become leader anyway [junit4:junit4] 2> 1468155 T4223 oasc.ShardLeaderElectionContext.runLeade= rProcess I am the new leader: http://127.0.0.1:60437/nodes_used_collection_= shard1_replica2/ [junit4:junit4] 2> 1468155 T4223 oascc.SolrZkClient.makePath makePath: /c= ollections/nodes_used_collection/leaders/shard1 [junit4:junit4] 2> ASYNC NEW_CORE C271 name=3Dnodes_used_collection_shar= d1_replica1 org.apache.solr.core.SolrCore@f274094 url=3Dhttp://127.0.0.1:60= 464/nodes_used_collection_shard1_replica1 node=3D127.0.0.1:60464_ C271_STAT= E=3Dcoll:nodes_used_collection core:nodes_used_collection_shard1_replica1 p= rops:{shard=3Dshard1, state=3Ddown, core=3Dnodes_used_collection_shard1_rep= lica1, collection=3Dnodes_used_collection, node_name=3D127.0.0.1:60464_, ba= se_url=3Dhttp://127.0.0.1:60464} [junit4:junit4] 2> 1468166 T4265 C271 P60464 oasc.SolrCore.execute [nodes= _used_collection_shard1_replica1] webapp=3D path=3D/get params=3D{getVersio= ns=3D100&distrib=3Dfalse&wt=3Djavabin&qt=3D/get&version=3D2} status=3D0 QTi= me=3D1=20 [junit4:junit4] 2> 1468293 T4252 oasc.ShardLeaderElectionContext.waitForR= eplicasToComeUp Enough replicas found to continue. [junit4:junit4] 2> 1468293 T4252 oasc.ShardLeaderElectionContext.runLeade= rProcess I may be the new leader - try and sync [junit4:junit4] 2> 1468293 T4252 oasc.SyncStrategy.sync Sync replicas to = http://127.0.0.1:60455/nodes_used_collection_shard2_replica1/ [junit4:junit4] 2> 1468293 T4252 oasu.PeerSync.sync PeerSync: core=3Dnode= s_used_collection_shard2_replica1 url=3Dhttp://127.0.0.1:60455 START replic= as=3D[http://127.0.0.1:60425/nodes_used_collection_shard2_replica2/] nUpdat= es=3D100 [junit4:junit4] 2> 1468293 T4252 oasu.PeerSync.sync PeerSync: core=3Dnode= s_used_collection_shard2_replica1 url=3Dhttp://127.0.0.1:60455 DONE. We ha= ve no versions. sync failed. [junit4:junit4] 2> 1468294 T4252 oasc.SyncStrategy.syncReplicas Leader's = attempt to sync with shard failed, moving to the next canidate [junit4:junit4] 2> 1468294 T4252 oasc.ShardLeaderElectionContext.runLeade= rProcess We failed sync, but we have no versions - we can't sync in that ca= se - we were active before, so become leader anyway [junit4:junit4] 2> 1468295 T4252 oasc.ShardLeaderElectionContext.runLeade= rProcess I am the new leader: http://127.0.0.1:60455/nodes_used_collection_= shard2_replica1/ [junit4:junit4] 2> 1468296 T4252 oascc.SolrZkClient.makePath makePath: /c= ollections/nodes_used_collection/leaders/shard2 [junit4:junit4] 2> ASYNC NEW_CORE C272 name=3Dnodes_used_collection_shar= d2_replica2 org.apache.solr.core.SolrCore@19b4078d url=3Dhttp://127.0.0.1:6= 0425/nodes_used_collection_shard2_replica2 node=3D127.0.0.1:60425_ C272_STA= TE=3Dcoll:nodes_used_collection core:nodes_used_collection_shard2_replica2 = props:{shard=3Dshard2, state=3Ddown, core=3Dnodes_used_collection_shard2_re= plica2, collection=3Dnodes_used_collection, node_name=3D127.0.0.1:60425_, b= ase_url=3Dhttp://127.0.0.1:60425} [junit4:junit4] 2> 1468310 T4203 C272 P60425 oasc.SolrCore.execute [nodes= _used_collection_shard2_replica2] webapp=3D path=3D/get params=3D{getVersio= ns=3D100&distrib=3Dfalse&wt=3Djavabin&qt=3D/get&version=3D2} status=3D0 QTi= me=3D1=20 [junit4:junit4] 2> 1468385 T4213 oascc.ZkStateReader.updateClusterState U= pdating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 1468497 T4272 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1468497 T4258 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1468497 T4230 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1468497 T4244 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1468497 T4212 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1468501 T4217 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1468513 T4252 oasc.ZkController.register We are http:/= /127.0.0.1:60455/nodes_used_collection_shard2_replica1/ and leader is http:= //127.0.0.1:60455/nodes_used_collection_shard2_replica1/ [junit4:junit4] 2> 1468513 T4252 oasc.ZkController.register No LogReplay = needed for core=3Dnodes_used_collection_shard2_replica1 baseURL=3Dhttp://12= 7.0.0.1:60455 [junit4:junit4] 2> 1468513 T4252 oasc.ZkController.checkRecovery I am the= leader, no recovery necessary [junit4:junit4] 2> 1468513 T4252 oasc.ZkController.publish publishing cor= e=3Dnodes_used_collection_shard2_replica1 state=3Dactive [junit4:junit4] 2> 1468528 T4252 oascc.ZkStateReader.updateClusterState U= pdating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 1468535 T4252 oass.SolrDispatchFilter.handleAdminReque= st [admin] webapp=3Dnull path=3D/admin/cores params=3D{numShards=3D2&shard= =3Dshard2&name=3Dnodes_used_collection_shard2_replica1&action=3DCREATE&coll= ection=3Dnodes_used_collection&wt=3Djavabin&qt=3D/admin/cores&version=3D2} = status=3D0 QTime=3D4371=20 [junit4:junit4] 2> 1468537 T4223 oasc.ZkController.register We are http:/= /127.0.0.1:60437/nodes_used_collection_shard1_replica2/ and leader is http:= //127.0.0.1:60437/nodes_used_collection_shard1_replica2/ [junit4:junit4] 2> 1468537 T4223 oasc.ZkController.register No LogReplay = needed for core=3Dnodes_used_collection_shard1_replica2 baseURL=3Dhttp://12= 7.0.0.1:60437 [junit4:junit4] 2> 1468538 T4223 oasc.ZkController.checkRecovery I am the= leader, no recovery necessary [junit4:junit4] 2> 1468538 T4223 oasc.ZkController.publish publishing cor= e=3Dnodes_used_collection_shard1_replica2 state=3Dactive [junit4:junit4] 2> 1468552 T4223 oascc.ZkStateReader.updateClusterState U= pdating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 1468554 T4223 oass.SolrDispatchFilter.handleAdminReque= st [admin] webapp=3Dnull path=3D/admin/cores params=3D{numShards=3D2&shard= =3Dshard1&name=3Dnodes_used_collection_shard1_replica2&action=3DCREATE&coll= ection=3Dnodes_used_collection&wt=3Djavabin&qt=3D/admin/cores&version=3D2} = status=3D0 QTime=3D4389=20 [junit4:junit4] 2> 1468773 T4266 oasc.ZkController.register We are http:/= /127.0.0.1:60464/nodes_used_collection_shard1_replica1/ and leader is http:= //127.0.0.1:60437/nodes_used_collection_shard1_replica2/ [junit4:junit4] 2> 1468773 T4266 oasc.ZkController.register No LogReplay = needed for core=3Dnodes_used_collection_shard1_replica1 baseURL=3Dhttp://12= 7.0.0.1:60464 [junit4:junit4] 2> 1468774 T4266 oasc.ZkController.checkRecovery Core nee= ds to recover:nodes_used_collection_shard1_replica1 [junit4:junit4] 2> 1468774 T4266 oasu.DefaultSolrCoreState.doRecovery Run= ning recovery - first canceling any ongoing recovery [junit4:junit4] 2> 1468777 T4284 C271 P60464 oasc.RecoveryStrategy.run St= arting recovery process. core=3Dnodes_used_collection_shard1_replica1 reco= veringAfterStartup=3Dtrue [junit4:junit4] 2> 1468777 T4284 C271 P60464 oasc.RecoveryStrategy.doReco= very ###### startupVersions=3D[] [junit4:junit4] 2> 1468780 T4284 C271 P60464 oasc.ZkController.publish pu= blishing core=3Dnodes_used_collection_shard1_replica1 state=3Drecovering [junit4:junit4] 2> 1468780 T4266 oascc.ZkStateReader.updateClusterState U= pdating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 1468785 T4284 C271 P60464 oascsi.HttpClientUtil.create= Client Creating new http client, config:maxConnections=3D128&maxConnections= PerHost=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 1468786 T4266 oass.SolrDispatchFilter.handleAdminReque= st [admin] webapp=3Dnull path=3D/admin/cores params=3D{numShards=3D2&shard= =3Dshard1&name=3Dnodes_used_collection_shard1_replica1&action=3DCREATE&coll= ection=3Dnodes_used_collection&wt=3Djavabin&qt=3D/admin/cores&version=3D2} = status=3D0 QTime=3D4624=20 [junit4:junit4] 2> 1468802 T4225 oasha.CoreAdminHandler.handleWaitForStat= eAction Going to wait for coreNodeName: 127.0.0.1:60464__nodes_used_collect= ion_shard1_replica1, state: recovering, checkLive: true, onlyIfLeader: true [junit4:junit4] 2> 1468831 T4206 oasc.ZkController.register We are http:/= /127.0.0.1:60425/nodes_used_collection_shard2_replica2/ and leader is http:= //127.0.0.1:60455/nodes_used_collection_shard2_replica1/ [junit4:junit4] 2> 1468832 T4206 oasc.ZkController.register No LogReplay = needed for core=3Dnodes_used_collection_shard2_replica2 baseURL=3Dhttp://12= 7.0.0.1:60425 [junit4:junit4] 2> 1468832 T4206 oasc.ZkController.checkRecovery Core nee= ds to recover:nodes_used_collection_shard2_replica2 [junit4:junit4] 2> 1468832 T4206 oasu.DefaultSolrCoreState.doRecovery Run= ning recovery - first canceling any ongoing recovery [junit4:junit4] 2> 1468835 T4285 C272 P60425 oasc.RecoveryStrategy.run St= arting recovery process. core=3Dnodes_used_collection_shard2_replica2 reco= veringAfterStartup=3Dtrue [junit4:junit4] 2> 1468835 T4285 C272 P60425 oasc.RecoveryStrategy.doReco= very ###### startupVersions=3D[] [junit4:junit4] 2> 1468836 T4206 oascc.ZkStateReader.updateClusterState U= pdating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 1468837 T4285 C272 P60425 oasc.ZkController.publish pu= blishing core=3Dnodes_used_collection_shard2_replica2 state=3Drecovering [junit4:junit4] 2> 1468838 T4206 oass.SolrDispatchFilter.handleAdminReque= st [admin] webapp=3Dnull path=3D/admin/cores params=3D{numShards=3D2&shard= =3Dshard2&name=3Dnodes_used_collection_shard2_replica2&action=3DCREATE&coll= ection=3Dnodes_used_collection&wt=3Djavabin&qt=3D/admin/cores&version=3D2} = status=3D0 QTime=3D4668=20 [junit4:junit4] 2> 1468842 T4285 C272 P60425 oascsi.HttpClientUtil.create= Client Creating new http client, config:maxConnections=3D128&maxConnections= PerHost=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 1468842 T4214 oasc.OverseerCollectionProcessor.createC= ollection Finished create command on all shards for collection: nodes_used_= collection [junit4:junit4] 2> 1468852 T4250 oasha.CoreAdminHandler.handleWaitForStat= eAction Going to wait for coreNodeName: 127.0.0.1:60425__nodes_used_collect= ion_shard2_replica2, state: recovering, checkLive: true, onlyIfLeader: true [junit4:junit4] 2> 1468863 T4230 oasc.DistributedQueue$LatchChildWatcher.= process Watcher fired on path: /overseer/collection-queue-work/qnr-00000000= 00 state: SyncConnected type NodeDataChanged [junit4:junit4] 2> 1468867 T4212 oasc.DistributedQueue$LatchChildWatcher.= process Watcher fired on path: /overseer/collection-queue-work state: SyncC= onnected type NodeChildrenChanged [junit4:junit4] 2> 1468867 T4214 oasc.OverseerCollectionProcessor.run Ove= rseer Collection Processor: Message id:/overseer/collection-queue-work/qn-0= 000000000 complete, response:{success=3D{null=3D{responseHeader=3D{status= =3D0,QTime=3D4371},core=3Dnodes_used_collection_shard2_replica1},null=3D{re= sponseHeader=3D{status=3D0,QTime=3D4389},core=3Dnodes_used_collection_shard= 1_replica2},null=3D{responseHeader=3D{status=3D0,QTime=3D4624},core=3Dnodes= _used_collection_shard1_replica1},null=3D{responseHeader=3D{status=3D0,QTim= e=3D4668},core=3Dnodes_used_collection_shard2_replica2}}} [junit4:junit4] 2> 1468872 T4222 oass.SolrDispatchFilter.handleAdminReque= st [admin] webapp=3Dnull path=3D/admin/collections params=3D{numShards=3D2&= name=3Dnodes_used_collection&replicationFactor=3D2&action=3DCREATE&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D4726=20 [junit4:junit4] 2> 1468873 T4190 oascsi.HttpClientUtil.createClient Creat= ing new http client, config: [junit4:junit4] 2> 1468875 T4190 oascc.ConnectionManager.waitForConnected= Waiting for client to connect to ZooKeeper [junit4:junit4] 2> 1468882 T4287 oascc.ConnectionManager.process Watcher = org.apache.solr.common.cloud.ConnectionManager@390b4b11 name:ZooKeeperConne= ction Watcher:127.0.0.1:60418/solr got event WatchedEvent state:SyncConnect= ed type:None path:null path:null type:None [junit4:junit4] 2> 1468882 T4190 oascc.ConnectionManager.waitForConnected= Client is connected to ZooKeeper [junit4:junit4] 2> 1468884 T4190 oascc.ZkStateReader.createClusterStateWa= tchersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 1468890 T4190 oascsi.HttpClientUtil.createClient Creat= ing new http client, config: [junit4:junit4] 2> 1468894 T4190 oascc.ConnectionManager.waitForConnected= Waiting for client to connect to ZooKeeper [junit4:junit4] 2> 1468899 T4289 oascc.ConnectionManager.process Watcher = org.apache.solr.common.cloud.ConnectionManager@7c0813ee name:ZooKeeperConne= ction Watcher:127.0.0.1:60418/solr got event WatchedEvent state:SyncConnect= ed type:None path:null path:null type:None [junit4:junit4] 2> 1468900 T4190 oascc.ConnectionManager.waitForConnected= Client is connected to ZooKeeper [junit4:junit4] 2> 1468902 T4190 oascc.ZkStateReader.createClusterStateWa= tchersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 1468913 T4207 oasha.CollectionsHandler.handleCreateAct= ion Creating Collection : numShards=3D1&maxShardsPerNode=3D2&name=3Dawholyn= ewcollection_0&replicationFactor=3D5&action=3DCREATE&wt=3Djavabin&version= =3D2 [junit4:junit4] 2> 1468917 T4212 oasc.DistributedQueue$LatchChildWatcher.= process Watcher fired on path: /overseer/collection-queue-work state: SyncC= onnected type NodeChildrenChanged [junit4:junit4] 2> 1468922 T4214 oasc.OverseerCollectionProcessor.run Ove= rseer Collection Processor: Get the message id:/overseer/collection-queue-w= ork/qn-0000000002 message:{ [junit4:junit4] 2> =09 "operation":"createcollection", [junit4:junit4] 2> =09 "numShards":"1", [junit4:junit4] 2> =09 "maxShardsPerNode":"2", [junit4:junit4] 2> =09 "createNodeSet":null, [junit4:junit4] 2> =09 "name":"awholynewcollection_0", [junit4:junit4] 2> =09 "replicationFactor":"5"} [junit4:junit4] 2> 1468923 T4214 oasc.OverseerCollectionProcessor.createC= ollection Creating shard awholynewcollection_0_shard1_replica1 as part of s= lice shard1 of collection awholynewcollection_0 on 127.0.0.1:60446_ [junit4:junit4] 2> 1468923 T4214 oasc.OverseerCollectionProcessor.createC= ollection Creating shard awholynewcollection_0_shard1_replica2 as part of s= lice shard1 of collection awholynewcollection_0 on 127.0.0.1:60437_ [junit4:junit4] 2> 1468923 T4214 oasc.OverseerCollectionProcessor.createC= ollection Creating shard awholynewcollection_0_shard1_replica3 as part of s= lice shard1 of collection awholynewcollection_0 on 127.0.0.1:60425_ [junit4:junit4] 2> 1468924 T4214 oasc.OverseerCollectionProcessor.createC= ollection Creating shard awholynewcollection_0_shard1_replica4 as part of s= lice shard1 of collection awholynewcollection_0 on 127.0.0.1:60455_ [junit4:junit4] 2> 1468924 T4214 oasc.OverseerCollectionProcessor.createC= ollection Creating shard awholynewcollection_0_shard1_replica5 as part of s= lice shard1 of collection awholynewcollection_0 on 127.0.0.1:60464_ [junit4:junit4] 2> 1468928 T4239 oasc.CoreContainer.create Creating SolrC= ore 'awholynewcollection_0_shard1_replica1' using instanceDir: C:\Users\Jen= kinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\= .\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-136889499908= 0\awholynewcollection_0_shard1_replica1 [junit4:junit4] 2> 1468931 T4226 oasc.CoreContainer.create Creating SolrC= ore 'awholynewcollection_0_shard1_replica2' using instanceDir: C:\Users\Jen= kinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\= .\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-136889499700= 5\awholynewcollection_0_shard1_replica2 [junit4:junit4] 2> 1468932 T4239 oasc.ZkController.createCollectionZkNode= Check for collection zkNode:awholynewcollection_0 [junit4:junit4] 2> 1468933 T4253 oasc.CoreContainer.create Creating SolrC= ore 'awholynewcollection_0_shard1_replica4' using instanceDir: C:\Users\Jen= kinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\= .\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-136889500122= 4\awholynewcollection_0_shard1_replica4 [junit4:junit4] 2> 1468936 T4253 oasc.ZkController.createCollectionZkNode= Check for collection zkNode:awholynewcollection_0 [junit4:junit4] 2> 1468936 T4208 oasc.CoreContainer.create Creating SolrC= ore 'awholynewcollection_0_shard1_replica3' using instanceDir: C:\Users\Jen= kinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\= .\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-136889= 4996050\awholynewcollection_0_shard1_replica3 [junit4:junit4] 2> 1468937 T4208 oasc.ZkController.createCollectionZkNode= Check for collection zkNode:awholynewcollection_0 [junit4:junit4] 2> 1468933 T4226 oasc.ZkController.createCollectionZkNode= Check for collection zkNode:awholynewcollection_0 [junit4:junit4] 2> 1468938 T4253 oasc.ZkController.createCollectionZkNode= Creating collection in ZooKeeper:awholynewcollection_0 [junit4:junit4] 2> 1468939 T4226 oasc.ZkController.createCollectionZkNode= Creating collection in ZooKeeper:awholynewcollection_0 [junit4:junit4] 2> 1468939 T4226 oasc.ZkController.getConfName Looking fo= r collection configName [junit4:junit4] 2> 1468937 T4239 oasc.ZkController.createCollectionZkNode= Creating collection in ZooKeeper:awholynewcollection_0 [junit4:junit4] 2> 1468936 T4268 oasc.CoreContainer.create Creating SolrC= ore 'awholynewcollection_0_shard1_replica5' using instanceDir: C:\Users\Jen= kinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\= .\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-136889500337= 3\awholynewcollection_0_shard1_replica5 [junit4:junit4] 2> 1468941 T4268 oasc.ZkController.createCollectionZkNode= Check for collection zkNode:awholynewcollection_0 [junit4:junit4] 2> 1468942 T4226 oasc.ZkController.getConfName Only one c= onfig set found in zk - using it:conf1 [junit4:junit4] 2> 1468942 T4226 oascc.SolrZkClient.makePath makePath: /c= ollections/awholynewcollection_0 [junit4:junit4] 2> 1468940 T4239 oasc.ZkController.getConfName Looking fo= r collection configName [junit4:junit4] 2> 1468939 T4253 oasc.ZkController.getConfName Looking fo= r collection configName [junit4:junit4] 2> 1468939 T4208 oasc.ZkController.createCollectionZkNode= Creating collection in ZooKeeper:awholynewcollection_0 [junit4:junit4] 2> 1468945 T4208 oasc.ZkController.getConfName Looking fo= r collection configName [junit4:junit4] 2> 1468943 T4268 oasc.ZkController.createCollectionZkNode= Creating collection in ZooKeeper:awholynewcollection_0 [junit4:junit4] 2> 1468946 T4268 oasc.ZkController.getConfName Looking fo= r collection configName [junit4:junit4] 2> 1468949 T4253 oasc.ZkController.getConfName Only one c= onfig set found in zk - using it:conf1 [junit4:junit4] 2> 1468951 T4253 oascc.SolrZkClient.makePath makePath: /c= ollections/awholynewcollection_0 [junit4:junit4] 2> 1468952 T4208 oasc.ZkController.getConfName Only one c= onfig set found in zk - using it:conf1 [junit4:junit4] 2> 1468952 T4208 oascc.SolrZkClient.makePath makePath: /c= ollections/awholynewcollection_0 [junit4:junit4] 2> 1468954 T4268 oasc.ZkController.getConfName Only one c= onfig set found in zk - using it:conf1 [junit4:junit4] 2> 1468954 T4268 oascc.SolrZkClient.makePath makePath: /c= ollections/awholynewcollection_0 [junit4:junit4] 2> 1468955 T4226 oasc.ZkController.readConfigName Load co= llection config from:/collections/awholynewcollection_0 [junit4:junit4] 2> 1468953 T4239 oasc.ZkController.getConfName Only one c= onfig set found in zk - using it:conf1 [junit4:junit4] 2> 1468957 T4239 oascc.SolrZkClient.makePath makePath: /c= ollections/awholynewcollection_0 [junit4:junit4] 2> 1468961 T4226 oasc.SolrResourceLoader. new SolrR= esourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-t= runk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Collectio= nsAPIDistributedZkTest-jetty1-1368894997005\awholynewcollection_0_shard1_re= plica2\' [junit4:junit4] 2> 1468963 T4253 oasc.ZkController.readConfigName Load co= llection config from:/collections/awholynewcollection_0 [junit4:junit4] 2> 1468965 T4208 oasc.ZkController.readConfigName Load co= llection config from:/collections/awholynewcollection_0 [junit4:junit4] 2> 1468966 T4268 oasc.ZkController.readConfigName Load co= llection config from:/collections/awholynewcollection_0 [junit4:junit4] 2> 1468970 T4239 oasc.ZkController.readConfigName Load co= llection config from:/collections/awholynewcollection_0 [junit4:junit4] 2> 1468976 T4253 oasc.SolrResourceLoader. new SolrR= esourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-t= runk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Collectio= nsAPIDistributedZkTest-jetty3-1368895001224\awholynewcollection_0_shard1_re= plica4\' [junit4:junit4] 2> 1468978 T4208 oasc.SolrResourceLoader. new SolrR= esourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-t= runk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Collectio= nsAPIDistributedZkTest-controljetty-1368894996050\awholynewcollection_0_sha= rd1_replica3\' [junit4:junit4] 2> 1468988 T4239 oasc.SolrResourceLoader. new SolrR= esourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-t= runk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Collectio= nsAPIDistributedZkTest-jetty2-1368894999080\awholynewcollection_0_shard1_re= plica1\' [junit4:junit4] 2> 1468990 T4268 oasc.SolrResourceLoader. new SolrR= esourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-t= runk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Collectio= nsAPIDistributedZkTest-jetty4-1368895003373\awholynewcollection_0_shard1_re= plica5\' [junit4:junit4] 2> 1469124 T4253 oasc.SolrConfig. Using Lucene Matc= hVersion: LUCENE_50 [junit4:junit4] 2> 1469133 T4268 oasc.SolrConfig. Using Lucene Matc= hVersion: LUCENE_50 [junit4:junit4] 2> 1469166 T4208 oasc.SolrConfig. Using Lucene Matc= hVersion: LUCENE_50 [junit4:junit4] 2> 1469222 T4226 oasc.SolrConfig. Using Lucene Matc= hVersion: LUCENE_50 [junit4:junit4] 2> 1469252 T4253 oasc.SolrConfig. Loaded SolrConfig= : solrconfig.xml [junit4:junit4] 2> 1469313 T4226 oasc.SolrConfig. Loaded SolrConfig= : solrconfig.xml [junit4:junit4] 2> 1469317 T4268 oasc.SolrConfig. Loaded SolrConfig= : solrconfig.xml [junit4:junit4] 2> 1469366 T4253 oass.IndexSchema.readSchema Reading Solr= Schema from schema.xml [junit4:junit4] 2> 1469369 T4208 oasc.SolrConfig. Loaded SolrConfig= : solrconfig.xml [junit4:junit4] 2> 1469372 T4226 oass.IndexSchema.readSchema Reading Solr= Schema from schema.xml [junit4:junit4] 2> 1469376 T4253 oass.IndexSchema.readSchema [awholynewco= llection_0_shard1_replica4] Schema name=3Dtest [junit4:junit4] 2> 1469376 T4239 oasc.SolrConfig. Using Lucene Matc= hVersion: LUCENE_50 [junit4:junit4] 2> 1469413 T4226 oass.IndexSchema.readSchema [awholynewco= llection_0_shard1_replica2] Schema name=3Dtest [junit4:junit4] 2> 1469425 T4268 oass.IndexSchema.readSchema Reading Solr= Schema from schema.xml [junit4:junit4] 2> 1469427 T4208 oass.IndexSchema.readSchema Reading Solr= Schema from schema.xml [junit4:junit4] 2> 1469434 T4268 oass.IndexSchema.readSchema [awholynewco= llection_0_shard1_replica5] Schema name=3Dtest [junit4:junit4] 2> 1469434 T4208 oass.IndexSchema.readSchema [awholynewco= llection_0_shard1_replica3] Schema name=3Dtest [junit4:junit4] 2> 1469574 T4239 oasc.SolrConfig. Loaded SolrConfig= : solrconfig.xml [junit4:junit4] 2> 1469792 T4239 oass.IndexSchema.readSchema Reading Solr= Schema from schema.xml [junit4:junit4] 2> 1469800 T4239 oass.IndexSchema.readSchema [awholynewco= llection_0_shard1_replica1] Schema name=3Dtest [junit4:junit4] 2> 1470302 T4213 oascc.ZkStateReader.updateClusterState U= pdating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 1470306 T4213 oasc.Overseer$ClusterStateUpdater.update= State Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "core_node_name":"127.0.0.1:60455__nodes_used_col= lection_shard2_replica1", [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "shard":"shard2", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "state":"active", [junit4:junit4] 2> =09 "shard_state":"active", [junit4:junit4] 2> =09 "core":"nodes_used_collection_shard2_replica1", [junit4:junit4] 2> =09 "collection":"nodes_used_collection", [junit4:junit4] 2> =09 "shard_range":null, [junit4:junit4] 2> =09 "node_name":"127.0.0.1:60455_", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:60455"} [junit4:junit4] 2> 1470330 T4213 oasc.Overseer$ClusterStateUpdater.update= State Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "core_node_name":"127.0.0.1:60437__nodes_used_col= lection_shard1_replica2", [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "shard":"shard1", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "state":"active", [junit4:junit4] 2> =09 "shard_state":"active", [junit4:junit4] 2> =09 "core":"nodes_used_collection_shard1_replica2", [junit4:junit4] 2> =09 "collection":"nodes_used_collection", [junit4:junit4] 2> =09 "shard_range":null, [junit4:junit4] 2> =09 "node_name":"127.0.0.1:60437_", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:60437"} [junit4:junit4] 2> 1470355 T4213 oasc.Overseer$ClusterStateUpdater.update= State Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "core_node_name":"127.0.0.1:60464__nodes_used_col= lection_shard1_replica1", [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "shard":"shard1", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "state":"recovering", [junit4:junit4] 2> =09 "shard_state":"active", [junit4:junit4] 2> =09 "core":"nodes_used_collection_shard1_replica1", [junit4:junit4] 2> =09 "collection":"nodes_used_collection", [junit4:junit4] 2> =09 "shard_range":null, [junit4:junit4] 2> =09 "node_name":"127.0.0.1:60464_", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:60464"} [junit4:junit4] 2> 1470389 T4213 oasc.Overseer$ClusterStateUpdater.update= State Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "core_node_name":"127.0.0.1:60425__nodes_used_col= lection_shard2_replica2", [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "shard":"shard2", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "state":"recovering", [junit4:junit4] 2> =09 "shard_state":"active", [junit4:junit4] 2> =09 "core":"nodes_used_collection_shard2_replica2", [junit4:junit4] 2> =09 "collection":"nodes_used_collection", [junit4:junit4] 2> =09 "shard_range":null, [junit4:junit4] 2> =09 "node_name":"127.0.0.1:60425_", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:60425"} [junit4:junit4] 2> 1470950 T4253 oass.OpenExchangeRatesOrgProvider.init I= nitialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 1470966 T4253 oass.IndexSchema.readSchema default sear= ch field in schema is text [junit4:junit4] 2> 1470974 T4253 oass.IndexSchema.readSchema unique key f= ield: id [junit4:junit4] 2> 1471055 T4226 oass.OpenExchangeRatesOrgProvider.init I= nitialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 1471066 T4226 oass.IndexSchema.readSchema default sear= ch field in schema is text [junit4:junit4] 2> 1471071 T4226 oass.IndexSchema.readSchema unique key f= ield: id [junit4:junit4] 2> 1471156 T4212 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1471157 T4244 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1471157 T4230 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1471158 T4258 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1471159 T4272 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1471163 T4287 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1471181 T4217 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1471182 T4289 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1471523 T4226 oass.FileExchangeRateProvider.reload Rel= oading exchange rates from file currency.xml [junit4:junit4] 2> 1471547 T4253 oass.FileExchangeRateProvider.reload Rel= oading exchange rates from file currency.xml [junit4:junit4] 2> 1471553 T4226 oass.FileExchangeRateProvider.reload Rel= oading exchange rates from file currency.xml [junit4:junit4] 2> 1471559 T4253 oass.FileExchangeRateProvider.reload Rel= oading exchange rates from file currency.xml [junit4:junit4] 2> 1471564 T4226 oass.OpenExchangeRatesOrgProvider.reload= Reloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 1471580 T4253 oass.OpenExchangeRatesOrgProvider.reload= Reloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 1471626 T4239 oass.OpenExchangeRatesOrgProvider.init I= nitialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 1471639 T4239 oass.IndexSchema.readSchema default sear= ch field in schema is text [junit4:junit4] 2> 1471645 T4239 oass.IndexSchema.readSchema unique key f= ield: id [junit4:junit4] 2> 1471707 T4226 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Unknown key IMPORTANT NOTE [junit4:junit4] 2> 1471708 T4226 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Expected key, got STRING [junit4:junit4] 2> 1471708 T4226 oass.OpenExchangeRatesOrgProvider.reload= Reloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 1471709 T4253 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Unknown key IMPORTANT NOTE [junit4:junit4] 2> 1471710 T4253 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Expected key, got STRING [junit4:junit4] 2> 1471711 T4253 oass.OpenExchangeRatesOrgProvider.reload= Reloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 1471715 T4226 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Unknown key IMPORTANT NOTE [junit4:junit4] 2> 1471716 T4226 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Expected key, got STRING [junit4:junit4] 2> 1471716 T4226 oasc.SolrCore.initDirectoryFactory org.a= pache.solr.core.MockDirectoryFactory [junit4:junit4] 2> 1471717 T4226 oasc.SolrCore. [awholynewcollectio= n_0_shard1_replica2] Opening new SolrCore at C:\Users\JenkinsSlave\workspac= e\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.= cloud.CollectionsAPIDistributedZkTest-jetty1-1368894997005\awholynewcollect= ion_0_shard1_replica2\, dataDir=3DC:\Users\JenkinsSlave\workspace\Lucene-So= lr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Colle= ctionsAPIDistributedZkTest-jetty1-1368894997005\awholynewcollection_0_shard= 1_replica2\data\ [junit4:junit4] 2> 1471717 T4226 oasc.JmxMonitoredMap. JMX monitori= ng is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@24769ece [junit4:junit4] 2> 1471718 T4226 oasc.SolrCore.initDirectoryFactory org.a= pache.solr.core.MockDirectoryFactory [junit4:junit4] 2> 1471720 T4253 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Unknown key IMPORTANT NOTE [junit4:junit4] 2> 1471721 T4253 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Expected key, got STRING [junit4:junit4] 2> 1471722 T4253 oasc.SolrCore.initDirectoryFactory org.a= pache.solr.core.MockDirectoryFactory [junit4:junit4] 2> 1471722 T4253 oasc.SolrCore. [awholynewcollectio= n_0_shard1_replica4] Opening new SolrCore at C:\Users\JenkinsSlave\workspac= e\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.= cloud.CollectionsAPIDistributedZkTest-jetty3-1368895001224\awholynewcollect= ion_0_shard1_replica4\, dataDir=3DC:\Users\JenkinsSlave\workspace\Lucene-So= lr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Colle= ctionsAPIDistributedZkTest-jetty3-1368895001224\awholynewcollection_0_shard= 1_replica4\data\ [junit4:junit4] 2> 1471723 T4253 oasc.JmxMonitoredMap. JMX monitori= ng is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@24769ece [junit4:junit4] 2> 1471723 T4253 oasc.SolrCore.initDirectoryFactory org.a= pache.solr.core.MockDirectoryFactory [junit4:junit4] 2> 1471729 T4208 oass.OpenExchangeRatesOrgProvider.init I= nitialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 1471738 T4253 oasc.CachingDirectoryFactory.get return = new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistrib= utedZkTest-jetty3-1368895001224\awholynewcollection_0_shard1_replica4\data\ [junit4:junit4] 2> 1471741 T4253 oasc.SolrCore.getNewIndexDir New index d= irectory detected: old=3Dnull new=3DC:\Users\JenkinsSlave\workspace\Lucene-= Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Col= lectionsAPIDistributedZkTest-jetty3-1368895001224\awholynewcollection_0_sha= rd1_replica4\data\index/ [junit4:junit4] 2> 1471744 T4253 oasc.SolrCore.initIndex WARN [awholynewc= ollection_0_shard1_replica4] Solr index directory 'C:\Users\JenkinsSlave\wo= rkspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache= .solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1368895001224\awholynewc= ollection_0_shard1_replica4\data\index' doesn't exist. Creating new index..= . [junit4:junit4] 2> 1471740 T4226 oasc.CachingDirectoryFactory.get return = new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistrib= utedZkTest-jetty1-1368894997005\awholynewcollection_0_shard1_replica2\data\ [junit4:junit4] 2> 1471746 T4226 oasc.SolrCore.getNewIndexDir New index d= irectory detected: old=3Dnull new=3DC:\Users\JenkinsSlave\workspace\Lucene-= Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Col= lectionsAPIDistributedZkTest-jetty1-1368894997005\awholynewcollection_0_sha= rd1_replica2\data\index/ [junit4:junit4] 2> 1471745 T4253 oasc.CachingDirectoryFactory.get return = new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistrib= utedZkTest-jetty3-1368895001224\awholynewcollection_0_shard1_replica4\data\= index [junit4:junit4] 2> 1471744 T4239 oass.FileExchangeRateProvider.reload Rel= oading exchange rates from file currency.xml [junit4:junit4] 2> 1471746 T4226 oasc.SolrCore.initIndex WARN [awholynewc= ollection_0_shard1_replica2] Solr index directory 'C:\Users\JenkinsSlave\wo= rkspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache= .solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1368894997005\awholynewc= ollection_0_shard1_replica2\data\index' doesn't exist. Creating new index..= . [junit4:junit4] 2> 1471756 T4239 oass.FileExchangeRateProvider.reload Rel= oading exchange rates from file currency.xml [junit4:junit4] 2> 1471762 T4268 oass.OpenExchangeRatesOrgProvider.init I= nitialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 1471764 T4239 oass.OpenExchangeRatesOrgProvider.reload= Reloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 1471766 T4239 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Unknown key IMPORTANT NOTE [junit4:junit4] 2> 1471767 T4239 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Expected key, got STRING [junit4:junit4] 2> 1471767 T4239 oass.OpenExchangeRatesOrgProvider.reload= Reloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 1471771 T4239 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Unknown key IMPORTANT NOTE [junit4:junit4] 2> 1471772 T4239 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Expected key, got STRING [junit4:junit4] 2> 1471772 T4239 oasc.SolrCore.initDirectoryFactory org.a= pache.solr.core.MockDirectoryFactory [junit4:junit4] 2> 1471773 T4239 oasc.SolrCore. [awholynewcollectio= n_0_shard1_replica1] Opening new SolrCore at C:\Users\JenkinsSlave\workspac= e\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.= cloud.CollectionsAPIDistributedZkTest-jetty2-1368894999080\awholynewcollect= ion_0_shard1_replica1\, dataDir=3DC:\Users\JenkinsSlave\workspace\Lucene-So= lr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Colle= ctionsAPIDistributedZkTest-jetty2-1368894999080\awholynewcollection_0_shard= 1_replica1\data\ [junit4:junit4] 2> 1471773 T4239 oasc.JmxMonitoredMap. JMX monitori= ng is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@24769ece [junit4:junit4] 2> 1471774 T4239 oasc.SolrCore.initDirectoryFactory org.a= pache.solr.core.MockDirectoryFactory [junit4:junit4] 2> 1471774 T4268 oass.IndexSchema.readSchema default sear= ch field in schema is text [junit4:junit4] 2> 1471775 T4208 oass.IndexSchema.readSchema default sear= ch field in schema is text [junit4:junit4] 2> 1471780 T4268 oass.IndexSchema.readSchema unique key f= ield: id [junit4:junit4] 2> 1471781 T4208 oass.IndexSchema.readSchema unique key f= ield: id [junit4:junit4] 2> 1471785 T4226 oasc.CachingDirectoryFactory.get return = new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistrib= utedZkTest-jetty1-1368894997005\awholynewcollection_0_shard1_replica2\data\= index [junit4:junit4] 2> 1471789 T4239 oasc.CachingDirectoryFactory.get return = new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistrib= utedZkTest-jetty2-1368894999080\awholynewcollection_0_shard1_replica1\data\ [junit4:junit4] 2> 1471791 T4239 oasc.SolrCore.getNewIndexDir New index d= irectory detected: old=3Dnull new=3DC:\Users\JenkinsSlave\workspace\Lucene-= Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Col= lectionsAPIDistributedZkTest-jetty2-1368894999080\awholynewcollection_0_sha= rd1_replica1\data\index/ [junit4:junit4] 2> 1471792 T4239 oasc.SolrCore.initIndex WARN [awholynewc= ollection_0_shard1_replica1] Solr index directory 'C:\Users\JenkinsSlave\wo= rkspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache= .solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1368894999080\awholynewc= ollection_0_shard1_replica1\data\index' doesn't exist. Creating new index..= . [junit4:junit4] 2> 1471793 T4239 oasc.CachingDirectoryFactory.get return = new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistrib= utedZkTest-jetty2-1368894999080\awholynewcollection_0_shard1_replica1\data\= index [junit4:junit4] 2> 1471820 T4226 oasc.SolrDeletionPolicy.onCommit SolrDel= etionPolicy.onCommit: commits: num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@52791789 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@70c81b76),segFN=3Dsegments_1,generation=3D1} [junit4:junit4] 2> 1471822 T4268 oass.FileExchangeRateProvider.reload Rel= oading exchange rates from file currency.xml [junit4:junit4] 2> 1471821 T4253 oasc.SolrDeletionPolicy.onCommit SolrDel= etionPolicy.onCommit: commits: num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@7f5883c9 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@5f7ae0bb),segFN=3Dsegments_1,generation=3D1} [junit4:junit4] 2> 1471823 T4253 oasc.SolrDeletionPolicy.updateCommits ne= west commit generation =3D 1 [junit4:junit4] 2> 1471823 T4208 oass.FileExchangeRateProvider.reload Rel= oading exchange rates from file currency.xml [junit4:junit4] 2> 1471821 T4239 oasc.SolrDeletionPolicy.onCommit SolrDel= etionPolicy.onCommit: commits: num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@3c463b7c lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@7aaee686),segFN=3Dsegments_1,generation=3D1} [junit4:junit4] 2> 1471824 T4239 oasc.SolrDeletionPolicy.updateCommits ne= west commit generation =3D 1 [junit4:junit4] 2> 1471828 T4253 oasup.UpdateRequestProcessorChain.init i= nserting DistributedUpdateProcessorFactory into updateRequestProcessorChain= "dedupe" [junit4:junit4] 2> 1471828 T4253 oasup.UpdateRequestProcessorChain.init i= nserting DistributedUpdateProcessorFactory into updateRequestProcessorChain= "stored_sig" [junit4:junit4] 2> 1471829 T4253 oasup.UpdateRequestProcessorChain.init i= nserting DistributedUpdateProcessorFactory into updateRequestProcessorChain= "distrib-dup-test-chain-implicit" [junit4:junit4] 2> 1471831 T4253 oasc.RequestHandlers.initHandlersFromCon= fig created standard: solr.StandardRequestHandler [junit4:junit4] 2> 1471822 T4226 oasc.SolrDeletionPolicy.updateCommits ne= west commit generation =3D 1 [junit4:junit4] 2> 1471833 T4239 oasup.UpdateRequestProcessorChain.init i= nserting DistributedUpdateProcessorFactory into updateRequestProcessorChain= "dedupe" [junit4:junit4] 2> 1471834 T4239 oasup.UpdateRequestProcessorChain.init i= nserting DistributedUpdateProcessorFactory into updateRequestProcessorChain= "stored_sig" [junit4:junit4] 2> 1471835 T4239 oasup.UpdateRequestProcessorChain.init i= nserting DistributedUpdateProcessorFactory into updateRequestProcessorChain= "distrib-dup-test-chain-implicit" [junit4:junit4] 2> 1471836 T4239 oasc.RequestHandlers.initHandlersFromCon= fig created standard: solr.StandardRequestHandler [junit4:junit4] 2> 1471836 T4239 oasc.RequestHandlers.initHandlersFromCon= fig created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 1471837 T4239 oasc.RequestHandlers.initHandlersFromCon= fig adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 1471838 T4239 oasc.RequestHandlers.initHandlersFromCon= fig created /replication: solr.ReplicationHandler [junit4:junit4] 2> 1471839 T4239 oasc.RequestHandlers.initHandlersFromCon= fig created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 1471839 T4226 oasup.UpdateRequestProcessorChain.init i= nserting DistributedUpdateProcessorFactory into updateRequestProcessorChain= "dedupe" [junit4:junit4] 2> 1471840 T4226 oasup.UpdateRequestProcessorChain.init i= nserting DistributedUpdateProcessorFactory into updateRequestProcessorChain= "stored_sig" [junit4:junit4] 2> 1471841 T4226 oasup.UpdateRequestProcessorChain.init i= nserting DistributedUpdateProcessorFactory into updateRequestProcessorChain= "distrib-dup-test-chain-implicit" [junit4:junit4] 2> 1471840 T4239 oasc.RequestHandlers.initHandlersFromCon= fig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 1471842 T4226 oasc.RequestHandlers.initHandlersFromCon= fig created standard: solr.StandardRequestHandler [junit4:junit4] 2> 1471843 T4226 oasc.RequestHandlers.initHandlersFromCon= fig created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 1471844 T4226 oasc.RequestHandlers.initHandlersFromCon= fig adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 1471844 T4226 oasc.RequestHandlers.initHandlersFromCon= fig created /replication: solr.ReplicationHandler [junit4:junit4] 2> 1471845 T4226 oasc.RequestHandlers.initHandlersFromCon= fig created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 1471846 T4226 oasc.RequestHandlers.initHandlersFromCon= fig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 1471840 T4253 oasc.RequestHandlers.initHandlersFromCon= fig created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 1471847 T4253 oasc.RequestHandlers.initHandlersFromCon= fig adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 1471848 T4253 oasc.RequestHandlers.initHandlersFromCon= fig created /replication: solr.ReplicationHandler [junit4:junit4] 2> 1471849 T4253 oasc.RequestHandlers.initHandlersFromCon= fig created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 1471849 T4253 oasc.RequestHandlers.initHandlersFromCon= fig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 1471867 T4239 oashl.XMLLoader.init xsltCacheLifetimeSe= conds=3D60 [junit4:junit4] 2> 1471869 T4253 oashl.XMLLoader.init xsltCacheLifetimeSe= conds=3D60 [junit4:junit4] 2> 1471869 T4226 oashl.XMLLoader.init xsltCacheLifetimeSe= conds=3D60 [junit4:junit4] 2> 1471881 T4268 oass.FileExchangeRateProvider.reload Rel= oading exchange rates from file currency.xml [junit4:junit4] 2> 1471886 T4208 oass.FileExchangeRateProvider.reload Rel= oading exchange rates from file currency.xml [junit4:junit4] 2> 1471887 T4253 oass.SolrIndexSearcher. Opening Se= archer@153c1581 main [junit4:junit4] 2> 1471888 T4253 oasu.UpdateHandler.clearLog Clearing tlo= g files, tlogDir=3DC:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Window= s\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistri= butedZkTest-jetty3-1368895001224\awholynewcollection_0_shard1_replica4\data= \tlog [junit4:junit4] 2> 1471890 T4226 oass.SolrIndexSearcher. Opening Se= archer@62ecee98 main [junit4:junit4] 2> 1471894 T4268 oass.OpenExchangeRatesOrgProvider.reload= Reloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 1471896 T4226 oasu.UpdateHandler.clearLog Clearing tlo= g files, tlogDir=3DC:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Window= s\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistri= butedZkTest-jetty1-1368894997005\awholynewcollection_0_shard1_replica2\data= \tlog [junit4:junit4] 2> 1471898 T4208 oass.OpenExchangeRatesOrgProvider.reload= Reloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 1471892 T4239 oass.SolrIndexSearcher. Opening Se= archer@24e58d7d main [junit4:junit4] 2> 1471892 T4253 oasu.CommitTracker. Hard AutoCommi= t: disabled [junit4:junit4] 2> 1471900 T4253 oasu.CommitTracker. Soft AutoCommi= t: disabled [junit4:junit4] 2> 1471900 T4268 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Unknown key IMPORTANT NOTE [junit4:junit4] 2> 1471902 T4268 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Expected key, got STRING [junit4:junit4] 2> 1471903 T4268 oass.OpenExchangeRatesOrgProvider.reload= Reloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 1471899 T4239 oasu.UpdateHandler.clearLog Clearing tlo= g files, tlogDir=3DC:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Window= s\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistri= butedZkTest-jetty2-1368894999080\awholynewcollection_0_shard1_replica1\data= \tlog [junit4:junit4] 2> 1471907 T4239 oasu.CommitTracker. Hard AutoCommi= t: disabled [junit4:junit4] 2> 1471907 T4239 oasu.CommitTracker. Soft AutoCommi= t: disabled [junit4:junit4] 2> 1471909 T4253 oasc.ZkController.publish publishing cor= e=3Dawholynewcollection_0_shard1_replica4 state=3Ddown [junit4:junit4] 2> 1471898 T4226 oasu.CommitTracker. Hard AutoCommi= t: disabled [junit4:junit4] 2> 1471911 T4226 oasu.CommitTracker. Soft AutoCommi= t: disabled [junit4:junit4] 2> 1471910 T4291 oasc.SolrCore.registerSearcher [awholyne= wcollection_0_shard1_replica4] Registered new searcher Searcher@153c1581 ma= in{StandardDirectoryReader(segments_1:1)} [junit4:junit4] 2> 1471913 T4292 oasc.SolrCore.registerSearcher [awholyne= wcollection_0_shard1_replica1] Registered new searcher Searcher@24e58d7d ma= in{StandardDirectoryReader(segments_1:1)} [junit4:junit4] 2> 1471903 T4208 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Unknown key IMPORTANT NOTE [junit4:junit4] 2> 1471914 T4208 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Expected key, got STRING [junit4:junit4] 2> 1471915 T4208 oass.OpenExchangeRatesOrgProvider.reload= Reloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 1471919 T4239 oasc.ZkController.publish publishing cor= e=3Dawholynewcollection_0_shard1_replica1 state=3Ddown [junit4:junit4] 2> 1471919 T4226 oasc.ZkController.publish publishing cor= e=3Dawholynewcollection_0_shard1_replica2 state=3Ddown [junit4:junit4] 2> 1471919 T4293 oasc.SolrCore.registerSearcher [awholyne= wcollection_0_shard1_replica2] Registered new searcher Searcher@62ecee98 ma= in{StandardDirectoryReader(segments_1:1)} [junit4:junit4] 2> 1471989 T4268 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Unknown key IMPORTANT NOTE [junit4:junit4] 2> 1471990 T4268 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Expected key, got STRING [junit4:junit4] 2> 1471990 T4268 oasc.SolrCore.initDirectoryFactory org.a= pache.solr.core.MockDirectoryFactory [junit4:junit4] 2> 1471991 T4268 oasc.SolrCore. [awholynewcollectio= n_0_shard1_replica5] Opening new SolrCore at C:\Users\JenkinsSlave\workspac= e\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.= cloud.CollectionsAPIDistributedZkTest-jetty4-1368895003373\awholynewcollect= ion_0_shard1_replica5\, dataDir=3DC:\Users\JenkinsSlave\workspace\Lucene-So= lr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Colle= ctionsAPIDistributedZkTest-jetty4-1368895003373\awholynewcollection_0_shard= 1_replica5\data\ [junit4:junit4] 2> 1471991 T4268 oasc.JmxMonitoredMap. JMX monitori= ng is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@24769ece [junit4:junit4] 2> 1471991 T4268 oasc.SolrCore.initDirectoryFactory org.a= pache.solr.core.MockDirectoryFactory [junit4:junit4] 2> 1471992 T4268 oasc.CachingDirectoryFactory.get return = new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistrib= utedZkTest-jetty4-1368895003373\awholynewcollection_0_shard1_replica5\data\ [junit4:junit4] 2> 1471993 T4268 oasc.SolrCore.getNewIndexDir New index d= irectory detected: old=3Dnull new=3DC:\Users\JenkinsSlave\workspace\Lucene-= Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Col= lectionsAPIDistributedZkTest-jetty4-1368895003373\awholynewcollection_0_sha= rd1_replica5\data\index/ [junit4:junit4] 2> 1471993 T4268 oasc.SolrCore.initIndex WARN [awholynewc= ollection_0_shard1_replica5] Solr index directory 'C:\Users\JenkinsSlave\wo= rkspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache= .solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1368895003373\awholynewc= ollection_0_shard1_replica5\data\index' doesn't exist. Creating new index..= . [junit4:junit4] 2> 1471993 T4268 oasc.CachingDirectoryFactory.get return = new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistrib= utedZkTest-jetty4-1368895003373\awholynewcollection_0_shard1_replica5\data\= index [junit4:junit4] 2> 1471997 T4268 oasc.SolrDeletionPolicy.onCommit SolrDel= etionPolicy.onCommit: commits: num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@2d318fc0 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@7ffddf72),segFN=3Dsegments_1,generation=3D1} [junit4:junit4] 2> 1471998 T4268 oasc.SolrDeletionPolicy.updateCommits ne= west commit generation =3D 1 [junit4:junit4] 2> 1472002 T4268 oasup.UpdateRequestProcessorChain.init i= nserting DistributedUpdateProcessorFactory into updateRequestProcessorChain= "dedupe" [junit4:junit4] 2> 1472003 T4268 oasup.UpdateRequestProcessorChain.init i= nserting DistributedUpdateProcessorFactory into updateRequestProcessorChain= "stored_sig" [junit4:junit4] 2> 1472003 T4268 oasup.UpdateRequestProcessorChain.init i= nserting DistributedUpdateProcessorFactory into updateRequestProcessorChain= "distrib-dup-test-chain-implicit" [junit4:junit4] 2> 1472004 T4268 oasc.RequestHandlers.initHandlersFromCon= fig created standard: solr.StandardRequestHandler [junit4:junit4] 2> 1472004 T4268 oasc.RequestHandlers.initHandlersFromCon= fig created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 1472005 T4268 oasc.RequestHandlers.initHandlersFromCon= fig adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 1472005 T4268 oasc.RequestHandlers.initHandlersFromCon= fig created /replication: solr.ReplicationHandler [junit4:junit4] 2> 1472006 T4268 oasc.RequestHandlers.initHandlersFromCon= fig created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 1472006 T4268 oasc.RequestHandlers.initHandlersFromCon= fig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 1472013 T4250 oasha.CoreAdminHandler.handleWaitForStat= eAction Waited coreNodeName: 127.0.0.1:60425__nodes_used_collection_shard2_= replica2, state: recovering, checkLive: true, onlyIfLeader: true for: 3 sec= onds. [junit4:junit4] 2> 1472013 T4250 oass.SolrDispatchFilter.handleAdminReque= st [admin] webapp=3Dnull path=3D/admin/cores params=3D{coreNodeName=3D127.0= .0.1:60425__nodes_used_collection_shard2_replica2&state=3Drecovering&nodeNa= me=3D127.0.0.1:60425_&action=3DPREPRECOVERY&checkLive=3Dtrue&core=3Dnodes_u= sed_collection_shard2_replica1&wt=3Djavabin&onlyIfLeader=3Dtrue&version=3D2= } status=3D0 QTime=3D3161=20 [junit4:junit4] 2> 1472015 T4225 oasha.CoreAdminHandler.handleWaitForStat= eAction Waited coreNodeName: 127.0.0.1:60464__nodes_used_collection_shard1_= replica1, state: recovering, checkLive: true, onlyIfLeader: true for: 3 sec= onds. [junit4:junit4] 2> 1472015 T4225 oass.SolrDispatchFilter.handleAdminReque= st [admin] webapp=3Dnull path=3D/admin/cores params=3D{coreNodeName=3D127.0= .0.1:60464__nodes_used_collection_shard1_replica1&state=3Drecovering&nodeNa= me=3D127.0.0.1:60464_&action=3DPREPRECOVERY&checkLive=3Dtrue&core=3Dnodes_u= sed_collection_shard1_replica2&wt=3Djavabin&onlyIfLeader=3Dtrue&version=3D2= } status=3D0 QTime=3D3213=20 [junit4:junit4] 2> 1472019 T4268 oashl.XMLLoader.init xsltCacheLifetimeSe= conds=3D60 [junit4:junit4] 2> 1472027 T4268 oass.SolrIndexSearcher. Opening Se= archer@6acc0571 main [junit4:junit4] 2> 1472028 T4268 oasu.UpdateHandler.clearLog Clearing tlo= g files, tlogDir=3DC:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Window= s\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistri= butedZkTest-jetty4-1368895003373\awholynewcollection_0_shard1_replica5\data= \tlog [junit4:junit4] 2> 1472030 T4268 oasu.CommitTracker. Hard AutoCommi= t: disabled [junit4:junit4] 2> 1472030 T4268 oasu.CommitTracker. Soft AutoCommi= t: disabled [junit4:junit4] 2> 1472033 T4294 oasc.SolrCore.registerSearcher [awholyne= wcollection_0_shard1_replica5] Registered new searcher Searcher@6acc0571 ma= in{StandardDirectoryReader(segments_1:1)} [junit4:junit4] 2> 1472034 T4268 oasc.ZkController.publish publishing cor= e=3Dawholynewcollection_0_shard1_replica5 state=3Ddown [junit4:junit4] 2> 1472101 T4208 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Unknown key IMPORTANT NOTE [junit4:junit4] 2> 1472102 T4208 oass.OpenExchangeRatesOrgProvider$OpenEx= changeRates. WARN Expected key, got STRING [junit4:junit4] 2> 1472103 T4208 oasc.SolrCore.initDirectoryFactory org.a= pache.solr.core.MockDirectoryFactory [junit4:junit4] 2> 1472113 T4208 oasc.SolrCore. [awholynewcollectio= n_0_shard1_replica3] Opening new SolrCore at C:\Users\JenkinsSlave\workspac= e\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.= cloud.CollectionsAPIDistributedZkTest-controljetty-1368894996050\awholynewc= ollection_0_shard1_replica3\, dataDir=3DC:\Users\JenkinsSlave\workspace\Luc= ene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud= .CollectionsAPIDistributedZkTest-controljetty-1368894996050\awholynewcollec= tion_0_shard1_replica3\data\ [junit4:junit4] 2> 1472113 T4208 oasc.JmxMonitoredMap. JMX monitori= ng is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@24769ece [junit4:junit4] 2> 1472114 T4208 oasc.SolrCore.initDirectoryFactory org.a= pache.solr.core.MockDirectoryFactory [junit4:junit4] 2> 1472114 T4208 oasc.CachingDirectoryFactory.get return = new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistrib= utedZkTest-controljetty-1368894996050\awholynewcollection_0_shard1_replica3= \data\ [junit4:junit4] 2> 1472115 T4208 oasc.SolrCore.getNewIndexDir New index d= irectory detected: old=3Dnull new=3DC:\Users\JenkinsSlave\workspace\Lucene-= Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Col= lectionsAPIDistributedZkTest-controljetty-1368894996050\awholynewcollection= _0_shard1_replica3\data\index/ [junit4:junit4] 2> 1472115 T4208 oasc.SolrCore.initIndex WARN [awholynewc= ollection_0_shard1_replica3] Solr index directory 'C:\Users\JenkinsSlave\wo= rkspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache= .solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1368894996050\awho= lynewcollection_0_shard1_replica3\data\index' doesn't exist. Creating new i= ndex... [junit4:junit4] 2> 1472115 T4208 oasc.CachingDirectoryFactory.get return = new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows= \solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistrib= utedZkTest-controljetty-1368894996050\awholynewcollection_0_shard1_replica3= \data\index [junit4:junit4] 2> 1472123 T4208 oasc.SolrDeletionPolicy.onCommit SolrDel= etionPolicy.onCommit: commits: num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@6421ac87 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@409fe330),segFN=3Dsegments_1,generation=3D1} [junit4:junit4] 2> 1472124 T4208 oasc.SolrDeletionPolicy.updateCommits ne= west commit generation =3D 1 [junit4:junit4] 2> 1472130 T4208 oasup.UpdateRequestProcessorChain.init i= nserting DistributedUpdateProcessorFactory into updateRequestProcessorChain= "dedupe" [junit4:junit4] 2> 1472130 T4208 oasup.UpdateRequestProcessorChain.init i= nserting DistributedUpdateProcessorFactory into updateRequestProcessorChain= "stored_sig" [junit4:junit4] 2> 1472131 T4208 oasup.UpdateRequestProcessorChain.init i= nserting DistributedUpdateProcessorFactory into updateRequestProcessorChain= "distrib-dup-test-chain-implicit" [junit4:junit4] 2> 1472131 T4208 oasc.RequestHandlers.initHandlersFromCon= fig created standard: solr.StandardRequestHandler [junit4:junit4] 2> 1472132 T4208 oasc.RequestHandlers.initHandlersFromCon= fig created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 1472132 T4208 oasc.RequestHandlers.initHandlersFromCon= fig adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 1472132 T4208 oasc.RequestHandlers.initHandlersFromCon= fig created /replication: solr.ReplicationHandler [junit4:junit4] 2> 1472133 T4208 oasc.RequestHandlers.initHandlersFromCon= fig created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 1472133 T4208 oasc.RequestHandlers.initHandlersFromCon= fig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 1472142 T4208 oashl.XMLLoader.init xsltCacheLifetimeSe= conds=3D60 [junit4:junit4] 2> 1472149 T4208 oass.SolrIndexSearcher. Opening Se= archer@66d284cc main [junit4:junit4] 2> 1472150 T4208 oasu.UpdateHandler.clearLog Clearing tlo= g files, tlogDir=3DC:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Window= s\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistri= butedZkTest-controljetty-1368894996050\awholynewcollection_0_shard1_replica= 3\data\tlog [junit4:junit4] 2> 1472153 T4208 oasu.CommitTracker. Hard AutoCommi= t: disabled [junit4:junit4] 2> 1472153 T4208 oasu.CommitTracker. Soft AutoCommi= t: disabled [junit4:junit4] 2> 1472156 T4295 oasc.SolrCore.registerSearcher [awholyne= wcollection_0_shard1_replica3] Registered new searcher Searcher@66d284cc ma= in{StandardDirectoryReader(segments_1:1)} [junit4:junit4] 2> 1472158 T4208 oasc.ZkController.publish publishing cor= e=3Dawholynewcollection_0_shard1_replica3 state=3Ddown [junit4:junit4] 2> 1472909 T4213 oascc.ZkStateReader.updateClusterState U= pdating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 1472911 T4213 oasc.Overseer$ClusterStateUpdater.update= State Update state numShards=3D1 message=3D{ [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "core_node_name":null, [junit4:junit4] 2> =09 "numShards":"1", [junit4:junit4] 2> =09 "shard":"shard1", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "state":"down", [junit4:junit4] 2> =09 "shard_state":"active", [junit4:junit4] 2> =09 "core":"awholynewcollection_0_shard1_replica4", [junit4:junit4] 2> =09 "collection":"awholynewcollection_0", [junit4:junit4] 2> =09 "shard_range":null, [junit4:junit4] 2> =09 "node_name":"127.0.0.1:60455_", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:60455"} [junit4:junit4] 2> 1472911 T4213 oasc.Overseer$ClusterStateUpdater.create= Collection Create collection awholynewcollection_0 with numShards 1 [junit4:junit4] 2> 1472920 T4213 oasc.Overseer$ClusterStateUpdater.update= State Update state numShards=3D1 message=3D{ [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "core_node_name":null, [junit4:junit4] 2> =09 "numShards":"1", [junit4:junit4] 2> =09 "shard":"shard1", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "state":"down", [junit4:junit4] 2> =09 "shard_state":"active", [junit4:junit4] 2> =09 "core":"awholynewcollection_0_shard1_replica1", [junit4:junit4] 2> =09 "collection":"awholynewcollection_0", [junit4:junit4] 2> =09 "shard_range":null, [junit4:junit4] 2> =09 "node_name":"127.0.0.1:60446_", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:60446"} [junit4:junit4] 2> 1472928 T4213 oasc.Overseer$ClusterStateUpdater.update= State Update state numShards=3D1 message=3D{ [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "core_node_name":null, [junit4:junit4] 2> =09 "numShards":"1", [junit4:junit4] 2> =09 "shard":"shard1", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "state":"down", [junit4:junit4] 2> =09 "shard_state":"active", [junit4:junit4] 2> =09 "core":"awholynewcollection_0_shard1_replica2", [junit4:junit4] 2> =09 "collection":"awholynewcollection_0", [junit4:junit4] 2> =09 "shard_range":null, [junit4:junit4] 2> =09 "node_name":"127.0.0.1:60437_", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:60437"} [junit4:junit4] 2> 1472934 T4213 oasc.Overseer$ClusterStateUpdater.update= State Update state numShards=3D1 message=3D{ [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "core_node_name":null, [junit4:junit4] 2> =09 "numShards":"1", [junit4:junit4] 2> =09 "shard":"shard1", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "state":"down", [junit4:junit4] 2> =09 "shard_state":"active", [junit4:junit4] 2> =09 "core":"awholynewcollection_0_shard1_replica5", [junit4:junit4] 2> =09 "collection":"awholynewcollection_0", [junit4:junit4] 2> =09 "shard_range":null, [junit4:junit4] 2> =09 "node_name":"127.0.0.1:60464_", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:60464"} [junit4:junit4] 2> 1472942 T4213 oasc.Overseer$ClusterStateUpdater.update= State Update state numShards=3D1 message=3D{ [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "core_node_name":null, [junit4:junit4] 2> =09 "numShards":"1", [junit4:junit4] 2> =09 "shard":"shard1", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "state":"down", [junit4:junit4] 2> =09 "shard_state":"active", [junit4:junit4] 2> =09 "core":"awholynewcollection_0_shard1_replica3", [junit4:junit4] 2> =09 "collection":"awholynewcollection_0", [junit4:junit4] 2> =09 "shard_range":null, [junit4:junit4] 2> =09 "node_name":"127.0.0.1:60425_", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:60425"} [junit4:junit4] 2> 1472952 T4272 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1472952 T4217 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1472953 T4287 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1472952 T4258 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1472953 T4289 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1472953 T4212 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1472952 T4244 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1472952 T4230 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1472979 T4253 oasc.CoreContainer.registerCore register= ing core: awholynewcollection_0_shard1_replica4 [junit4:junit4] 2> 1472980 T4253 oasc.ZkController.register Register repl= ica - core:awholynewcollection_0_shard1_replica4 address:http://127.0.0.1:6= 0455 collection:awholynewcollection_0 shard:shard1 [junit4:junit4] 2> 1472981 T4253 oascc.SolrZkClient.makePath makePath: /c= ollections/awholynewcollection_0/leader_elect/shard1/election [junit4:junit4] 2> 1472998 T4253 oasc.ShardLeaderElectionContext.runLeade= rProcess Running the leader process. [junit4:junit4] 2> 1473006 T4253 oasc.ShardLeaderElectionContext.waitForR= eplicasToComeUp Waiting until we see more replicas up: total=3D5 found=3D1 = timeoutin=3D179999 [junit4:junit4] 2> 1473092 T4226 oasc.CoreContainer.registerCore register= ing core: awholynewcollection_0_shard1_replica2 [junit4:junit4] 2> 1473092 T4226 oasc.ZkController.register Register repl= ica - core:awholynewcollection_0_shard1_replica2 address:http://127.0.0.1:6= 0437 collection:awholynewcollection_0 shard:shard1 [junit4:junit4] 2> 1473092 T4239 oasc.CoreContainer.registerCore register= ing core: awholynewcollection_0_shard1_replica1 [junit4:junit4] 2> 1473093 T4239 oasc.ZkController.register Register repl= ica - core:awholynewcollection_0_shard1_replica1 address:http://127.0.0.1:6= 0446 collection:awholynewcollection_0 shard:shard1 [junit4:junit4] 2> 1473103 T4268 oasc.CoreContainer.registerCore register= ing core: awholynewcollection_0_shard1_replica5 [junit4:junit4] 2> 1473103 T4268 oasc.ZkController.register Register repl= ica - core:awholynewcollection_0_shard1_replica5 address:http://127.0.0.1:6= 0464 collection:awholynewcollection_0 shard:shard1 [junit4:junit4] 2> 1473154 T4208 oasc.CoreContainer.registerCore register= ing core: awholynewcollection_0_shard1_replica3 [junit4:junit4] 2> 1473155 T4208 oasc.ZkController.register Register repl= ica - core:awholynewcollection_0_shard1_replica3 address:http://127.0.0.1:6= 0425 collection:awholynewcollection_0 shard:shard1 [junit4:junit4] 2> 1473504 T4253 oasc.ShardLeaderElectionContext.waitForR= eplicasToComeUp Enough replicas found to continue. [junit4:junit4] 2> 1473504 T4253 oasc.ShardLeaderElectionContext.runLeade= rProcess I may be the new leader - try and sync [junit4:junit4] 2> 1473504 T4253 oasc.SyncStrategy.sync Sync replicas to = http://127.0.0.1:60455/awholynewcollection_0_shard1_replica4/ [junit4:junit4] 2> 1473505 T4253 oasu.PeerSync.sync PeerSync: core=3Dawho= lynewcollection_0_shard1_replica4 url=3Dhttp://127.0.0.1:60455 START replic= as=3D[http://127.0.0.1:60446/awholynewcollection_0_shard1_replica1/, http:/= /127.0.0.1:60437/awholynewcollection_0_shard1_replica2/, http://127.0.0.1:6= 0464/awholynewcollection_0_shard1_replica5/, http://127.0.0.1:60425/awholyn= ewcollection_0_shard1_replica3/] nUpdates=3D100 [junit4:junit4] 2> 1473511 T4253 oasu.PeerSync.sync PeerSync: core=3Dawho= lynewcollection_0_shard1_replica4 url=3Dhttp://127.0.0.1:60455 DONE. We ha= ve no versions. sync failed. [junit4:junit4] 2> 1473523 T4253 oasc.SyncStrategy.syncReplicas Leader's = attempt to sync with shard failed, moving to the next canidate [junit4:junit4] 2> 1473524 T4253 oasc.ShardLeaderElectionContext.runLeade= rProcess We failed sync, but we have no versions - we can't sync in that ca= se - we were active before, so become leader anyway [junit4:junit4] 2> 1473524 T4253 oasc.ShardLeaderElectionContext.runLeade= rProcess I am the new leader: http://127.0.0.1:60455/awholynewcollection_0_= shard1_replica4/ [junit4:junit4] 2> 1473524 T4253 oascc.SolrZkClient.makePath makePath: /c= ollections/awholynewcollection_0/leaders/shard1 [junit4:junit4] 2> ASYNC NEW_CORE C273 name=3Dawholynewcollection_0_shar= d1_replica5 org.apache.solr.core.SolrCore@4fa156f2 url=3Dhttp://127.0.0.1:6= 0464/awholynewcollection_0_shard1_replica5 node=3D127.0.0.1:60464_ C273_STA= TE=3Dcoll:awholynewcollection_0 core:awholynewcollection_0_shard1_replica5 = props:{shard=3Dshard1, state=3Ddown, core=3Dawholynewcollection_0_shard1_re= plica5, collection=3Dawholynewcollection_0, node_name=3D127.0.0.1:60464_, b= ase_url=3Dhttp://127.0.0.1:60464} [junit4:junit4] 2> 1473528 T4264 C273 P60464 oasc.SolrCore.execute [awhol= ynewcollection_0_shard1_replica5] webapp=3D path=3D/get params=3D{getVersio= ns=3D100&distrib=3Dfalse&wt=3Djavabin&qt=3D/get&version=3D2} status=3D0 QTi= me=3D4=20 [junit4:junit4] 2> ASYNC NEW_CORE C274 name=3Dawholynewcollection_0_shar= d1_replica1 org.apache.solr.core.SolrCore@35b52946 url=3Dhttp://127.0.0.1:6= 0446/awholynewcollection_0_shard1_replica1 node=3D127.0.0.1:60446_ C274_STA= TE=3Dcoll:awholynewcollection_0 core:awholynewcollection_0_shard1_replica1 = props:{shard=3Dshard1, state=3Ddown, core=3Dawholynewcollection_0_shard1_re= plica1, collection=3Dawholynewcollection_0, node_name=3D127.0.0.1:60446_, b= ase_url=3Dhttp://127.0.0.1:60446} [junit4:junit4] 2> 1473529 T4235 C274 P60446 oasc.SolrCore.execute [awhol= ynewcollection_0_shard1_replica1] webapp=3D path=3D/get params=3D{getVersio= ns=3D100&distrib=3Dfalse&wt=3Djavabin&qt=3D/get&version=3D2} status=3D0 QTi= me=3D6=20 [junit4:junit4] 2> ASYNC NEW_CORE C275 name=3Dawholynewcollection_0_shar= d1_replica2 org.apache.solr.core.SolrCore@5fe5d56e url=3Dhttp://127.0.0.1:6= 0437/awholynewcollection_0_shard1_replica2 node=3D127.0.0.1:60437_ C275_STA= TE=3Dcoll:awholynewcollection_0 core:awholynewcollection_0_shard1_replica2 = props:{shard=3Dshard1, state=3Ddown, core=3Dawholynewcollection_0_shard1_re= plica2, collection=3Dawholynewcollection_0, node_name=3D127.0.0.1:60437_, b= ase_url=3Dhttp://127.0.0.1:60437} [junit4:junit4] 2> 1473537 T4222 C275 P60437 oasc.SolrCore.execute [awhol= ynewcollection_0_shard1_replica2] webapp=3D path=3D/get params=3D{getVersio= ns=3D100&distrib=3Dfalse&wt=3Djavabin&qt=3D/get&version=3D2} status=3D0 QTi= me=3D1=20 [junit4:junit4] 2> ASYNC NEW_CORE C276 name=3Dawholynewcollection_0_shar= d1_replica3 org.apache.solr.core.SolrCore@3f338c1e url=3Dhttp://127.0.0.1:6= 0425/awholynewcollection_0_shard1_replica3 node=3D127.0.0.1:60425_ C276_STA= TE=3Dcoll:awholynewcollection_0 core:awholynewcollection_0_shard1_replica3 = props:{shard=3Dshard1, state=3Ddown, core=3Dawholynewcollection_0_shard1_re= plica3, collection=3Dawholynewcollection_0, node_name=3D127.0.0.1:60425_, b= ase_url=3Dhttp://127.0.0.1:60425} [junit4:junit4] 2> 1473544 T4205 C276 P60425 oasc.SolrCore.execute [awhol= ynewcollection_0_shard1_replica3] webapp=3D path=3D/get params=3D{getVersio= ns=3D100&distrib=3Dfalse&wt=3Djavabin&qt=3D/get&version=3D2} status=3D0 QTi= me=3D1=20 [junit4:junit4] 2> C272_STATE=3Dcoll:nodes_used_collection core:nodes_us= ed_collection_shard2_replica2 props:{shard=3Dshard2, state=3Drecovering, co= re=3Dnodes_used_collection_shard2_replica2, collection=3Dnodes_used_collect= ion, node_name=3D127.0.0.1:60425_, base_url=3Dhttp://127.0.0.1:60425} [junit4:junit4] 2> 1473994 T4285 C272 P60425 oasc.RecoveryStrategy.doReco= very Attempting to PeerSync from http://127.0.0.1:60455/nodes_used_collecti= on_shard2_replica1/ core=3Dnodes_used_collection_shard2_replica2 - recoveri= ngAfterStartup=3Dtrue [junit4:junit4] 2> 1473994 T4285 C272 P60425 oasu.PeerSync.sync PeerSync:= core=3Dnodes_used_collection_shard2_replica2 url=3Dhttp://127.0.0.1:60425 = START replicas=3D[http://127.0.0.1:60455/nodes_used_collection_shard2_repli= ca1/] nUpdates=3D100 [junit4:junit4] 2> 1473995 T4285 C272 P60425 oasu.PeerSync.sync WARN no f= rame of reference to tell of we've missed updates [junit4:junit4] 2> 1473995 T4285 C272 P60425 oasc.RecoveryStrategy.doReco= very PeerSync Recovery was not successful - trying replication. core=3Dnode= s_used_collection_shard2_replica2 [junit4:junit4] 2> 1473996 T4285 C272 P60425 oasc.RecoveryStrategy.doReco= very Starting Replication Recovery. core=3Dnodes_used_collection_shard2_rep= lica2 [junit4:junit4] 2> 1473996 T4285 C272 P60425 oasc.RecoveryStrategy.doReco= very Begin buffering updates. core=3Dnodes_used_collection_shard2_replica2 [junit4:junit4] 2> 1473996 T4285 C272 P60425 oasu.UpdateLog.bufferUpdates= Starting to buffer updates. FSUpdateLog{state=3DACTIVE, tlog=3Dnull} [junit4:junit4] 2> 1473996 T4285 C272 P60425 oasc.RecoveryStrategy.replic= ate Attempting to replicate from http://127.0.0.1:60455/nodes_used_collecti= on_shard2_replica1/. core=3Dnodes_used_collection_shard2_replica2 [junit4:junit4] 2> 1473997 T4285 C272 P60425 oascsi.HttpClientUtil.create= Client Creating new http client, config:maxConnections=3D128&maxConnections= PerHost=3D32&followRedirects=3Dfalse [junit4:junit4] 2> C271_STATE=3Dcoll:nodes_used_collection core:nodes_us= ed_collection_shard1_replica1 props:{shard=3Dshard1, state=3Drecovering, co= re=3Dnodes_used_collection_shard1_replica1, collection=3Dnodes_used_collect= ion, node_name=3D127.0.0.1:60464_, base_url=3Dhttp://127.0.0.1:60464} [junit4:junit4] 2> 1473997 T4284 C271 P60464 oasc.RecoveryStrategy.doReco= very Attempting to PeerSync from http://127.0.0.1:60437/nodes_used_collecti= on_shard1_replica2/ core=3Dnodes_used_collection_shard1_replica1 - recoveri= ngAfterStartup=3Dtrue [junit4:junit4] 2> 1473998 T4284 C271 P60464 oasu.PeerSync.sync PeerSync:= core=3Dnodes_used_collection_shard1_replica1 url=3Dhttp://127.0.0.1:60464 = START replicas=3D[http://127.0.0.1:60437/nodes_used_collection_shard1_repli= ca2/] nUpdates=3D100 [junit4:junit4] 2> 1473999 T4284 C271 P60464 oasu.PeerSync.sync WARN no f= rame of reference to tell of we've missed updates [junit4:junit4] 2> 1473999 T4284 C271 P60464 oasc.RecoveryStrategy.doReco= very PeerSync Recovery was not successful - trying replication. core=3Dnode= s_used_collection_shard1_replica1 [junit4:junit4] 2> 1473999 T4284 C271 P60464 oasc.RecoveryStrategy.doReco= very Starting Replication Recovery. core=3Dnodes_used_collection_shard1_rep= lica1 [junit4:junit4] 2> 1473999 T4284 C271 P60464 oasc.RecoveryStrategy.doReco= very Begin buffering updates. core=3Dnodes_used_collection_shard1_replica1 [junit4:junit4] 2> 1474000 T4284 C271 P60464 oasu.UpdateLog.bufferUpdates= Starting to buffer updates. FSUpdateLog{state=3DACTIVE, tlog=3Dnull} [junit4:junit4] 2> 1474001 T4284 C271 P60464 oasc.RecoveryStrategy.replic= ate Attempting to replicate from http://127.0.0.1:60437/nodes_used_collecti= on_shard1_replica2/. core=3Dnodes_used_collection_shard1_replica1 [junit4:junit4] 2> 1474001 T4284 C271 P60464 oascsi.HttpClientUtil.create= Client Creating new http client, config:maxConnections=3D128&maxConnections= PerHost=3D32&followRedirects=3Dfalse [junit4:junit4] 2> ASYNC NEW_CORE C277 name=3Dnodes_used_collection_shar= d2_replica1 org.apache.solr.core.SolrCore@6ce85c77 url=3Dhttp://127.0.0.1:6= 0455/nodes_used_collection_shard2_replica1 node=3D127.0.0.1:60455_ C277_STA= TE=3Dcoll:nodes_used_collection core:nodes_used_collection_shard2_replica1 = props:{shard=3Dshard2, state=3Dactive, core=3Dnodes_used_collection_shard2_= replica1, collection=3Dnodes_used_collection, node_name=3D127.0.0.1:60455_,= base_url=3Dhttp://127.0.0.1:60455, leader=3Dtrue} [junit4:junit4] 2> 1474033 T4250 C277 P60455 oasc.SolrCore.execute [nodes= _used_collection_shard2_replica1] webapp=3D path=3D/get params=3D{getVersio= ns=3D100&distrib=3Dfalse&wt=3Djavabin&qt=3D/get&version=3D2} status=3D0 QTi= me=3D25=20 [junit4:junit4] 2> 1474042 T4254 C277 P60455 oasu.DirectUpdateHandler2.co= mmit start commit{,optimize=3Dfalse,openSearcher=3Dfalse,waitSearcher=3Dtru= e,expungeDeletes=3Dfalse,softCommit=3Dfalse,prepareCommit=3Dfalse} [junit4:junit4] 2> ASYNC NEW_CORE C278 name=3Dnodes_used_collection_shar= d1_replica2 org.apache.solr.core.SolrCore@71fc9a0a url=3Dhttp://127.0.0.1:6= 0437/nodes_used_collection_shard1_replica2 node=3D127.0.0.1:60437_ C278_STA= TE=3Dcoll:nodes_used_collection core:nodes_used_collection_shard1_replica2 = props:{shard=3Dshard1, state=3Dactive, core=3Dnodes_used_collection_shard1_= replica2, collection=3Dnodes_used_collection, node_name=3D127.0.0.1:60437_,= base_url=3Dhttp://127.0.0.1:60437, leader=3Dtrue} [junit4:junit4] 2> 1474051 T4221 C278 P60437 oasc.SolrCore.execute [nodes= _used_collection_shard1_replica2] webapp=3D path=3D/get params=3D{getVersio= ns=3D100&distrib=3Dfalse&wt=3Djavabin&qt=3D/get&version=3D2} status=3D0 QTi= me=3D1=20 [junit4:junit4] 2> 1474053 T4254 C277 P60455 oasc.SolrDeletionPolicy.onIn= it SolrDeletionPolicy.onInit: commits: num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4e335015 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@1d66b0d3),segFN=3Dsegments_1,generation=3D1} [junit4:junit4] 2> 1474056 T4254 C277 P60455 oasc.SolrDeletionPolicy.upda= teCommits newest commit generation =3D 1 [junit4:junit4] 2> 1474057 T4223 C278 P60437 oasu.DirectUpdateHandler2.co= mmit start commit{,optimize=3Dfalse,openSearcher=3Dfalse,waitSearcher=3Dtru= e,expungeDeletes=3Dfalse,softCommit=3Dfalse,prepareCommit=3Dfalse} [junit4:junit4] 2> 1474057 T4254 C277 P60455 oasc.SolrDeletionPolicy.onCo= mmit SolrDeletionPolicy.onCommit: commits: num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4e335015 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@1d66b0d3),segFN=3Dsegments_1,generation=3D1} [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4e335015 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@1d66b0d3),segFN=3Dsegments_2,generation=3D2} [junit4:junit4] 2> 1474058 T4254 C277 P60455 oasc.SolrDeletionPolicy.upda= teCommits newest commit generation =3D 2 [junit4:junit4] 2> 1474059 T4254 C277 P60455 oass.SolrIndexSearcher. Opening Searcher@4cf9914a realtime [junit4:junit4] 2> 1474059 T4254 C277 P60455 oasu.DirectUpdateHandler2.co= mmit end_commit_flush [junit4:junit4] 2> 1474059 T4254 C277 P60455 oasup.LogUpdateProcessor.fin= ish [nodes_used_collection_shard2_replica1] webapp=3D path=3D/update params= =3D{waitSearcher=3Dtrue&openSearcher=3Dfalse&commit=3Dtrue&wt=3Djavabin&com= mit_end_point=3Dtrue&version=3D2&softCommit=3Dfalse} {commit=3D} 0 17 [junit4:junit4] 2> 1474061 T4285 C272 P60425 oash.ReplicationHandler.info= rm Commits will be reserved for 10000 [junit4:junit4] 2> 1474061 T4285 C272 P60425 oash.SnapPuller. No v= alue set for 'pollInterval'. Timer Task not started. [junit4:junit4] 2> 1474062 T4223 C278 P60437 oasc.SolrDeletionPolicy.onIn= it SolrDeletionPolicy.onInit: commits: num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@75b6382d lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@16506dc0),segFN=3Dsegments_1,generation=3D1} [junit4:junit4] 2> 1474062 T4223 C278 P60437 oasc.SolrDeletionPolicy.upda= teCommits newest commit generation =3D 1 [junit4:junit4] 2> 1474064 T4223 C278 P60437 oasc.SolrDeletionPolicy.onCo= mmit SolrDeletionPolicy.onCommit: commits: num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@75b6382d lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@16506dc0),segFN=3Dsegments_1,generation=3D1} [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@75b6382d lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@16506dc0),segFN=3Dsegments_2,generation=3D2} [junit4:junit4] 2> 1474064 T4223 C278 P60437 oasc.SolrDeletionPolicy.upda= teCommits newest commit generation =3D 2 [junit4:junit4] 2> 1474064 T4252 C277 P60455 oash.ReplicationHandler.info= rm Commits will be reserved for 10000 [junit4:junit4] 2> 1474065 T4223 C278 P60437 oass.SolrIndexSearcher. Opening Searcher@26535437 realtime [junit4:junit4] 2> 1474065 T4223 C278 P60437 oasu.DirectUpdateHandler2.co= mmit end_commit_flush [junit4:junit4] 2> 1474065 T4252 C277 P60455 oasc.SolrCore.execute [nodes= _used_collection_shard2_replica1] webapp=3D path=3D/replication params=3D{c= ommand=3Dindexversion&qt=3D/replication&wt=3Djavabin&version=3D2} status=3D= 0 QTime=3D1=20 [junit4:junit4] 2> 1474066 T4223 C278 P60437 oasup.LogUpdateProcessor.fin= ish [nodes_used_collection_shard1_replica2] webapp=3D path=3D/update params= =3D{waitSearcher=3Dtrue&openSearcher=3Dfalse&commit=3Dtrue&wt=3Djavabin&com= mit_end_point=3Dtrue&version=3D2&softCommit=3Dfalse} {commit=3D} 0 9 [junit4:junit4] 2> 1474067 T4285 C272 P60425 oash.SnapPuller.fetchLatestI= ndex Master's generation: 2 [junit4:junit4] 2> 1474067 T4285 C272 P60425 oash.SnapPuller.fetchLatestI= ndex Slave's generation: 1 [junit4:junit4] 2> 1474067 T4285 C272 P60425 oash.SnapPuller.fetchLatestI= ndex Starting replication process [junit4:junit4] 2> 1474067 T4284 C271 P60464 oash.ReplicationHandler.info= rm Commits will be reserved for 10000 [junit4:junit4] 2> 1474068 T4284 C271 P60464 oash.SnapPuller. No v= alue set for 'pollInterval'. Timer Task not started. [junit4:junit4] 2> 1474074 T4251 C277 P60455 oasc.SolrCore.execute [nodes= _used_collection_shard2_replica1] webapp=3D path=3D/replication params=3D{c= ommand=3Dfilelist&qt=3D/replication&wt=3Djavabin&generation=3D2&version=3D2= } status=3D0 QTime=3D1=20 [junit4:junit4] 2> 1474075 T4285 C272 P60425 oash.SnapPuller.fetchLatestI= ndex Number of files in latest index in master: 1 [junit4:junit4] 2> 1474075 T4224 C278 P60437 oash.ReplicationHandler.info= rm Commits will be reserved for 10000 [junit4:junit4] 2> 1474083 T4224 C278 P60437 oasc.SolrCore.execute [nodes= _used_collection_shard1_replica2] webapp=3D path=3D/replication params=3D{c= ommand=3Dindexversion&qt=3D/replication&wt=3Djavabin&version=3D2} status=3D= 0 QTime=3D9=20 [junit4:junit4] 2> 1474084 T4285 C272 P60425 oasc.CachingDirectoryFactory= .get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-t= runk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Collectio= nsAPIDistributedZkTest-controljetty-1368894996050\nodes_used_collection_sha= rd2_replica2\data\index.20130518183655765 [junit4:junit4] 2> 1474084 T4285 C272 P60425 oash.SnapPuller.fetchLatestI= ndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirecto= ry@5882f052 lockFactory=3Dorg.apache.lucene.store.NativeFSLockFactory@4df40= 479) fullCopy=3Dfalse [junit4:junit4] 2> 1474084 T4284 C271 P60464 oash.SnapPuller.fetchLatestI= ndex Master's generation: 2 [junit4:junit4] 2> 1474084 T4284 C271 P60464 oash.SnapPuller.fetchLatestI= ndex Slave's generation: 1 [junit4:junit4] 2> 1474085 T4284 C271 P60464 oash.SnapPuller.fetchLatestI= ndex Starting replication process [junit4:junit4] 2> 1474086 T4250 C277 P60455 oasc.SolrCore.execute [nodes= _used_collection_shard2_replica1] webapp=3D path=3D/replication params=3D{f= ile=3Dsegments_2&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt= =3D/replication&generation=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 1474088 T4222 C278 P60437 oasc.SolrCore.execute [nodes= _used_collection_shard1_replica2] webapp=3D path=3D/replication params=3D{c= ommand=3Dfilelist&qt=3D/replication&wt=3Djavabin&generation=3D2&version=3D2= } status=3D0 QTime=3D1=20 [junit4:junit4] 2> 1474089 T4285 C272 P60425 oash.SnapPuller.fetchLatestI= ndex Total time taken for download : 0 secs [junit4:junit4] 2> 1474090 T4284 C271 P60464 oash.SnapPuller.fetchLatestI= ndex Number of files in latest index in master: 1 [junit4:junit4] 2> 1474091 T4285 C272 P60425 oasu.DefaultSolrCoreState.ne= wIndexWriter Creating new IndexWriter... [junit4:junit4] 2> 1474093 T4285 C272 P60425 oasu.DefaultSolrCoreState.ne= wIndexWriter Waiting until IndexWriter is unused... core=3Dnodes_used_colle= ction_shard2_replica2 [junit4:junit4] 2> 1474093 T4284 C271 P60464 oasc.CachingDirectoryFactory= .get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-t= runk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Collectio= nsAPIDistributedZkTest-jetty4-1368895003373\nodes_used_collection_shard1_re= plica1\data\index.20130518183655780 [junit4:junit4] 2> 1474093 T4284 C271 P60464 oash.SnapPuller.fetchLatestI= ndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirecto= ry@447eb677 lockFactory=3Dorg.apache.lucene.store.NativeFSLockFactory@74d2f= 15f) fullCopy=3Dfalse [junit4:junit4] 2> 1474096 T4225 C278 P60437 oasc.SolrCore.execute [nodes= _used_collection_shard1_replica2] webapp=3D path=3D/replication params=3D{f= ile=3Dsegments_2&command=3Dfilecontent&checksum=3Dtrue&wt=3Dfilestream&qt= =3D/replication&generation=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 1474096 T4285 C272 P60425 oasc.SolrDeletionPolicy.onIn= it SolrDeletionPolicy.onInit: commits: num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@202ba2e lockFactory=3Dorg.apache.lucene.store.NativeFSLock= Factory@61cda73d),segFN=3Dsegments_1,generation=3D1} [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@202ba2e lockFactory=3Dorg.apache.lucene.store.NativeFSLock= Factory@61cda73d),segFN=3Dsegments_2,generation=3D2} [junit4:junit4] 2> 1474097 T4285 C272 P60425 oasc.SolrDeletionPolicy.upda= teCommits newest commit generation =3D 2 [junit4:junit4] 2> 1474097 T4285 C272 P60425 oasu.DefaultSolrCoreState.ne= wIndexWriter New IndexWriter is ready to be used. [junit4:junit4] 2> 1474098 T4285 C272 P60425 oass.SolrIndexSearcher. Opening Searcher@5f4e906a main [junit4:junit4] 2> 1474098 T4284 C271 P60464 oash.SnapPuller.fetchLatestI= ndex Total time taken for download : 0 secs [junit4:junit4] 2> 1474099 T4281 oasc.SolrCore.registerSearcher [nodes_us= ed_collection_shard2_replica2] Registered new searcher Searcher@5f4e906a ma= in{StandardDirectoryReader(segments_2:1:nrt)} [junit4:junit4] 2> 1474103 T4284 C271 P60464 oasu.DefaultSolrCoreState.ne= wIndexWriter Creating new IndexWriter... [junit4:junit4] 2> 1474103 T4284 C271 P60464 oasu.DefaultSolrCoreState.ne= wIndexWriter Waiting until IndexWriter is unused... core=3Dnodes_used_colle= ction_shard1_replica1 [junit4:junit4] 2> 1474104 T4285 C272 P60425 oasc.CachingDirectoryFactory= .closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-So= lr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Colle= ctionsAPIDistributedZkTest-controljetty-1368894996050\nodes_used_collection= _shard2_replica2\data\index.20130518183655765 [CachedDir<>] [junit4:junit4] 2> 1474104 T4285 C272 P60425 oasc.CachingDirectoryFactory= .close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk= -Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAP= IDistributedZkTest-controljetty-1368894996050\nodes_used_collection_shard2_= replica2\data\index.20130518183655765 [junit4:junit4] 2> 1474104 T4285 C272 P60425 oasc.CachingDirectoryFactory= .closeCacheValue Removing directory before core close: C:\Users\JenkinsSlav= e\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.ap= ache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1368894996050\= nodes_used_collection_shard2_replica2\data\index.20130518183655765 [junit4:junit4] 2> 1474105 T4285 C272 P60425 oasc.RecoveryStrategy.replay= No replay needed. core=3Dnodes_used_collection_shard2_replica2 [junit4:junit4] 2> 1474105 T4285 C272 P60425 oasc.RecoveryStrategy.doReco= very Replication Recovery was successful - registering as Active. core=3Dno= des_used_collection_shard2_replica2 [junit4:junit4] 2> 1474105 T4285 C272 P60425 oasc.ZkController.publish pu= blishing core=3Dnodes_used_collection_shard2_replica2 state=3Dactive [junit4:junit4] 2> 1474108 T4284 C271 P60464 oasc.SolrDeletionPolicy.onIn= it SolrDeletionPolicy.onInit: commits: num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4a8b1458 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@64caa55a),segFN=3Dsegments_1,generation=3D1} [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@4a8b1458 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@64caa55a),segFN=3Dsegments_2,generation=3D2} [junit4:junit4] 2> 1474109 T4284 C271 P60464 oasc.SolrDeletionPolicy.upda= teCommits newest commit generation =3D 2 [junit4:junit4] 2> 1474109 T4284 C271 P60464 oasu.DefaultSolrCoreState.ne= wIndexWriter New IndexWriter is ready to be used. [junit4:junit4] 2> 1474110 T4284 C271 P60464 oass.SolrIndexSearcher. Opening Searcher@7c7ceb1 main [junit4:junit4] 2> 1474111 T4280 oasc.SolrCore.registerSearcher [nodes_us= ed_collection_shard1_replica1] Registered new searcher Searcher@7c7ceb1 mai= n{StandardDirectoryReader(segments_2:1:nrt)} [junit4:junit4] 2> 1474111 T4284 C271 P60464 oasc.CachingDirectoryFactory= .closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-So= lr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.Colle= ctionsAPIDistributedZkTest-jetty4-1368895003373\nodes_used_collection_shard= 1_replica1\data\index.20130518183655780 [CachedDir<>] [junit4:junit4] 2> 1474111 T4284 C271 P60464 oasc.CachingDirectoryFactory= .close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk= -Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAP= IDistributedZkTest-jetty4-1368895003373\nodes_used_collection_shard1_replic= a1\data\index.20130518183655780 [junit4:junit4] 2> 1474111 T4284 C271 P60464 oasc.CachingDirectoryFactory= .closeCacheValue Removing directory before core close: C:\Users\JenkinsSlav= e\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.ap= ache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1368895003373\nodes_= used_collection_shard1_replica1\data\index.20130518183655780 [junit4:junit4] 2> 1474112 T4284 C271 P60464 oasc.RecoveryStrategy.replay= No replay needed. core=3Dnodes_used_collection_shard1_replica1 [junit4:junit4] 2> 1474112 T4284 C271 P60464 oasc.RecoveryStrategy.doReco= very Replication Recovery was successful - registering as Active. core=3Dno= des_used_collection_shard1_replica1 [junit4:junit4] 2> 1474112 T4284 C271 P60464 oasc.ZkController.publish pu= blishing core=3Dnodes_used_collection_shard1_replica1 state=3Dactive [junit4:junit4] 2> 1474112 T4285 C272 P60425 oasc.RecoveryStrategy.doReco= very Finished recovery process. core=3Dnodes_used_collection_shard2_replica= 2 [junit4:junit4] 2> 1474115 T4284 C271 P60464 oasc.RecoveryStrategy.doReco= very Finished recovery process. core=3Dnodes_used_collection_shard1_replica= 1 [junit4:junit4] 2> 1474464 T4213 oascc.ZkStateReader.updateClusterState U= pdating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 1474501 T4213 oasc.Overseer$ClusterStateUpdater.update= State Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "core_node_name":"127.0.0.1:60425__nodes_used_col= lection_shard2_replica2", [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "shard":"shard2", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "state":"active", [junit4:junit4] 2> =09 "shard_state":"active", [junit4:junit4] 2> =09 "core":"nodes_used_collection_shard2_replica2", [junit4:junit4] 2> =09 "collection":"nodes_used_collection", [junit4:junit4] 2> =09 "shard_range":null, [junit4:junit4] 2> =09 "node_name":"127.0.0.1:60425_", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:60425"} [junit4:junit4] 2> 1474511 T4213 oasc.Overseer$ClusterStateUpdater.update= State Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "core_node_name":"127.0.0.1:60464__nodes_used_col= lection_shard1_replica1", [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "shard":"shard1", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "state":"active", [junit4:junit4] 2> =09 "shard_state":"active", [junit4:junit4] 2> =09 "core":"nodes_used_collection_shard1_replica1", [junit4:junit4] 2> =09 "collection":"nodes_used_collection", [junit4:junit4] 2> =09 "shard_range":null, [junit4:junit4] 2> =09 "node_name":"127.0.0.1:60464_", [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:60464"} [junit4:junit4] 2> 1474523 T4217 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1474524 T4244 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1474525 T4212 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1474524 T4289 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1474524 T4287 oascc.ZkStateReader$2.process A cluster = state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/c= lusterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 1474524 T4272 oascc.ZkStateReader$2.process A cluster = state c [...truncated too long message...] /solr/collections/awholynewcollection_1/leader_elect/shard2/election/89711= 902448222216-127.0.0.1:60446__awholynewcollection_1_shard2_replica2-n_00000= 00002 (0) [junit4:junit4] 1> /solr/collections/awholynewcollection_1/leader_el= ect/shard3 (1) [junit4:junit4] 1> /solr/collections/awholynewcollection_1/leader_e= lect/shard3/election (3) [junit4:junit4] 1> /solr/collections/awholynewcollection_1/leader_= elect/shard3/election/89711902448222211-127.0.0.1:60425__awholynewcollectio= n_1_shard3_replica3-n_0000000002 (0) [junit4:junit4] 1> /solr/collections/awholynewcollection_1/leader_= elect/shard3/election/89711902448222214-127.0.0.1:60437__awholynewcollectio= n_1_shard3_replica1-n_0000000001 (0) [junit4:junit4] 1> /solr/collections/awholynewcollection_1/leader_= elect/shard3/election/89711902448222218-127.0.0.1:60455__awholynewcollectio= n_1_shard3_replica2-n_0000000000 (0) [junit4:junit4] 1> /solr/collections/awholynewcollection_1/leaders (4= ) [junit4:junit4] 1> /solr/collections/awholynewcollection_1/leaders/s= hard1 (0) [junit4:junit4] 1> DATA: [junit4:junit4] 1> { [junit4:junit4] 1> "core":"awholynewcollection_1_shard1_replic= a2", [junit4:junit4] 1> "node_name":"127.0.0.1:60437_", [junit4:junit4] 1> "base_url":"http://127.0.0.1:60437"} [junit4:junit4] 1> /solr/collections/awholynewcollection_1/leaders/s= hard4 (0) [junit4:junit4] 1> DATA: [junit4:junit4] 1> { [junit4:junit4] 1> "core":"awholynewcollection_1_shard4_replic= a2", [junit4:junit4] 1> "node_name":"127.0.0.1:60464_", [junit4:junit4] 1> "base_url":"http://127.0.0.1:60464"} [junit4:junit4] 1> /solr/collections/awholynewcollection_1/leaders/s= hard2 (0) [junit4:junit4] 1> DATA: [junit4:junit4] 1> { [junit4:junit4] 1> "core":"awholynewcollection_1_shard2_replic= a3", [junit4:junit4] 1> "node_name":"127.0.0.1:60464_", [junit4:junit4] 1> "base_url":"http://127.0.0.1:60464"} [junit4:junit4] 1> /solr/collections/awholynewcollection_1/leaders/s= hard3 (0) [junit4:junit4] 1> DATA: [junit4:junit4] 1> { [junit4:junit4] 1> "core":"awholynewcollection_1_shard3_replic= a2", [junit4:junit4] 1> "node_name":"127.0.0.1:60455_", [junit4:junit4] 1> "base_url":"http://127.0.0.1:60455"} [junit4:junit4] 1> /solr/overseer_elect (2) [junit4:junit4] 1> /solr/overseer_elect/election (5) [junit4:junit4] 1> /solr/overseer_elect/election/89711902448222220-12= 7.0.0.1:60464_-n_0000000004 (0) [junit4:junit4] 1> /solr/overseer_elect/election/89711902448222216-12= 7.0.0.1:60446_-n_0000000002 (0) [junit4:junit4] 1> /solr/overseer_elect/election/89711902448222214-12= 7.0.0.1:60437_-n_0000000001 (0) [junit4:junit4] 1> /solr/overseer_elect/election/89711902448222218-12= 7.0.0.1:60455_-n_0000000003 (0) [junit4:junit4] 1> /solr/overseer_elect/election/89711902448222211-12= 7.0.0.1:60425_-n_0000000000 (0) [junit4:junit4] 1> /solr/overseer_elect/leader (0) [junit4:junit4] 1> DATA: [junit4:junit4] 1> {"id":"89711902448222211-127.0.0.1:60425_-n_000= 0000000"} [junit4:junit4] 1> /zookeeper (1) [junit4:junit4] 1> DATA: [junit4:junit4] 1> =20 [junit4:junit4] 1>=20 [junit4:junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DCollectio= nsAPIDistributedZkTest -Dtests.method=3DtestDistribSearch -Dtests.seed=3D7D= A14641F86B0A99 -Dtests.slow=3Dtrue -Dtests.locale=3Des_CL -Dtests.timezone= =3DAfrica/Lubumbashi -Dtests.file.encoding=3DCp1252 [junit4:junit4] FAILURE 321s | CollectionsAPIDistributedZkTest.testDistrib= Search <<< [junit4:junit4] > Throwable #1: java.lang.AssertionError: There are stil= l nodes recoverying - waited for 230 seconds [junit4:junit4] > =09at __randomizedtesting.SeedInfo.seed([7DA14641F86B0= A99:FC47C8598F346AA5]:0) [junit4:junit4] > =09at org.apache.solr.cloud.AbstractDistribZkTestBase.= waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:173) [junit4:junit4] > =09at org.apache.solr.cloud.AbstractDistribZkTestBase.= waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:131) [junit4:junit4] > =09at org.apache.solr.cloud.AbstractDistribZkTestBase.= waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:126) [junit4:junit4] > =09at org.apache.solr.cloud.CollectionsAPIDistributedZ= kTest.testCollectionsAPI(CollectionsAPIDistributedZkTest.java:512) [junit4:junit4] > =09at org.apache.solr.cloud.CollectionsAPIDistributedZ= kTest.doTest(CollectionsAPIDistributedZkTest.java:146) [junit4:junit4] > =09at org.apache.solr.BaseDistributedSearchTestCase.te= stDistribSearch(BaseDistributedSearchTestCase.java:815) [junit4:junit4] > =09at java.lang.Thread.run(Thread.java:722) [junit4:junit4] 2> 1775264 T4190 oas.SolrTestCaseJ4.deleteCore ###deleteC= ore [junit4:junit4] 2> 321125 T4189 ccr.ThreadLeakControl.checkThreadLeaks WA= RNING Will linger awaiting termination of 1 leaked thread(s). [junit4:junit4] 2> NOTE: test params are: codec=3DLucene40, sim=3DRandomS= imilarityProvider(queryNorm=3Dtrue,coord=3Dyes): {}, locale=3Des_CL, timezo= ne=3DAfrica/Lubumbashi [junit4:junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.7.0_21 = (64-bit)/cpus=3D2,threads=3D1,free=3D172068232,total=3D357433344 [junit4:junit4] 2> NOTE: All tests run in this JVM: [BadCopyFieldTest, Up= dateParamsTest, TestFastLRUCache, TestDefaultSimilarityFactory, QueryEquali= tyTest, SyncSliceTest, TestQueryTypes, TestReload, SolrCmdDistributorTest, = XmlUpdateRequestHandlerTest, TestPseudoReturnFields, IndexBasedSpellChecker= Test, TimeZoneUtilsTest, TestAnalyzedSuggestions, TestLRUCache, RecoveryZkT= est, ShardRoutingCustomTest, CoreContainerCoreInitFailuresTest, CurrencyFie= ldOpenExchangeTest, UpdateRequestProcessorFactoryTest, TestSweetSpotSimilar= ityFactory, TermsComponentTest, SolrTestCaseJ4Test, TestUpdate, OpenCloseCo= reStressTest, TestDefaultSearchFieldResource, TestManagedSchemaFieldResourc= e, TestJmxMonitoredMap, TestGroupingSearch, TestBinaryField, HighlighterCon= figTest, TestStressVersions, ChaosMonkeyNothingIsSafeTest, TestCSVLoader, T= estSchemaSimilarityResource, TestSerializedLuceneMatchVersion, TestLMJeline= kMercerSimilarityFactory, TestCoreDiscovery, TestLFUCache, MoreLikeThisHand= lerTest, PolyFieldTest, TestConfig, SortByFunctionTest, TestPropInject, Sta= tsComponentTest, DocValuesMultiTest, TestDFRSimilarityFactory, LukeRequestH= andlerTest, UUIDFieldTest, TestSystemIdResolver, SpatialFilterTest, DirectU= pdateHandlerTest, PrimitiveFieldTypeTest, AnalysisAfterCoreReloadTest, Sugg= esterWFSTTest, UnloadDistributedZkTest, BasicFunctionalityTest, TestCSVResp= onseWriter, TestSearchPerf, DirectSolrSpellCheckerTest, FastVectorHighlight= erTest, BinaryUpdateRequestHandlerTest, TestDistributedSearch, ZkNodePropsT= est, TestFieldTypeCollectionResource, URLClassifyProcessorTest, DocValuesTe= st, TestBM25SimilarityFactory, TestCodecSupport, TestRecovery, TestFaceting= , TestWordDelimiterFilterFactory, PathHierarchyTokenizerFactoryTest, TestLu= ceneMatchVersion, NotRequiredUniqueKeyTest, TestSchemaNameResource, ChaosMo= nkeyShardSplitTest, RequiredFieldsTest, SystemInfoHandlerTest, RequestHandl= ersTest, PrimUtilsTest, SuggesterTSTTest, CachingDirectoryFactoryTest, Test= MultiCoreConfBootstrap, DistributedQueryElevationComponentTest, TestFilteri= ng, TestFastWriter, ZkControllerTest, TestSuggestSpellingConverter, MultiTe= rmTest, TestSolr4Spatial, TestAddFieldRealTimeGet, TestSolrXml, ZkCLITest, = CSVRequestHandlerTest, TestSolrJ, RAMDirectoryFactoryTest, TestZkChroot, Ch= aosMonkeySafeLeaderTest, TestSolrQueryParserResource, TestFoldingMultitermQ= uery, MinimalSchemaTest, OutputWriterTest, LeaderElectionIntegrationTest, D= isMaxRequestHandlerTest, ScriptEngineTest, SuggesterTest, QueryElevationCom= ponentTest, TestHashPartitioner, SolrCoreTest, JsonLoaderTest, TestLMDirich= letSimilarityFactory, TestRandomFaceting, TestSolrIndexConfig, ResourceLoad= erTest, SearchHandlerTest, OverseerTest, TestQuerySenderListener, TestLazyC= ores, TestArbitraryIndexDir, TestUniqueKeyFieldResource, TestComponentsName= , TestIndexSearcher, LoggingHandlerTest, TestSchemaResource, DistanceFuncti= onTest, SolrRequestParserTest, TestFieldCollectionResource, TestDocSet, Bas= icDistributedZk2Test, TestIBSimilarityFactory, PingRequestHandlerTest, Test= DistributedGrouping, TestRTGBase, TestSolrXMLSerializer, FullSolrCloudDistr= ibCmdsTest, TestQueryUtils, ExternalFileFieldSortTest, IndexSchemaRuntimeFi= eldTest, DOMUtilTest, CurrencyFieldXmlFileTest, BasicDistributedZkTest, Col= lectionsAPIDistributedZkTest] [junit4:junit4] Completed in 322.43s, 1 test, 1 failure <<< FAILURES! [...truncated 454 lines...] BUILD FAILED C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:377: Th= e following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:357: Th= e following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:39: The= following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:18= 1: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build= .xml:437: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-bui= ld.xml:1240: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-bui= ld.xml:884: There were test failures: 295 suites, 1231 tests, 1 failure, 18= ignored (12 assumptions) Total time: 65 minutes 42 seconds Build step 'Invoke Ant' marked build as failure Description set: Java: 64bit/jdk1.7.0_21 -XX:+UseCompressedOops -XX:+UsePar= allelGC Archiving artifacts Recording test results Email was triggered for: Failure Sending email for trigger: Failure ------=_Part_188_1300250285.1368896172292 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_188_1300250285.1368896172292--