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 D17CAD8D5 for ; Thu, 20 Sep 2012 19:06:47 +0000 (UTC) Received: (qmail 39212 invoked by uid 500); 20 Sep 2012 19:06:46 -0000 Delivered-To: apmail-lucene-dev-archive@lucene.apache.org Received: (qmail 39177 invoked by uid 500); 20 Sep 2012 19:06:46 -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 39167 invoked by uid 99); 20 Sep 2012 19:06:45 -0000 Received: from nike.apache.org (HELO nike.apache.org) (192.87.106.230) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 20 Sep 2012 19:06:45 +0000 X-ASF-Spam-Status: No, hits=-2000.0 required=5.0 tests=ALL_TRUSTED,NORMAL_HTTP_TO_IP,WEIRD_PORT X-Spam-Check-By: apache.org Received: from [140.211.11.8] (HELO aegis.apache.org) (140.211.11.8) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 20 Sep 2012 19:06:25 +0000 Received: from aegis.apache.org (localhost [127.0.0.1]) by aegis.apache.org (Postfix) with ESMTP id C3B9FC00F0 for ; Thu, 20 Sep 2012 19:06:02 +0000 (UTC) Date: Thu, 20 Sep 2012 19:05:52 +0000 (UTC) From: Apache Jenkins Server To: dev@lucene.apache.org Message-ID: <92339832.837.1348167962737.JavaMail.hudson@aegis.apache.org> In-Reply-To: <1715272444.793.1348154416935.JavaMail.hudson@aegis.apache.org> References: <1715272444.793.1348154416935.JavaMail.hudson@aegis.apache.org> Subject: [JENKINS] Lucene-Solr-Tests-4.x-Java6 - Build # 681 - Still Failing MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_836_1397572148.1348167952450" ------=_Part_836_1397572148.1348167952450 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: https://builds.apache.org/job/Lucene-Solr-Tests-4.x-Java6/681/ 1 tests failed. FAILED: org.apache.solr.cloud.SyncSliceTest.testDistribSearch Error Message: No live SolrServers available to handle this request Stack Trace: org.apache.solr.client.solrj.SolrServerException: No live SolrServers avail= able to handle this request =09at __randomizedtesting.SeedInfo.seed([95FEC9025FEEDA01:1418471A28B1BA3D]= :0) =09at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolr= Server.java:323) =09at org.apache.solr.client.solrj.impl.CloudSolrServer.request(CloudSolrSe= rver.java:257) =09at org.apache.solr.client.solrj.request.QueryRequest.process(QueryReques= t.java:90) =09at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:324) =09at org.apache.solr.cloud.AbstractFullDistribZkTestBase.checkShardConsist= ency(AbstractFullDistribZkTestBase.java:909) =09at org.apache.solr.cloud.SyncSliceTest.doTest(SyncSliceTest.java:226) =09at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseD= istributedSearchTestCase.java:695) =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:616) =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:45) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRul= e$1.evaluate(SystemPropertiesInvariantRule.java:55) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:48) =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:45) =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:679) Build Log: [...truncated 7238 lines...] [junit4:junit4] Suite: org.apache.solr.cloud.SyncSliceTest [junit4:junit4] 2> 6 T1469 oas.SolrTestCaseJ4.setUp ###Starting testDistr= ibSearch [junit4:junit4] 2> Creating dataDir: /usr/home/hudson/hudson-slave/worksp= ace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-Syn= cSliceTest-1348166975070 [junit4:junit4] 2> 8 T1469 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4:junit4] 2> 11 T1470 oazs.ZooKeeperServer.setTickTime tickTime set= to 1000 [junit4:junit4] 2> 12 T1470 oazs.NIOServerCnxn$Factory. binding to = port 0.0.0.0/0.0.0.0:0 [junit4:junit4] 2> 12 T1470 oazsp.FileTxnSnapLog.save Snapshotting: 0 [junit4:junit4] 2> 109 T1469 oasc.ZkTestServer.run start zk server on por= t:47624 [junit4:junit4] 2> 109 T1469 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:47624 sessionTimeout=3D10000 watcher=3Dorg= .apache.solr.common.cloud.ConnectionManager@52528399 [junit4:junit4] 2> 110 T1475 oaz.ClientCnxn$SendThread.startConnect Openi= ng socket connection to server /127.0.0.1:47624 [junit4:junit4] 2> 110 T1469 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 111 T1475 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to localhost/127.0.0.1:47624, initiating sessio= n [junit4:junit4] 2> 111 T1471 oazs.NIOServerCnxn$Factory.run Accepted sock= et connection from /140.211.11.196:47625 [junit4:junit4] 2> 111 T1471 oazs.NIOServerCnxn.readConnectRequest Client= attempting to establish new session at /140.211.11.196:47625 [junit4:junit4] 2> 112 T1473 oazsp.FileTxnLog.append Creating new log fil= e: log.1 [junit4:junit4] 2> 114 T1473 oazs.NIOServerCnxn.finishSessionInit Establi= shed session 0x139e5037e640000 with negotiated timeout 10000 for client /14= 0.211.11.196:47625 [junit4:junit4] 2> 114 T1475 oaz.ClientCnxn$SendThread.readConnectResult = Session establishment complete on server localhost/127.0.0.1:47624, session= id =3D 0x139e5037e640000, negotiated timeout =3D 10000 [junit4:junit4] 2> 114 T1476 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@52528399 name:ZooKeeperConnectio= n Watcher:127.0.0.1:47624 got event WatchedEvent state:SyncConnected type:N= one path:null path:null type:None [junit4:junit4] 2> 114 T1469 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 115 T1469 oascc.SolrZkClient.makePath makePath: /solr [junit4:junit4] 2> 117 T1474 oazs.PrepRequestProcessor.pRequest Processed= session termination for sessionid: 0x139e5037e640000 [junit4:junit4] 2> 119 T1476 oaz.ClientCnxn$EventThread.run EventThread s= hut down [junit4:junit4] 2> 119 T1469 oaz.ZooKeeper.close Session: 0x139e5037e6400= 00 closed [junit4:junit4] 2> 119 T1471 oazs.NIOServerCnxn.closeSock Closed socket c= onnection for client /140.211.11.196:47625 which had sessionid 0x139e5037e6= 40000 [junit4:junit4] 2> 119 T1469 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:47624/solr sessionTimeout=3D10000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@56f87a20 [junit4:junit4] 2> 120 T1477 oaz.ClientCnxn$SendThread.startConnect Openi= ng socket connection to server /127.0.0.1:47624 [junit4:junit4] 2> 120 T1469 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 121 T1477 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to localhost/127.0.0.1:47624, initiating sessio= n [junit4:junit4] 2> 121 T1471 oazs.NIOServerCnxn$Factory.run Accepted sock= et connection from /140.211.11.196:47626 [junit4:junit4] 2> 121 T1471 oazs.NIOServerCnxn.readConnectRequest Client= attempting to establish new session at /140.211.11.196:47626 [junit4:junit4] 2> 122 T1473 oazs.NIOServerCnxn.finishSessionInit Establi= shed session 0x139e5037e640001 with negotiated timeout 10000 for client /14= 0.211.11.196:47626 [junit4:junit4] 2> 122 T1477 oaz.ClientCnxn$SendThread.readConnectResult = Session establishment complete on server localhost/127.0.0.1:47624, session= id =3D 0x139e5037e640001, negotiated timeout =3D 10000 [junit4:junit4] 2> 123 T1478 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@56f87a20 name:ZooKeeperConnectio= n Watcher:127.0.0.1:47624/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 123 T1469 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 123 T1469 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1 [junit4:junit4] 2> 177 T1469 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1/shards [junit4:junit4] 2> 184 T1469 oascc.SolrZkClient.makePath makePath: /colle= ctions/control_collection [junit4:junit4] 2> 187 T1469 oascc.SolrZkClient.makePath makePath: /colle= ctions/control_collection/shards [junit4:junit4] 2> 190 T1469 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/so= lr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/s= olrconfig.xml [junit4:junit4] 2> 191 T1469 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/solrconfig.xml [junit4:junit4] 2> 210 T1469 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/so= lr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schem= a.xml [junit4:junit4] 2> 211 T1469 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/schema.xml [junit4:junit4] 2> 330 T1469 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/so= lr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/s= olrconfig.xml [junit4:junit4] 2> 331 T1469 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/solrconfig.xml [junit4:junit4] 2> 334 T1469 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/so= lr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/st= opwords.txt [junit4:junit4] 2> 335 T1469 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/stopwords.txt [junit4:junit4] 2> 348 T1469 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/so= lr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/pr= otwords.txt [junit4:junit4] 2> 349 T1469 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/protwords.txt [junit4:junit4] 2> 352 T1469 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/so= lr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/cur= rency.xml [junit4:junit4] 2> 352 T1469 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/currency.xml [junit4:junit4] 2> 356 T1469 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/so= lr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /confi= gs/conf1/open-exchange-rates.json [junit4:junit4] 2> 357 T1469 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/open-exchange-rates.json [junit4:junit4] 2> 360 T1469 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/so= lr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /co= nfigs/conf1/mapping-ISOLatin1Accent.txt [junit4:junit4] 2> 360 T1469 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/mapping-ISOLatin1Accent.txt [junit4:junit4] 2> 364 T1469 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/so= lr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1= /old_synonyms.txt [junit4:junit4] 2> 365 T1469 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/old_synonyms.txt [junit4:junit4] 2> 372 T1469 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/so= lr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/syn= onyms.txt [junit4:junit4] 2> 373 T1469 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/synonyms.txt [junit4:junit4] 2> 383 T1474 oazs.PrepRequestProcessor.pRequest Processed= session termination for sessionid: 0x139e5037e640001 [junit4:junit4] 2> 390 T1478 oaz.ClientCnxn$EventThread.run EventThread s= hut down [junit4:junit4] 2> 390 T1471 oazs.NIOServerCnxn.closeSock Closed socket c= onnection for client /140.211.11.196:47626 which had sessionid 0x139e5037e6= 40001 [junit4:junit4] 2> 390 T1469 oaz.ZooKeeper.close Session: 0x139e5037e6400= 01 closed [junit4:junit4] 2> 393 T1469 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 396 T1469 oejs.AbstractConnector.doStart Started Socke= tConnector@0.0.0.0:47632 [junit4:junit4] 2> 397 T1469 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 397 T1469 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr [junit4:junit4] 2> 398 T1469 oasc.SolrResourceLoader. new SolrResou= rceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/' [junit4:junit4] 2> 446 T1469 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() [junit4:junit4] 2> 446 T1469 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 447 T1469 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr [junit4:junit4] 2> 447 T1469 oasc.CoreContainer$Initializer.initialize lo= oking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tes= ts-4.x-Java6/solr/build/solr-core/test-files/solr/solr.xml [junit4:junit4] 2> 447 T1469 oasc.CoreContainer. New CoreContainer = 110983361 [junit4:junit4] 2> 448 T1469 oasc.CoreContainer.load Loading CoreContaine= r using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tes= ts-4.x-Java6/solr/build/solr-core/test-files/solr/' [junit4:junit4] 2> 449 T1469 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-So= lr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/' [junit4:junit4] 2> 477 T1469 oasc.CoreContainer.load Registering Log List= ener [junit4:junit4] 2> 494 T1469 oasc.CoreContainer.initZooKeeper Zookeeper c= lient=3D127.0.0.1:47624/solr [junit4:junit4] 2> 495 T1469 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:47624/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@69cf291a [junit4:junit4] 2> 496 T1488 oaz.ClientCnxn$SendThread.startConnect Openi= ng socket connection to server /127.0.0.1:47624 [junit4:junit4] 2> 496 T1469 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 497 T1488 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to localhost/127.0.0.1:47624, initiating sessio= n [junit4:junit4] 2> 497 T1471 oazs.NIOServerCnxn$Factory.run Accepted sock= et connection from /140.211.11.196:47633 [junit4:junit4] 2> 498 T1471 oazs.NIOServerCnxn.readConnectRequest Client= attempting to establish new session at /140.211.11.196:47633 [junit4:junit4] 2> 499 T1473 oazs.NIOServerCnxn.finishSessionInit Establi= shed session 0x139e5037e640002 with negotiated timeout 5000 for client /140= .211.11.196:47633 [junit4:junit4] 2> 500 T1488 oaz.ClientCnxn$SendThread.readConnectResult = Session establishment complete on server localhost/127.0.0.1:47624, session= id =3D 0x139e5037e640002, negotiated timeout =3D 5000 [junit4:junit4] 2> 500 T1489 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@69cf291a name:ZooKeeperConnectio= n Watcher:127.0.0.1:47624/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 500 T1469 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 502 T1474 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139e5037e640002 type:crea= te cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 512 T1469 oascc.SolrZkClient.makePath makePath: /live_= nodes [junit4:junit4] 2> 514 T1469 oasc.ZkController.createEphemeralLiveNode Re= gister node as live in ZooKeeper:/live_nodes/127.0.0.1:47632_solr [junit4:junit4] 2> 515 T1474 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139e5037e640002 type:dele= te cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/live_nodes/127.0.0.1:47632_solr Error:KeeperErrorCode =3D NoNode for = /solr/live_nodes/127.0.0.1:47632_solr [junit4:junit4] 2> 516 T1469 oascc.SolrZkClient.makePath makePath: /live_= nodes/127.0.0.1:47632_solr [junit4:junit4] 2> 524 T1469 oashc.HttpShardHandlerFactory.getParameter S= etting socketTimeout to: 0 [junit4:junit4] 2> 525 T1469 oashc.HttpShardHandlerFactory.getParameter S= etting urlScheme to: http:// [junit4:junit4] 2> 526 T1469 oashc.HttpShardHandlerFactory.getParameter S= etting connTimeout to: 0 [junit4:junit4] 2> 526 T1469 oashc.HttpShardHandlerFactory.getParameter S= etting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 527 T1469 oashc.HttpShardHandlerFactory.getParameter S= etting corePoolSize to: 0 [junit4:junit4] 2> 528 T1469 oashc.HttpShardHandlerFactory.getParameter S= etting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 528 T1469 oashc.HttpShardHandlerFactory.getParameter S= etting maxThreadIdleTime to: 5 [junit4:junit4] 2> 529 T1469 oashc.HttpShardHandlerFactory.getParameter S= etting sizeOfQueue to: -1 [junit4:junit4] 2> 530 T1469 oashc.HttpShardHandlerFactory.getParameter S= etting fairnessPolicy to: false [junit4:junit4] 2> 530 T1469 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&s= ocketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 554 T1469 oascc.SolrZkClient.makePath makePath: /overs= eer_elect/election [junit4:junit4] 2> 567 T1474 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139e5037e640002 type:dele= te cxid:0x15 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer_elect/leader Error:KeeperErrorCode =3D NoNode for /solr/ove= rseer_elect/leader [junit4:junit4] 2> 568 T1469 oascc.SolrZkClient.makePath makePath: /overs= eer_elect/leader [junit4:junit4] 2> 571 T1469 oasc.Overseer.start Overseer (id=3D883534708= 78580738-127.0.0.1:47632_solr-n_0000000000) starting [junit4:junit4] 2> 572 T1474 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139e5037e640002 type:crea= te cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 579 T1474 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139e5037e640002 type:crea= te cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 581 T1474 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139e5037e640002 type:crea= te cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 582 T1474 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139e5037e640002 type:crea= te cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 591 T1491 oasc.OverseerCollectionProcessor.run Process= current queue of collection creations [junit4:junit4] 2> 592 T1469 oascc.SolrZkClient.makePath makePath: /clust= erstate.json [junit4:junit4] 2> 617 T1469 oascc.ZkStateReader.createClusterStateWatche= rsAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 620 T1490 oasc.Overseer$ClusterStateUpdater.run Starti= ng to work on the main queue [junit4:junit4] 2> 628 T1469 oasc.CoreContainer.create Creating SolrCore = 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1 [junit4:junit4] 2> 628 T1469 oasc.ZkController.createCollectionZkNode Che= ck for collection zkNode:control_collection [junit4:junit4] 2> 630 T1469 oasc.ZkController.createCollectionZkNode Col= lection zkNode exists [junit4:junit4] 2> 630 T1469 oasc.ZkController.readConfigName Load collec= tion config from:/collections/control_collection [junit4:junit4] 2> 632 T1469 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-So= lr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/' [junit4:junit4] 2> 634 T1469 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-J= ava6/solr/build/solr-core/test-files/solr/collection1/lib/README' to classl= oader [junit4:junit4] 2> 636 T1469 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-J= ava6/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to clas= sloader [junit4:junit4] 2> 702 T1469 oasc.SolrConfig. Using Lucene MatchVer= sion: LUCENE_40 [junit4:junit4] 2> 796 T1469 oasc.SolrConfig. Loaded SolrConfig: so= lrconfig.xml [junit4:junit4] 2> 897 T1469 oass.IndexSchema.readSchema Reading Solr Sch= ema [junit4:junit4] 2> 905 T1469 oass.IndexSchema.readSchema Schema name=3Dte= st [junit4:junit4] 2> 1557 T1469 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 1565 T1469 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 1569 T1469 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 1580 T1469 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 1585 T1469 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 1588 T1469 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 1589 T1469 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 1590 T1469 oasc.SolrCore. [collection1] Opening = new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4= .x-Java6/solr/build/solr-core/test-files/solr/collection1/, dataDir=3D./org= .apache.solr.cloud.SyncSliceTest-1348166975070/control/data/ [junit4:junit4] 2> 1590 T1469 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@1702679a [junit4:junit4] 2> 1591 T1469 oasc.SolrCore.getNewIndexDir WARNING New in= dex directory detected: old=3Dnull new=3D./org.apache.solr.cloud.SyncSliceT= est-1348166975070/control/data/index/ [junit4:junit4] 2> 1592 T1469 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory './org.apache.solr.cloud.SyncSliceTest-134816697507= 0/control/data/index' doesn't exist. Creating new index... [junit4:junit4] 2> 1593 T1469 oasc.CachingDirectoryFactory.get return new= directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.= x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-= 1348166975070/control/data/index forceNew:false [junit4:junit4] 2> 1611 T1469 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/control/data/index,segFN=3Dsegments_1,= generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 1611 T1469 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1 [junit4:junit4] 2> 1612 T1469 oasc.SolrCore.initWriters created xml: solr= .XMLResponseWriter [junit4:junit4] 2> 1613 T1469 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 1614 T1469 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe-allfields" [junit4:junit4] 2> 1614 T1469 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 1615 T1469 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "u= niq-fields" [junit4:junit4] 2> 1615 T1469 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 1615 T1469 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 1616 T1469 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 1616 T1469 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 1617 T1469 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 1617 T1469 oasc.RequestHandlers.initHandlersFromConfig= created dismax: solr.SearchHandler [junit4:junit4] 2> 1617 T1469 oasc.RequestHandlers.initHandlersFromConfig= created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 1618 T1469 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 1618 T1469 oasc.RequestHandlers.initHandlersFromConfig= created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 1619 T1469 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 1619 T1469 oasc.RequestHandlers.initHandlersFromConfig= created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 1619 T1469 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 1620 T1469 oasc.RequestHandlers.initHandlersFromConfig= created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 1620 T1469 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 1621 T1469 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 1621 T1469 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 1621 T1469 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.= SearchHandler [junit4:junit4] 2> 1622 T1469 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 1622 T1469 oasc.RequestHandlers.initHandlersFromConfig= created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 1623 T1469 oasc.RequestHandlers.initHandlersFromConfig= created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 1623 T1469 oasc.RequestHandlers.initHandlersFromConfig= created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 1626 T1469 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 1629 T1469 oasc.SolrCore.initDeprecatedSupport WARNING= solrconfig.xml uses deprecated , Please update your c= onfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 1629 T1469 oasc.SolrCore.initDeprecatedSupport WARNING= adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS= .TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT,= CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT] [junit4:junit4] 2> 1633 T1469 oass.SolrIndexSearcher. Opening Searc= her@1e6d682d main [junit4:junit4] 2> 1634 T1469 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 1635 T1469 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 1635 T1469 oashc.SpellCheckComponent.inform Initializi= ng spell checkers [junit4:junit4] 2> 1656 T1469 oass.DirectSolrSpellChecker.init init: {nam= e=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLen= gth=3D3} [junit4:junit4] 2> 1711 T1492 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@1e6d682d main{StandardDirectoryReader(se= gments_1:1)} [junit4:junit4] 2> 1715 T1474 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139e5037e640002 type:cre= ate cxid:0x56 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer/queue Error:KeeperErrorCode =3D NoNode for /solr/overseer/= queue [junit4:junit4] 2> 1726 T1469 oasc.CoreContainer.register registering cor= e: collection1 [junit4:junit4] 2> 1726 T1469 oasc.ZkController.register Register shard -= core:collection1 address:http://127.0.0.1:47632/solr shardId:control_shard [junit4:junit4] 2> 1726 T1469 oascc.SolrZkClient.makePath makePath: /coll= ections/control_collection/leader_elect/control_shard/election [junit4:junit4] 2> 1741 T1474 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139e5037e640002 type:del= ete cxid:0x65 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/collections/control_collection/leaders Error:KeeperErrorCode =3D No= Node for /solr/collections/control_collection/leaders [junit4:junit4] 2> 1742 T1469 oasc.ShardLeaderElectionContext.runLeaderPr= ocess Running the leader process. [junit4:junit4] 2> 1742 T1474 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139e5037e640002 type:cre= ate cxid:0x66 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 2127 T1490 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 2128 T1474 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139e5037e640002 type:cre= ate cxid:0x6d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer/queue-work Error:KeeperErrorCode =3D NoNode for /solr/over= seer/queue-work [junit4:junit4] 2> 2153 T1489 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 2245 T1469 oasc.ShardLeaderElectionContext.waitForRepl= icasToComeUp Enough replicas found to continue. [junit4:junit4] 2> 2245 T1469 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I may be the new leader - try and sync [junit4:junit4] 2> 2246 T1469 oasc.SyncStrategy.sync Sync replicas to htt= p://127.0.0.1:47632/solr/collection1/ [junit4:junit4] 2> 2246 T1469 oasc.SyncStrategy.syncReplicas Sync Success= - now sync replicas to me [junit4:junit4] 2> 2246 T1469 oasc.SyncStrategy.syncToMe http://127.0.0.1= :47632/solr/collection1/ has no replicas [junit4:junit4] 2> 2247 T1469 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I am the new leader: http://127.0.0.1:47632/solr/collection1/ [junit4:junit4] 2> 2247 T1469 oascc.SolrZkClient.makePath makePath: /coll= ections/control_collection/leaders/control_shard [junit4:junit4] 2> 2260 T1474 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139e5037e640002 type:cre= ate cxid:0x8b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 2660 T1490 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 2671 T1489 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 2677 T1469 oasc.ZkController.register We are http://12= 7.0.0.1:47632/solr/collection1/ and leader is http://127.0.0.1:47632/solr/c= ollection1/ [junit4:junit4] 2> 2677 T1469 oasc.ZkController.register No LogReplay nee= ded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:47632/solr [junit4:junit4] 2> 2678 T1469 oasc.ZkController.checkRecovery I am the le= ader, no recovery necessary [junit4:junit4] 2> 2680 T1469 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 2681 T1469 oass.SolrDispatchFilter.init user.dir=3D/us= r/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build= /solr-core/test/J1 [junit4:junit4] 2> 2681 T1469 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() done [junit4:junit4] 2> 2681 T1469 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fo= llowRedirects=3Dfalse [junit4:junit4] 2> 3177 T1490 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 3191 T1489 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 3218 T1469 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 3221 T1469 oejs.AbstractConnector.doStart Started Sock= etConnector@0.0.0.0:47640 [junit4:junit4] 2> 3222 T1469 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 3222 T1469 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty1-1= 348166977757 [junit4:junit4] 2> 3223 T1469 oasc.SolrResourceLoader. new SolrReso= urceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/= Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty1-= 1348166977757/' [junit4:junit4] 2> 3258 T1469 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() [junit4:junit4] 2> 3259 T1469 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 3259 T1469 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty1-1= 348166977757 [junit4:junit4] 2> 3259 T1469 oasc.CoreContainer$Initializer.initialize l= ooking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Te= sts-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty1-1348166977757/= solr.xml [junit4:junit4] 2> 3260 T1469 oasc.CoreContainer. New CoreContainer= 1155221157 [junit4:junit4] 2> 3260 T1469 oasc.CoreContainer.load Loading CoreContain= er using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Te= sts-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty1-1348166977757/= ' [junit4:junit4] 2> 3261 T1469 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty1-13481669= 77757/' [junit4:junit4] 2> 3286 T1469 oasc.CoreContainer.load Registering Log Lis= tener [junit4:junit4] 2> 3301 T1469 oasc.CoreContainer.initZooKeeper Zookeeper = client=3D127.0.0.1:47624/solr [junit4:junit4] 2> 3302 T1469 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:47624/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@676b8a78 [junit4:junit4] 2> 3302 T1502 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:47624 [junit4:junit4] 2> 3302 T1469 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 3304 T1502 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost/127.0.0.1:47624, initiating sessi= on [junit4:junit4] 2> 3304 T1471 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /140.211.11.196:47641 [junit4:junit4] 2> 3304 T1471 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /140.211.11.196:47641 [junit4:junit4] 2> 3306 T1473 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x139e5037e640003 with negotiated timeout 5000 for client /14= 0.211.11.196:47641 [junit4:junit4] 2> 3306 T1502 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server localhost/127.0.0.1:47624, sessio= nid =3D 0x139e5037e640003, negotiated timeout =3D 5000 [junit4:junit4] 2> 3306 T1503 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@676b8a78 name:ZooKeeperConnecti= on Watcher:127.0.0.1:47624/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4:junit4] 2> 3306 T1469 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 3307 T1474 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139e5037e640003 type:cre= ate cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 3309 T1474 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139e5037e640003 type:cre= ate cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 3317 T1469 oasc.ZkController.createEphemeralLiveNode R= egister node as live in ZooKeeper:/live_nodes/127.0.0.1:47640_solr [junit4:junit4] 2> 3318 T1474 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139e5037e640003 type:del= ete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/live_nodes/127.0.0.1:47640_solr Error:KeeperErrorCode =3D NoNode for= /solr/live_nodes/127.0.0.1:47640_solr [junit4:junit4] 2> 3320 T1469 oascc.SolrZkClient.makePath makePath: /live= _nodes/127.0.0.1:47640_solr [junit4:junit4] 2> 3322 T1489 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 3323 T1469 oashc.HttpShardHandlerFactory.getParameter = Setting socketTimeout to: 0 [junit4:junit4] 2> 3324 T1469 oashc.HttpShardHandlerFactory.getParameter = Setting urlScheme to: http:// [junit4:junit4] 2> 3324 T1469 oashc.HttpShardHandlerFactory.getParameter = Setting connTimeout to: 0 [junit4:junit4] 2> 3324 T1469 oashc.HttpShardHandlerFactory.getParameter = Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 3325 T1469 oashc.HttpShardHandlerFactory.getParameter = Setting corePoolSize to: 0 [junit4:junit4] 2> 3325 T1469 oashc.HttpShardHandlerFactory.getParameter = Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 3326 T1469 oashc.HttpShardHandlerFactory.getParameter = Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 3326 T1469 oashc.HttpShardHandlerFactory.getParameter = Setting sizeOfQueue to: -1 [junit4:junit4] 2> 3327 T1469 oashc.HttpShardHandlerFactory.getParameter = Setting fairnessPolicy to: false [junit4:junit4] 2> 3327 T1469 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&= socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 3343 T1469 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 3349 T1469 oasc.CoreContainer.create Creating SolrCore= 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty1-1= 348166977757/collection1 [junit4:junit4] 2> 3350 T1469 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:collection1 [junit4:junit4] 2> 3351 T1469 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 3351 T1469 oasc.ZkController.readConfigName Load colle= ction config from:/collections/collection1 [junit4:junit4] 2> 3353 T1469 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty1-13481669= 77757/collection1/' [junit4:junit4] 2> 3354 T1469 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-= Java6/solr/build/solr-core/test/J1/solrtest-jetty1-1348166977757/collection= 1/lib/README' to classloader [junit4:junit4] 2> 3355 T1469 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-= Java6/solr/build/solr-core/test/J1/solrtest-jetty1-1348166977757/collection= 1/lib/classes/' to classloader [junit4:junit4] 2> 3409 T1469 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_40 [junit4:junit4] 2> 3501 T1469 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 3602 T1469 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 3609 T1469 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 4237 T1469 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 4249 T1469 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 4254 T1469 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 4269 T1469 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 4274 T1469 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 4278 T1469 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 4280 T1469 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 4281 T1469 oasc.SolrCore. [collection1] Opening = new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4= .x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty1-1348166977757/colle= ction1/, dataDir=3D./org.apache.solr.cloud.SyncSliceTest-1348166975070/jett= y1/ [junit4:junit4] 2> 4282 T1469 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@1702679a [junit4:junit4] 2> 4283 T1469 oasc.SolrCore.getNewIndexDir WARNING New in= dex directory detected: old=3Dnull new=3D./org.apache.solr.cloud.SyncSliceT= est-1348166975070/jetty1/index/ [junit4:junit4] 2> 4284 T1469 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory './org.apache.solr.cloud.SyncSliceTest-134816697507= 0/jetty1/index' doesn't exist. Creating new index... [junit4:junit4] 2> 4285 T1469 oasc.CachingDirectoryFactory.get return new= directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.= x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-= 1348166975070/jetty1/index forceNew:false [junit4:junit4] 2> 4315 T1469 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/jetty1/index,segFN=3Dsegments_1,genera= tion=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 4316 T1469 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1 [junit4:junit4] 2> 4317 T1469 oasc.SolrCore.initWriters created xml: solr= .XMLResponseWriter [junit4:junit4] 2> 4319 T1469 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 4320 T1469 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe-allfields" [junit4:junit4] 2> 4320 T1469 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 4321 T1469 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "u= niq-fields" [junit4:junit4] 2> 4322 T1469 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 4323 T1469 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 4323 T1469 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 4324 T1469 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 4325 T1469 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 4326 T1469 oasc.RequestHandlers.initHandlersFromConfig= created dismax: solr.SearchHandler [junit4:junit4] 2> 4326 T1469 oasc.RequestHandlers.initHandlersFromConfig= created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 4327 T1469 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 4328 T1469 oasc.RequestHandlers.initHandlersFromConfig= created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 4328 T1469 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 4329 T1469 oasc.RequestHandlers.initHandlersFromConfig= created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 4330 T1469 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 4330 T1469 oasc.RequestHandlers.initHandlersFromConfig= created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 4331 T1469 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 4332 T1469 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 4332 T1469 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 4333 T1469 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.= SearchHandler [junit4:junit4] 2> 4334 T1469 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 4335 T1469 oasc.RequestHandlers.initHandlersFromConfig= created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 4335 T1469 oasc.RequestHandlers.initHandlersFromConfig= created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 4336 T1469 oasc.RequestHandlers.initHandlersFromConfig= created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 4340 T1469 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 4345 T1469 oasc.SolrCore.initDeprecatedSupport WARNING= solrconfig.xml uses deprecated , Please update your c= onfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 4346 T1469 oasc.SolrCore.initDeprecatedSupport WARNING= adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS= .TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT,= CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT] [junit4:junit4] 2> 4350 T1469 oass.SolrIndexSearcher. Opening Searc= her@4817216a main [junit4:junit4] 2> 4352 T1469 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 4352 T1469 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 4353 T1469 oashc.SpellCheckComponent.inform Initializi= ng spell checkers [junit4:junit4] 2> 4361 T1469 oass.DirectSolrSpellChecker.init init: {nam= e=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLen= gth=3D3} [junit4:junit4] 2> 4411 T1504 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@4817216a main{StandardDirectoryReader(se= gments_1:1)} [junit4:junit4] 2> 4700 T1490 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 4720 T1503 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 4720 T1489 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 4920 T1469 oasc.CoreContainer.register registering cor= e: collection1 [junit4:junit4] 2> 4920 T1469 oasc.ZkController.register Register shard -= core:collection1 address:http://127.0.0.1:47640/solr shardId:shard1 [junit4:junit4] 2> 4921 T1469 oascc.SolrZkClient.makePath makePath: /coll= ections/collection1/leader_elect/shard1/election [junit4:junit4] 2> 4948 T1474 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139e5037e640003 type:del= ete cxid:0x42 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/collections/collection1/leaders Error:KeeperErrorCode =3D NoNode fo= r /solr/collections/collection1/leaders [junit4:junit4] 2> 5032 T1469 oasc.ShardLeaderElectionContext.runLeaderPr= ocess Running the leader process. [junit4:junit4] 2> 5033 T1474 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139e5037e640003 type:cre= ate cxid:0x43 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 5057 T1469 oasc.ShardLeaderElectionContext.waitForRepl= icasToComeUp Enough replicas found to continue. [junit4:junit4] 2> 5058 T1469 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I may be the new leader - try and sync [junit4:junit4] 2> 5058 T1469 oasc.SyncStrategy.sync Sync replicas to htt= p://127.0.0.1:47640/solr/collection1/ [junit4:junit4] 2> 5059 T1469 oasc.SyncStrategy.syncReplicas Sync Success= - now sync replicas to me [junit4:junit4] 2> 5059 T1469 oasc.SyncStrategy.syncToMe http://127.0.0.1= :47640/solr/collection1/ has no replicas [junit4:junit4] 2> 5059 T1469 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I am the new leader: http://127.0.0.1:47640/solr/collection1/ [junit4:junit4] 2> 5060 T1469 oascc.SolrZkClient.makePath makePath: /coll= ections/collection1/leaders/shard1 [junit4:junit4] 2> 5109 T1474 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139e5037e640003 type:cre= ate cxid:0x4d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 5226 T1490 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 5336 T1503 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 5336 T1489 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 5354 T1469 oasc.ZkController.register We are http://12= 7.0.0.1:47640/solr/collection1/ and leader is http://127.0.0.1:47640/solr/c= ollection1/ [junit4:junit4] 2> 5354 T1469 oasc.ZkController.register No LogReplay nee= ded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:47640/solr [junit4:junit4] 2> 5355 T1469 oasc.ZkController.checkRecovery I am the le= ader, no recovery necessary [junit4:junit4] 2> 5373 T1469 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 5426 T1469 oass.SolrDispatchFilter.init user.dir=3D/us= r/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build= /solr-core/test/J1 [junit4:junit4] 2> 5427 T1469 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() done [junit4:junit4] 2> 5427 T1469 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fo= llowRedirects=3Dfalse [junit4:junit4] 2> 5902 T1469 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 5905 T1469 oejs.AbstractConnector.doStart Started Sock= etConnector@0.0.0.0:47653 [junit4:junit4] 2> 5905 T1469 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 5906 T1469 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty2-1= 348166980505 [junit4:junit4] 2> 5906 T1469 oasc.SolrResourceLoader. new SolrReso= urceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/= Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty2-= 1348166980505/' [junit4:junit4] 2> 5931 T1490 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 5942 T1469 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() [junit4:junit4] 2> 5943 T1469 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 5943 T1469 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty2-1= 348166980505 [junit4:junit4] 2> 5943 T1469 oasc.CoreContainer$Initializer.initialize l= ooking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Te= sts-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty2-1348166980505/= solr.xml [junit4:junit4] 2> 5944 T1469 oasc.CoreContainer. New CoreContainer= 1609785904 [junit4:junit4] 2> 5945 T1469 oasc.CoreContainer.load Loading CoreContain= er using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Te= sts-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty2-1348166980505/= ' [junit4:junit4] 2> 5945 T1469 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty2-13481669= 80505/' [junit4:junit4] 2> 5959 T1503 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 5959 T1489 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 5971 T1469 oasc.CoreContainer.load Registering Log Lis= tener [junit4:junit4] 2> 5985 T1469 oasc.CoreContainer.initZooKeeper Zookeeper = client=3D127.0.0.1:47624/solr [junit4:junit4] 2> 5986 T1469 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:47624/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@191d528d [junit4:junit4] 2> 5987 T1514 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:47624 [junit4:junit4] 2> 5987 T1469 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 5988 T1514 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost/127.0.0.1:47624, initiating sessi= on [junit4:junit4] 2> 5988 T1471 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /140.211.11.196:47655 [junit4:junit4] 2> 5988 T1471 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /140.211.11.196:47655 [junit4:junit4] 2> 5990 T1473 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x139e5037e640004 with negotiated timeout 5000 for client /14= 0.211.11.196:47655 [junit4:junit4] 2> 5990 T1514 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server localhost/127.0.0.1:47624, sessio= nid =3D 0x139e5037e640004, negotiated timeout =3D 5000 [junit4:junit4] 2> 5990 T1515 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@191d528d name:ZooKeeperConnecti= on Watcher:127.0.0.1:47624/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4:junit4] 2> 5991 T1469 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 5991 T1474 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139e5037e640004 type:cre= ate cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 5993 T1474 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139e5037e640004 type:cre= ate cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 5995 T1469 oasc.ZkController.createEphemeralLiveNode R= egister node as live in ZooKeeper:/live_nodes/127.0.0.1:47653_solr [junit4:junit4] 2> 5996 T1474 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139e5037e640004 type:del= ete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/live_nodes/127.0.0.1:47653_solr Error:KeeperErrorCode =3D NoNode for= /solr/live_nodes/127.0.0.1:47653_solr [junit4:junit4] 2> 6003 T1469 oascc.SolrZkClient.makePath makePath: /live= _nodes/127.0.0.1:47653_solr [junit4:junit4] 2> 6009 T1489 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 6009 T1503 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 6010 T1469 oashc.HttpShardHandlerFactory.getParameter = Setting socketTimeout to: 0 [junit4:junit4] 2> 6011 T1469 oashc.HttpShardHandlerFactory.getParameter = Setting urlScheme to: http:// [junit4:junit4] 2> 6011 T1469 oashc.HttpShardHandlerFactory.getParameter = Setting connTimeout to: 0 [junit4:junit4] 2> 6011 T1469 oashc.HttpShardHandlerFactory.getParameter = Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 6012 T1469 oashc.HttpShardHandlerFactory.getParameter = Setting corePoolSize to: 0 [junit4:junit4] 2> 6012 T1469 oashc.HttpShardHandlerFactory.getParameter = Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 6013 T1469 oashc.HttpShardHandlerFactory.getParameter = Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 6013 T1469 oashc.HttpShardHandlerFactory.getParameter = Setting sizeOfQueue to: -1 [junit4:junit4] 2> 6014 T1469 oashc.HttpShardHandlerFactory.getParameter = Setting fairnessPolicy to: false [junit4:junit4] 2> 6014 T1469 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&= socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 6031 T1469 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 6037 T1469 oasc.CoreContainer.create Creating SolrCore= 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty2-1= 348166980505/collection1 [junit4:junit4] 2> 6038 T1469 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:collection1 [junit4:junit4] 2> 6039 T1469 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 6039 T1469 oasc.ZkController.readConfigName Load colle= ction config from:/collections/collection1 [junit4:junit4] 2> 6041 T1469 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty2-13481669= 80505/collection1/' [junit4:junit4] 2> 6042 T1469 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-= Java6/solr/build/solr-core/test/J1/solrtest-jetty2-1348166980505/collection= 1/lib/README' to classloader [junit4:junit4] 2> 6043 T1469 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-= Java6/solr/build/solr-core/test/J1/solrtest-jetty2-1348166980505/collection= 1/lib/classes/' to classloader [junit4:junit4] 2> 6098 T1469 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_40 [junit4:junit4] 2> 6171 T1469 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 6272 T1469 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 6278 T1469 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 6901 T1469 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 6909 T1469 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 6912 T1469 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 6925 T1469 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 6929 T1469 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 6933 T1469 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 6934 T1469 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 6935 T1469 oasc.SolrCore. [collection1] Opening = new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4= .x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty2-1348166980505/colle= ction1/, dataDir=3D./org.apache.solr.cloud.SyncSliceTest-1348166975070/jett= y2/ [junit4:junit4] 2> 6935 T1469 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@1702679a [junit4:junit4] 2> 6936 T1469 oasc.SolrCore.getNewIndexDir WARNING New in= dex directory detected: old=3Dnull new=3D./org.apache.solr.cloud.SyncSliceT= est-1348166975070/jetty2/index/ [junit4:junit4] 2> 6937 T1469 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory './org.apache.solr.cloud.SyncSliceTest-134816697507= 0/jetty2/index' doesn't exist. Creating new index... [junit4:junit4] 2> 6938 T1469 oasc.CachingDirectoryFactory.get return new= directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.= x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-= 1348166975070/jetty2/index forceNew:false [junit4:junit4] 2> 6942 T1469 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/jetty2/index,segFN=3Dsegments_1,genera= tion=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 6942 T1469 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1 [junit4:junit4] 2> 6943 T1469 oasc.SolrCore.initWriters created xml: solr= .XMLResponseWriter [junit4:junit4] 2> 6945 T1469 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 6945 T1469 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe-allfields" [junit4:junit4] 2> 6945 T1469 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 6946 T1469 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "u= niq-fields" [junit4:junit4] 2> 6946 T1469 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 6946 T1469 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 6947 T1469 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 6947 T1469 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 6948 T1469 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 6948 T1469 oasc.RequestHandlers.initHandlersFromConfig= created dismax: solr.SearchHandler [junit4:junit4] 2> 6949 T1469 oasc.RequestHandlers.initHandlersFromConfig= created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 6949 T1469 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 6950 T1469 oasc.RequestHandlers.initHandlersFromConfig= created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 6950 T1469 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 6950 T1469 oasc.RequestHandlers.initHandlersFromConfig= created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 6951 T1469 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 6951 T1469 oasc.RequestHandlers.initHandlersFromConfig= created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 6952 T1469 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 6952 T1469 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 6953 T1469 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 6953 T1469 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.= SearchHandler [junit4:junit4] 2> 6954 T1469 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 6954 T1469 oasc.RequestHandlers.initHandlersFromConfig= created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 6955 T1469 oasc.RequestHandlers.initHandlersFromConfig= created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 6955 T1469 oasc.RequestHandlers.initHandlersFromConfig= created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 6958 T1469 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 6962 T1469 oasc.SolrCore.initDeprecatedSupport WARNING= solrconfig.xml uses deprecated , Please update your c= onfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 6963 T1469 oasc.SolrCore.initDeprecatedSupport WARNING= adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS= .TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT,= CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT] [junit4:junit4] 2> 6966 T1469 oass.SolrIndexSearcher. Opening Searc= her@5adfd4cd main [junit4:junit4] 2> 6967 T1469 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 6967 T1469 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 6968 T1469 oashc.SpellCheckComponent.inform Initializi= ng spell checkers [junit4:junit4] 2> 6974 T1469 oass.DirectSolrSpellChecker.init init: {nam= e=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLen= gth=3D3} [junit4:junit4] 2> 7020 T1516 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@5adfd4cd main{StandardDirectoryReader(se= gments_1:1)} [junit4:junit4] 2> 7468 T1490 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 7474 T1503 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 7474 T1515 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 7474 T1489 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 7525 T1469 oasc.CoreContainer.register registering cor= e: collection1 [junit4:junit4] 2> 7525 T1469 oasc.ZkController.register Register shard -= core:collection1 address:http://127.0.0.1:47653/solr shardId:shard1 [junit4:junit4] 2> 7529 T1469 oasc.ZkController.register We are http://12= 7.0.0.1:47653/solr/collection1/ and leader is http://127.0.0.1:47640/solr/c= ollection1/ [junit4:junit4] 2> 7529 T1469 oasc.ZkController.register No LogReplay nee= ded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:47653/solr [junit4:junit4] 2> 7530 T1469 oasc.ZkController.checkRecovery Core needs = to recover:collection1 [junit4:junit4] 2> 7530 T1469 oasu.DefaultSolrCoreState.doRecovery Runnin= g recovery - first canceling any ongoing recovery [junit4:junit4] 2> ASYNC NEW_CORE C91 name=3Dcollection1 org.apache.solr= .core.SolrCore@6d37cd63 url=3Dhttp://127.0.0.1:47653/solr/collection1 node= =3D127.0.0.1:47653_solr [junit4:junit4] 2> 7531 T1517 C91 P47653 oasc.RecoveryStrategy.run Starti= ng recovery process. core=3Dcollection1 recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 7532 T1517 C91 P47653 oasc.RecoveryStrategy.doRecovery= ###### startupVersions=3D[] [junit4:junit4] 2> 7531 T1469 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 7533 T1517 C91 P47653 oasc.RecoveryStrategy.doRecovery= Attempting to PeerSync from http://127.0.0.1:47640/solr/collection1/ core= =3Dcollection1 - recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 7533 T1517 C91 P47653 oasu.PeerSync.sync PeerSync: cor= e=3Dcollection1 url=3Dhttp://127.0.0.1:47653/solr START replicas=3D[http://= 127.0.0.1:47640/solr/collection1/] nUpdates=3D100 [junit4:junit4] 2> 7534 T1517 C91 P47653 oasu.PeerSync.sync WARNING no fr= ame of reference to tell of we've missed updates [junit4:junit4] 2> 7533 T1469 oass.SolrDispatchFilter.init user.dir=3D/us= r/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build= /solr-core/test/J1 [junit4:junit4] 2> 7541 T1517 C91 P47653 oasc.RecoveryStrategy.doRecovery= PeerSync Recovery was not successful - trying replication. core=3Dcollecti= on1 [junit4:junit4] 2> 7534 T1469 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() done [junit4:junit4] 2> 7541 T1517 C91 P47653 oasc.RecoveryStrategy.doRecovery= Starting Replication Recovery. core=3Dcollection1 [junit4:junit4] 2> 7541 T1517 C91 P47653 oascsi.HttpClientUtil.createClie= nt Creating new http client, config:maxConnections=3D128&maxConnectionsPerH= ost=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 7541 T1469 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fo= llowRedirects=3Dfalse [junit4:junit4] 2> ASYNC NEW_CORE C92 name=3Dcollection1 org.apache.solr= .core.SolrCore@1fa7ae9e url=3Dhttp://127.0.0.1:47640/solr/collection1 node= =3D127.0.0.1:47640_solr [junit4:junit4] 2> 7549 T1495 C92 P47640 REQ /get {distrib=3Dfalse&qt=3D/= get&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 7980 T1490 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 7985 T1489 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 7985 T1515 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 7985 T1503 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 8181 T1469 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 8184 T1469 oejs.AbstractConnector.doStart Started Sock= etConnector@0.0.0.0:47731 [junit4:junit4] 2> 8184 T1469 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 8185 T1469 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty3-1= 348166982623 [junit4:junit4] 2> 8185 T1469 oasc.SolrResourceLoader. new SolrReso= urceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/= Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty3-= 1348166982623/' [junit4:junit4] 2> 8227 T1469 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() [junit4:junit4] 2> 8228 T1469 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 8228 T1469 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty3-1= 348166982623 [junit4:junit4] 2> 8229 T1469 oasc.CoreContainer$Initializer.initialize l= ooking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Te= sts-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty3-1348166982623/= solr.xml [junit4:junit4] 2> 8229 T1469 oasc.CoreContainer. New CoreContainer= 117718869 [junit4:junit4] 2> 8230 T1469 oasc.CoreContainer.load Loading CoreContain= er using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Te= sts-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty3-1348166982623/= ' [junit4:junit4] 2> 8230 T1469 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty3-13481669= 82623/' [junit4:junit4] 2> 8267 T1469 oasc.CoreContainer.load Registering Log Lis= tener [junit4:junit4] 2> 8287 T1469 oasc.CoreContainer.initZooKeeper Zookeeper = client=3D127.0.0.1:47624/solr [junit4:junit4] 2> 8287 T1469 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:47624/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@53532744 [junit4:junit4] 2> 8288 T1528 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:47624 [junit4:junit4] 2> 8288 T1469 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 8289 T1528 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost/127.0.0.1:47624, initiating sessi= on [junit4:junit4] 2> 8289 T1471 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /140.211.11.196:47739 [junit4:junit4] 2> 8290 T1471 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /140.211.11.196:47739 [junit4:junit4] 2> 8291 T1473 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x139e5037e640005 with negotiated timeout 5000 for client /14= 0.211.11.196:47739 [junit4:junit4] 2> 8291 T1528 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server localhost/127.0.0.1:47624, sessio= nid =3D 0x139e5037e640005, negotiated timeout =3D 5000 [junit4:junit4] 2> 8291 T1529 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@53532744 name:ZooKeeperConnecti= on Watcher:127.0.0.1:47624/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4:junit4] 2> 8292 T1469 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 8292 T1474 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139e5037e640005 type:cre= ate cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 8294 T1474 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139e5037e640005 type:cre= ate cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 8302 T1469 oasc.ZkController.createEphemeralLiveNode R= egister node as live in ZooKeeper:/live_nodes/127.0.0.1:47731_solr [junit4:junit4] 2> 8302 T1474 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139e5037e640005 type:del= ete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/live_nodes/127.0.0.1:47731_solr Error:KeeperErrorCode =3D NoNode for= /solr/live_nodes/127.0.0.1:47731_solr [junit4:junit4] 2> 8303 T1469 oascc.SolrZkClient.makePath makePath: /live= _nodes/127.0.0.1:47731_solr [junit4:junit4] 2> 8306 T1489 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 8306 T1515 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 8306 T1503 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 8307 T1469 oashc.HttpShardHandlerFactory.getParameter = Setting socketTimeout to: 0 [junit4:junit4] 2> 8307 T1469 oashc.HttpShardHandlerFactory.getParameter = Setting urlScheme to: http:// [junit4:junit4] 2> 8308 T1469 oashc.HttpShardHandlerFactory.getParameter = Setting connTimeout to: 0 [junit4:junit4] 2> 8308 T1469 oashc.HttpShardHandlerFactory.getParameter = Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 8309 T1469 oashc.HttpShardHandlerFactory.getParameter = Setting corePoolSize to: 0 [junit4:junit4] 2> 8309 T1469 oashc.HttpShardHandlerFactory.getParameter = Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 8309 T1469 oashc.HttpShardHandlerFactory.getParameter = Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 8310 T1469 oashc.HttpShardHandlerFactory.getParameter = Setting sizeOfQueue to: -1 [junit4:junit4] 2> 8310 T1469 oashc.HttpShardHandlerFactory.getParameter = Setting fairnessPolicy to: false [junit4:junit4] 2> 8311 T1469 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&= socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 8332 T1469 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 8338 T1469 oasc.CoreContainer.create Creating SolrCore= 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty3-1= 348166982623/collection1 [junit4:junit4] 2> 8339 T1469 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:collection1 [junit4:junit4] 2> 8339 T1469 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 8340 T1469 oasc.ZkController.readConfigName Load colle= ction config from:/collections/collection1 [junit4:junit4] 2> 8341 T1469 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty3-13481669= 82623/collection1/' [junit4:junit4] 2> 8342 T1469 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-= Java6/solr/build/solr-core/test/J1/solrtest-jetty3-1348166982623/collection= 1/lib/classes/' to classloader [junit4:junit4] 2> 8343 T1469 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-= Java6/solr/build/solr-core/test/J1/solrtest-jetty3-1348166982623/collection= 1/lib/README' to classloader [junit4:junit4] 2> 8391 T1469 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_40 [junit4:junit4] 2> 8454 T1469 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 8555 T1469 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 8560 T1469 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 9124 T1469 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 9134 T1469 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 9139 T1469 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 9159 T1469 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 9164 T1469 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 9169 T1469 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 9170 T1469 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 9171 T1469 oasc.SolrCore. [collection1] Opening = new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4= .x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty3-1348166982623/colle= ction1/, dataDir=3D./org.apache.solr.cloud.SyncSliceTest-1348166975070/jett= y3/ [junit4:junit4] 2> 9171 T1469 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@1702679a [junit4:junit4] 2> 9173 T1469 oasc.SolrCore.getNewIndexDir WARNING New in= dex directory detected: old=3Dnull new=3D./org.apache.solr.cloud.SyncSliceT= est-1348166975070/jetty3/index/ [junit4:junit4] 2> 9173 T1469 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory './org.apache.solr.cloud.SyncSliceTest-134816697507= 0/jetty3/index' doesn't exist. Creating new index... [junit4:junit4] 2> 9174 T1469 oasc.CachingDirectoryFactory.get return new= directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.= x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-= 1348166975070/jetty3/index forceNew:false [junit4:junit4] 2> 9190 T1469 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/jetty3/index,segFN=3Dsegments_1,genera= tion=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 9190 T1469 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1 [junit4:junit4] 2> 9191 T1469 oasc.SolrCore.initWriters created xml: solr= .XMLResponseWriter [junit4:junit4] 2> 9193 T1469 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 9194 T1469 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe-allfields" [junit4:junit4] 2> 9194 T1469 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 9195 T1469 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "u= niq-fields" [junit4:junit4] 2> 9195 T1469 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 9196 T1469 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 9196 T1469 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 9197 T1469 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 9198 T1469 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 9198 T1469 oasc.RequestHandlers.initHandlersFromConfig= created dismax: solr.SearchHandler [junit4:junit4] 2> 9199 T1469 oasc.RequestHandlers.initHandlersFromConfig= created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 9199 T1469 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 9200 T1469 oasc.RequestHandlers.initHandlersFromConfig= created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 9200 T1469 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 9201 T1469 oasc.RequestHandlers.initHandlersFromConfig= created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 9202 T1469 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 9202 T1469 oasc.RequestHandlers.initHandlersFromConfig= created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 9203 T1469 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 9203 T1469 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 9204 T1469 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 9205 T1469 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.= SearchHandler [junit4:junit4] 2> 9205 T1469 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 9206 T1469 oasc.RequestHandlers.initHandlersFromConfig= created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 9207 T1469 oasc.RequestHandlers.initHandlersFromConfig= created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 9207 T1469 oasc.RequestHandlers.initHandlersFromConfig= created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 9210 T1469 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 9215 T1469 oasc.SolrCore.initDeprecatedSupport WARNING= solrconfig.xml uses deprecated , Please update your c= onfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 9217 T1469 oasc.SolrCore.initDeprecatedSupport WARNING= adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS= .TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT,= CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT] [junit4:junit4] 2> 9221 T1469 oass.SolrIndexSearcher. Opening Searc= her@2811f8b1 main [junit4:junit4] 2> 9222 T1469 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 9223 T1469 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 9223 T1469 oashc.SpellCheckComponent.inform Initializi= ng spell checkers [junit4:junit4] 2> 9233 T1469 oass.DirectSolrSpellChecker.init init: {nam= e=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLen= gth=3D3} [junit4:junit4] 2> 9323 T1530 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@2811f8b1 main{StandardDirectoryReader(se= gments_1:1)} [junit4:junit4] 2> 9503 T1490 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 9516 T1489 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 9516 T1529 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 9516 T1515 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 9516 T1503 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 9832 T1469 oasc.CoreContainer.register registering cor= e: collection1 [junit4:junit4] 2> 9832 T1469 oasc.ZkController.register Register shard -= core:collection1 address:http://127.0.0.1:47731/solr shardId:shard1 [junit4:junit4] 2> 9836 T1469 oasc.ZkController.register We are http://12= 7.0.0.1:47731/solr/collection1/ and leader is http://127.0.0.1:47640/solr/c= ollection1/ [junit4:junit4] 2> 9836 T1469 oasc.ZkController.register No LogReplay nee= ded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:47731/solr [junit4:junit4] 2> 9837 T1469 oasc.ZkController.checkRecovery Core needs = to recover:collection1 [junit4:junit4] 2> 9837 T1469 oasu.DefaultSolrCoreState.doRecovery Runnin= g recovery - first canceling any ongoing recovery [junit4:junit4] 2> ASYNC NEW_CORE C93 name=3Dcollection1 org.apache.solr= .core.SolrCore@54d679d url=3Dhttp://127.0.0.1:47731/solr/collection1 node= =3D127.0.0.1:47731_solr [junit4:junit4] 2> 9839 T1531 C93 P47731 oasc.RecoveryStrategy.run Starti= ng recovery process. core=3Dcollection1 recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 9840 T1531 C93 P47731 oasc.RecoveryStrategy.doRecovery= ###### startupVersions=3D[] [junit4:junit4] 2> 9839 T1469 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 9841 T1531 C93 P47731 oasc.RecoveryStrategy.doRecovery= Attempting to PeerSync from http://127.0.0.1:47640/solr/collection1/ core= =3Dcollection1 - recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 9841 T1531 C93 P47731 oasu.PeerSync.sync PeerSync: cor= e=3Dcollection1 url=3Dhttp://127.0.0.1:47731/solr START replicas=3D[http://= 127.0.0.1:47640/solr/collection1/] nUpdates=3D100 [junit4:junit4] 2> 9841 T1469 oass.SolrDispatchFilter.init user.dir=3D/us= r/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build= /solr-core/test/J1 [junit4:junit4] 2> 9842 T1531 C93 P47731 oasu.PeerSync.sync WARNING no fr= ame of reference to tell of we've missed updates [junit4:junit4] 2> 9842 T1469 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() done [junit4:junit4] 2> 9842 T1531 C93 P47731 oasc.RecoveryStrategy.doRecovery= PeerSync Recovery was not successful - trying replication. core=3Dcollecti= on1 [junit4:junit4] 2> 9843 T1531 C93 P47731 oasc.RecoveryStrategy.doRecovery= Starting Replication Recovery. core=3Dcollection1 [junit4:junit4] 2> 9843 T1469 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fo= llowRedirects=3Dfalse [junit4:junit4] 2> 9843 T1531 C93 P47731 oascsi.HttpClientUtil.createClie= nt Creating new http client, config:maxConnections=3D128&maxConnectionsPerH= ost=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 9844 T1495 C92 P47640 REQ /get {distrib=3Dfalse&qt=3D/= get&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 10022 T1490 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 10070 T1489 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 10070 T1529 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 10070 T1503 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 10070 T1515 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 10368 T1469 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 10371 T1469 oejs.AbstractConnector.doStart Started Soc= ketConnector@0.0.0.0:47755 [junit4:junit4] 2> 10371 T1469 oasc.SolrResourceLoader.locateSolrHome JND= I not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 10372 T1469 oasc.SolrResourceLoader.locateSolrHome usi= ng system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/= Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty4-= 1348166984928 [junit4:junit4] 2> 10372 T1469 oasc.SolrResourceLoader. new SolrRes= ourceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace= /Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty4= -1348166984928/' [junit4:junit4] 2> 10408 T1469 oass.SolrDispatchFilter.init SolrDispatchF= ilter.init() [junit4:junit4] 2> 10408 T1469 oasc.SolrResourceLoader.locateSolrHome JND= I not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 10409 T1469 oasc.SolrResourceLoader.locateSolrHome usi= ng system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/= Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty4-= 1348166984928 [junit4:junit4] 2> 10409 T1469 oasc.CoreContainer$Initializer.initialize = looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-T= ests-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty4-1348166984928= /solr.xml [junit4:junit4] 2> 10409 T1469 oasc.CoreContainer. New CoreContaine= r 600729852 [junit4:junit4] 2> 10410 T1469 oasc.CoreContainer.load Loading CoreContai= ner using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-T= ests-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty4-1348166984928= /' [junit4:junit4] 2> 10410 T1469 oasc.SolrResourceLoader. new SolrRes= ourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-= Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty4-1348166= 984928/' [junit4:junit4] 2> 10436 T1469 oasc.CoreContainer.load Registering Log Li= stener [junit4:junit4] 2> 10451 T1469 oasc.CoreContainer.initZooKeeper Zookeeper= client=3D127.0.0.1:47624/solr [junit4:junit4] 2> 10452 T1469 oaz.ZooKeeper. Initiating client con= nection, connectString=3D127.0.0.1:47624/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@77a7f0 [junit4:junit4] 2> 10453 T1542 oaz.ClientCnxn$SendThread.startConnect Ope= ning socket connection to server /127.0.0.1:47624 [junit4:junit4] 2> 10453 T1469 oascc.ConnectionManager.waitForConnected W= aiting for client to connect to ZooKeeper [junit4:junit4] 2> 10454 T1542 oaz.ClientCnxn$SendThread.primeConnection = Socket connection established to localhost/127.0.0.1:47624, initiating sess= ion [junit4:junit4] 2> 10454 T1471 oazs.NIOServerCnxn$Factory.run Accepted so= cket connection from /140.211.11.196:47756 [junit4:junit4] 2> 10454 T1471 oazs.NIOServerCnxn.readConnectRequest Clie= nt attempting to establish new session at /140.211.11.196:47756 [junit4:junit4] 2> 10457 T1473 oazs.NIOServerCnxn.finishSessionInit Estab= lished session 0x139e5037e640006 with negotiated timeout 5000 for client /1= 40.211.11.196:47756 [junit4:junit4] 2> 10457 T1542 oaz.ClientCnxn$SendThread.readConnectResul= t Session establishment complete on server localhost/127.0.0.1:47624, sessi= onid =3D 0x139e5037e640006, negotiated timeout =3D 5000 [junit4:junit4] 2> 10457 T1543 oascc.ConnectionManager.process Watcher or= g.apache.solr.common.cloud.ConnectionManager@77a7f0 name:ZooKeeperConnectio= n Watcher:127.0.0.1:47624/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 10457 T1469 oascc.ConnectionManager.waitForConnected C= lient is connected to ZooKeeper [junit4:junit4] 2> 10458 T1474 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x139e5037e640006 type:cr= eate cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 10477 T1474 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x139e5037e640006 type:cr= eate cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 10479 T1469 oasc.ZkController.createEphemeralLiveNode = Register node as live in ZooKeeper:/live_nodes/127.0.0.1:47755_solr [junit4:junit4] 2> 10480 T1474 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x139e5037e640006 type:de= lete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/live_nodes/127.0.0.1:47755_solr Error:KeeperErrorCode =3D NoNode fo= r /solr/live_nodes/127.0.0.1:47755_solr [junit4:junit4] 2> 10481 T1469 oascc.SolrZkClient.makePath makePath: /liv= e_nodes/127.0.0.1:47755_solr [junit4:junit4] 2> 10483 T1489 oascc.ZkStateReader$3.process Updating liv= e nodes [junit4:junit4] 2> 10483 T1529 oascc.ZkStateReader$3.process Updating liv= e nodes [junit4:junit4] 2> 10483 T1503 oascc.ZkStateReader$3.process Updating liv= e nodes [junit4:junit4] 2> 10483 T1515 oascc.ZkStateReader$3.process Updating liv= e nodes [junit4:junit4] 2> 10484 T1469 oashc.HttpShardHandlerFactory.getParameter= Setting socketTimeout to: 0 [junit4:junit4] 2> 10484 T1469 oashc.HttpShardHandlerFactory.getParameter= Setting urlScheme to: http:// [junit4:junit4] 2> 10485 T1469 oashc.HttpShardHandlerFactory.getParameter= Setting connTimeout to: 0 [junit4:junit4] 2> 10485 T1469 oashc.HttpShardHandlerFactory.getParameter= Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 10486 T1469 oashc.HttpShardHandlerFactory.getParameter= Setting corePoolSize to: 0 [junit4:junit4] 2> 10486 T1469 oashc.HttpShardHandlerFactory.getParameter= Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 10486 T1469 oashc.HttpShardHandlerFactory.getParameter= Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 10487 T1469 oashc.HttpShardHandlerFactory.getParameter= Setting sizeOfQueue to: -1 [junit4:junit4] 2> 10487 T1469 oashc.HttpShardHandlerFactory.getParameter= Setting fairnessPolicy to: false [junit4:junit4] 2> 10488 T1469 oascsi.HttpClientUtil.createClient Creatin= g new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000= &socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 10505 T1469 oascc.ZkStateReader.createClusterStateWatc= hersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 10511 T1469 oasc.CoreContainer.create Creating SolrCor= e 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/= Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty4-= 1348166984928/collection1 [junit4:junit4] 2> 10512 T1469 oasc.ZkController.createCollectionZkNode C= heck for collection zkNode:collection1 [junit4:junit4] 2> 10512 T1469 oasc.ZkController.createCollectionZkNode C= ollection zkNode exists [junit4:junit4] 2> 10513 T1469 oasc.ZkController.readConfigName Load coll= ection config from:/collections/collection1 [junit4:junit4] 2> 10514 T1469 oasc.SolrResourceLoader. new SolrRes= ourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-= Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty4-1348166= 984928/collection1/' [junit4:junit4] 2> 10516 T1469 oasc.SolrResourceLoader.replaceClassLoader= Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x= -Java6/solr/build/solr-core/test/J1/solrtest-jetty4-1348166984928/collectio= n1/lib/README' to classloader [junit4:junit4] 2> 10516 T1469 oasc.SolrResourceLoader.replaceClassLoader= Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x= -Java6/solr/build/solr-core/test/J1/solrtest-jetty4-1348166984928/collectio= n1/lib/classes/' to classloader [junit4:junit4] 2> C91_STATE=3Dcoll:collection1 core:collection1 props:{= shard=3Dshard1, roles=3Dnull, state=3Drecovering, core=3Dcollection1, colle= ction=3Dcollection1, node_name=3D127.0.0.1:47653_solr, base_url=3Dhttp://12= 7.0.0.1:47653/solr} [junit4:junit4] 2> 10563 T1517 C91 P47653 oasc.RecoveryStrategy.doRecover= y Begin buffering updates. core=3Dcollection1 [junit4:junit4] 2> 10563 T1517 C91 P47653 oasu.UpdateLog.bufferUpdates St= arting to buffer updates. FSUpdateLog{state=3DACTIVE, tlog=3Dnull} [junit4:junit4] 2> 10563 T1517 C91 P47653 oasc.RecoveryStrategy.replicate= Attempting to replicate from http://127.0.0.1:47640/solr/collection1/. cor= e=3Dcollection1 [junit4:junit4] 2> 10563 T1517 C91 P47653 oascsi.HttpClientUtil.createCli= ent Creating new http client, config:maxConnections=3D128&maxConnectionsPer= Host=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 10566 T1469 oasc.SolrConfig. Using Lucene MatchV= ersion: LUCENE_40 [junit4:junit4] 2> 10575 T1498 C92 P47640 oasu.DirectUpdateHandler2.commi= t start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue= ,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 10580 T1498 C92 P47640 oasc.SolrDeletionPolicy.onInit = SolrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/jetty1/index,segFN=3Dsegments_1,genera= tion=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 10581 T1498 C92 P47640 oasc.SolrDeletionPolicy.updateC= ommits newest commit =3D 1 [junit4:junit4] 2> 10582 T1498 C92 P47640 oass.SolrIndexSearcher. O= pening Searcher@39d702bf main [junit4:junit4] 2> 10583 T1498 C92 P47640 oasu.DirectUpdateHandler2.commi= t end_commit_flush [junit4:junit4] 2> 10584 T1504 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@39d702bf main{StandardDirectoryReader(s= egments_1:1)} [junit4:junit4] 2> 10585 T1498 C92 P47640 /update {waitSearcher=3Dtrue&co= mmit=3Dtrue&commit_end_point=3Dtrue&wt=3Djavabin&softCommit=3Dfalse&version= =3D2} {commit=3D} 0 10 [junit4:junit4] 2> 10585 T1517 C91 P47653 oash.ReplicationHandler.inform = Commits will be reserved for 10000 [junit4:junit4] 2> 10586 T1517 C91 P47653 oash.SnapPuller. No valu= e set for 'pollInterval'. Timer Task not started. [junit4:junit4] 2> 10587 T1499 C92 P47640 oash.ReplicationHandler.inform = Commits will be reserved for 10000 [junit4:junit4] 2> 10587 T1499 C92 P47640 REQ /replication {command=3Dind= exversion&wt=3Djavabin&qt=3D/replication&version=3D2} status=3D0 QTime=3D0= =20 [junit4:junit4] 2> 10592 T1517 C91 P47653 oasc.SolrDeletionPolicy.onInit = SolrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/jetty2/index,segFN=3Dsegments_1,genera= tion=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 10592 T1517 C91 P47653 oasc.SolrDeletionPolicy.updateC= ommits newest commit =3D 1 [junit4:junit4] 2> 10593 T1517 C91 P47653 oasu.DirectUpdateHandler2.commi= t start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue= ,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 10596 T1517 C91 P47653 oasc.SolrDeletionPolicy.onCommi= t SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/jetty2/index,segFN=3Dsegments_1,genera= tion=3D1,filenames=3D[segments_1] [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/jetty2/index,segFN=3Dsegments_2,genera= tion=3D2,filenames=3D[segments_2] [junit4:junit4] 2> 10596 T1517 C91 P47653 oasc.SolrDeletionPolicy.updateC= ommits newest commit =3D 2 [junit4:junit4] 2> 10600 T1517 C91 P47653 oass.SolrIndexSearcher. O= pening Searcher@4974ecc2 main [junit4:junit4] 2> 10600 T1517 C91 P47653 oasu.DirectUpdateHandler2.commi= t end_commit_flush [junit4:junit4] 2> 10602 T1516 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@4974ecc2 main{StandardDirectoryReader(s= egments_2:2)} [junit4:junit4] 2> 10602 T1517 C91 P47653 oasc.RecoveryStrategy.replay No= replay needed. core=3Dcollection1 [junit4:junit4] 2> 10602 T1517 C91 P47653 oasc.RecoveryStrategy.doRecover= y Replication Recovery was successful - registering as Active. core=3Dcolle= ction1 [junit4:junit4] 2> 10604 T1517 C91 P47653 oasc.RecoveryStrategy.doRecover= y Finished recovery process. core=3Dcollection1 [junit4:junit4] 2> 10643 T1469 oasc.SolrConfig. Loaded SolrConfig: = solrconfig.xml [junit4:junit4] 2> 10744 T1469 oass.IndexSchema.readSchema Reading Solr S= chema [junit4:junit4] 2> 10753 T1469 oass.IndexSchema.readSchema Schema name=3D= test [junit4:junit4] 2> 11076 T1490 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 11092 T1543 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 11092 T1489 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 11092 T1515 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 11092 T1503 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 11092 T1529 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 11375 T1469 oass.OpenExchangeRatesOrgProvider.init Ini= tialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 11384 T1469 oass.IndexSchema.readSchema default search= field in schema is text [junit4:junit4] 2> 11387 T1469 oass.IndexSchema.readSchema unique key fie= ld: id [junit4:junit4] 2> 11398 T1469 oass.FileExchangeRateProvider.reload Reloa= ding exchange rates from file currency.xml [junit4:junit4] 2> 11402 T1469 oass.FileExchangeRateProvider.reload Reloa= ding exchange rates from file currency.xml [junit4:junit4] 2> 11405 T1469 oass.OpenExchangeRatesOrgProvider.reload R= eloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 11406 T1469 oass.OpenExchangeRatesOrgProvider.reload R= eloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 11407 T1469 oasc.SolrCore. [collection1] Opening= new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-= 4.x-Java6/solr/build/solr-core/test/J1/./solrtest-jetty4-1348166984928/coll= ection1/, dataDir=3D./org.apache.solr.cloud.SyncSliceTest-1348166975070/jet= ty4/ [junit4:junit4] 2> 11407 T1469 oasc.JmxMonitoredMap. JMX monitoring= is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxM= BeanServer@1702679a [junit4:junit4] 2> 11408 T1469 oasc.SolrCore.getNewIndexDir WARNING New i= ndex directory detected: old=3Dnull new=3D./org.apache.solr.cloud.SyncSlice= Test-1348166975070/jetty4/index/ [junit4:junit4] 2> 11409 T1469 oasc.SolrCore.initIndex WARNING [collectio= n1] Solr index directory './org.apache.solr.cloud.SyncSliceTest-13481669750= 70/jetty4/index' doesn't exist. Creating new index... [junit4:junit4] 2> 11409 T1469 oasc.CachingDirectoryFactory.get return ne= w directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4= .x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest= -1348166975070/jetty4/index forceNew:false [junit4:junit4] 2> 11414 T1469 oasc.SolrDeletionPolicy.onCommit SolrDelet= ionPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/jetty4/index,segFN=3Dsegments_1,genera= tion=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 11415 T1469 oasc.SolrDeletionPolicy.updateCommits newe= st commit =3D 1 [junit4:junit4] 2> 11415 T1469 oasc.SolrCore.initWriters created xml: sol= r.XMLResponseWriter [junit4:junit4] 2> 11417 T1469 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= dedupe" [junit4:junit4] 2> 11417 T1469 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= dedupe-allfields" [junit4:junit4] 2> 11418 T1469 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= stored_sig" [junit4:junit4] 2> 11418 T1469 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= uniq-fields" [junit4:junit4] 2> 11419 T1469 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= distrib-dup-test-chain-implicit" [junit4:junit4] 2> 11419 T1469 oasc.RequestHandlers.initHandlersFromConfi= g adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 11419 T1469 oasc.RequestHandlers.initHandlersFromConfi= g created /replication: solr.ReplicationHandler [junit4:junit4] 2> 11420 T1469 oasc.RequestHandlers.initHandlersFromConfi= g created standard: solr.StandardRequestHandler [junit4:junit4] 2> 11420 T1469 oasc.RequestHandlers.initHandlersFromConfi= g created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 11421 T1469 oasc.RequestHandlers.initHandlersFromConfi= g created dismax: solr.SearchHandler [junit4:junit4] 2> 11421 T1469 oasc.RequestHandlers.initHandlersFromConfi= g created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 11422 T1469 oasc.RequestHandlers.initHandlersFromConfi= g created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 11422 T1469 oasc.RequestHandlers.initHandlersFromConfi= g created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 11422 T1469 oasc.RequestHandlers.initHandlersFromConfi= g adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 11423 T1469 oasc.RequestHandlers.initHandlersFromConfi= g created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 11423 T1469 oasc.RequestHandlers.initHandlersFromConfi= g created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 11424 T1469 oasc.RequestHandlers.initHandlersFromConfi= g created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 11424 T1469 oasc.RequestHandlers.initHandlersFromConfi= g created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 11424 T1469 oasc.RequestHandlers.initHandlersFromConfi= g created spellCheckCompRH_Direct: org.apache.solr.handler.component.Search= Handler [junit4:junit4] 2> 11425 T1469 oasc.RequestHandlers.initHandlersFromConfi= g created spellCheckWithWordbreak: org.apache.solr.handler.component.Search= Handler [junit4:junit4] 2> 11425 T1469 oasc.RequestHandlers.initHandlersFromConfi= g created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component= .SearchHandler [junit4:junit4] 2> 11426 T1469 oasc.RequestHandlers.initHandlersFromConfi= g created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandle= r [junit4:junit4] 2> 11426 T1469 oasc.RequestHandlers.initHandlersFromConfi= g created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 11427 T1469 oasc.RequestHandlers.initHandlersFromConfi= g created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 11427 T1469 oasc.RequestHandlers.initHandlersFromConfi= g created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 11430 T1469 oashl.XMLLoader.init xsltCacheLifetimeSeco= nds=3D60 [junit4:junit4] 2> 11433 T1469 oasc.SolrCore.initDeprecatedSupport WARNIN= G solrconfig.xml uses deprecated , Please update your = config to use the ShowFileRequestHandler. [junit4:junit4] 2> 11434 T1469 oasc.SolrCore.initDeprecatedSupport WARNIN= G adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYM= S.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT= , CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT] [junit4:junit4] 2> 11437 T1469 oass.SolrIndexSearcher. Opening Sear= cher@3218dae main [junit4:junit4] 2> 11438 T1469 oasu.CommitTracker. Hard AutoCommit:= disabled [junit4:junit4] 2> 11438 T1469 oasu.CommitTracker. Soft AutoCommit:= disabled [junit4:junit4] 2> 11438 T1469 oashc.SpellCheckComponent.inform Initializ= ing spell checkers [junit4:junit4] 2> 11444 T1469 oass.DirectSolrSpellChecker.init init: {na= me=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLe= ngth=3D3} [junit4:junit4] 2> 11495 T1544 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@3218dae main{StandardDirectoryReader(se= gments_1:1)} [junit4:junit4] 2> 11597 T1490 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 11610 T1543 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 11610 T1489 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 11610 T1529 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 11610 T1503 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 11610 T1515 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 12000 T1469 oasc.CoreContainer.register registering co= re: collection1 [junit4:junit4] 2> 12000 T1469 oasc.ZkController.register Register shard = - core:collection1 address:http://127.0.0.1:47755/solr shardId:shard1 [junit4:junit4] 2> 12003 T1469 oasc.ZkController.register We are http://1= 27.0.0.1:47755/solr/collection1/ and leader is http://127.0.0.1:47640/solr/= collection1/ [junit4:junit4] 2> 12004 T1469 oasc.ZkController.register No LogReplay ne= eded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:47755/solr [junit4:junit4] 2> 12004 T1469 oasc.ZkController.checkRecovery Core needs= to recover:collection1 [junit4:junit4] 2> 12005 T1469 oasu.DefaultSolrCoreState.doRecovery Runni= ng recovery - first canceling any ongoing recovery [junit4:junit4] 2> ASYNC NEW_CORE C94 name=3Dcollection1 org.apache.solr= .core.SolrCore@2130a310 url=3Dhttp://127.0.0.1:47755/solr/collection1 node= =3D127.0.0.1:47755_solr [junit4:junit4] 2> 12006 T1545 C94 P47755 oasc.RecoveryStrategy.run Start= ing recovery process. core=3Dcollection1 recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 12006 T1545 C94 P47755 oasc.RecoveryStrategy.doRecover= y ###### startupVersions=3D[] [junit4:junit4] 2> 12006 T1469 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 12007 T1545 C94 P47755 oasc.RecoveryStrategy.doRecover= y Attempting to PeerSync from http://127.0.0.1:47640/solr/collection1/ core= =3Dcollection1 - recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 12007 T1545 C94 P47755 oasu.PeerSync.sync PeerSync: co= re=3Dcollection1 url=3Dhttp://127.0.0.1:47755/solr START replicas=3D[http:/= /127.0.0.1:47640/solr/collection1/] nUpdates=3D100 [junit4:junit4] 2> 12007 T1469 oass.SolrDispatchFilter.init user.dir=3D/u= sr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/buil= d/solr-core/test/J1 [junit4:junit4] 2> 12008 T1545 C94 P47755 oasu.PeerSync.sync WARNING no f= rame of reference to tell of we've missed updates [junit4:junit4] 2> 12008 T1545 C94 P47755 oasc.RecoveryStrategy.doRecover= y PeerSync Recovery was not successful - trying replication. core=3Dcollect= ion1 [junit4:junit4] 2> 12008 T1469 oass.SolrDispatchFilter.init SolrDispatchF= ilter.init() done [junit4:junit4] 2> 12008 T1545 C94 P47755 oasc.RecoveryStrategy.doRecover= y Starting Replication Recovery. core=3Dcollection1 [junit4:junit4] 2> 12009 T1545 C94 P47755 oascsi.HttpClientUtil.createCli= ent Creating new http client, config:maxConnections=3D128&maxConnectionsPer= Host=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 12009 T1469 oascsi.HttpClientUtil.createClient Creatin= g new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&f= ollowRedirects=3Dfalse [junit4:junit4] 2> 12010 T1495 C92 P47640 REQ /get {distrib=3Dfalse&qt=3D= /get&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 12021 T1469 oaz.ZooKeeper. Initiating client con= nection, connectString=3D127.0.0.1:47624/solr sessionTimeout=3D10000 watche= r=3Dorg.apache.solr.common.cloud.ConnectionManager@12127900 [junit4:junit4] 2> 12022 T1547 oaz.ClientCnxn$SendThread.startConnect Ope= ning socket connection to server /127.0.0.1:47624 [junit4:junit4] 2> 12022 T1469 oascc.ConnectionManager.waitForConnected W= aiting for client to connect to ZooKeeper [junit4:junit4] 2> 12023 T1547 oaz.ClientCnxn$SendThread.primeConnection = Socket connection established to localhost/127.0.0.1:47624, initiating sess= ion [junit4:junit4] 2> 12023 T1471 oazs.NIOServerCnxn$Factory.run Accepted so= cket connection from /140.211.11.196:47761 [junit4:junit4] 2> 12023 T1471 oazs.NIOServerCnxn.readConnectRequest Clie= nt attempting to establish new session at /140.211.11.196:47761 [junit4:junit4] 2> 12024 T1473 oazs.NIOServerCnxn.finishSessionInit Estab= lished session 0x139e5037e640007 with negotiated timeout 10000 for client /= 140.211.11.196:47761 [junit4:junit4] 2> 12024 T1547 oaz.ClientCnxn$SendThread.readConnectResul= t Session establishment complete on server localhost/127.0.0.1:47624, sessi= onid =3D 0x139e5037e640007, negotiated timeout =3D 10000 [junit4:junit4] 2> 12025 T1548 oascc.ConnectionManager.process Watcher or= g.apache.solr.common.cloud.ConnectionManager@12127900 name:ZooKeeperConnect= ion Watcher:127.0.0.1:47624/solr got event WatchedEvent state:SyncConnected= type:None path:null path:null type:None [junit4:junit4] 2> 12025 T1469 oascc.ConnectionManager.waitForConnected C= lient is connected to ZooKeeper [junit4:junit4] 2> 12026 T1469 oascc.ZkStateReader.createClusterStateWatc= hersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 12027 T1469 oasc.ChaosMonkey.monkeyLog monkey: init - = expire sessions:true cause connection loss:true [junit4:junit4] 2> 12028 T1469 oascsi.HttpClientUtil.createClient Creatin= g new http client, config: [junit4:junit4] 2> 12036 T1469 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 12037 T1469 oasc.AbstractFullDistribZkTestBase.waitFor= ThingsToLevelOut Wait for recoveries to finish - wait 15 for each attempt [junit4:junit4] 2> 12038 T1469 oasc.AbstractDistribZkTestBase.waitForReco= veriesToFinish Wait for recoveries to finish - collection: collection1 fail= OnTimeout:true timeout (sec):15 [junit4:junit4] 2> 12038 T1469 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 12114 T1490 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 12127 T1543 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 12127 T1515 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 12127 T1529 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 12127 T1548 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 12127 T1489 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 12127 T1503 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> C93_STATE=3Dcoll:collection1 core:collection1 props:{= shard=3Dshard1, roles=3Dnull, state=3Drecovering, core=3Dcollection1, colle= ction=3Dcollection1, node_name=3D127.0.0.1:47731_solr, base_url=3Dhttp://12= 7.0.0.1:47731/solr} [junit4:junit4] 2> 12867 T1531 C93 P47731 oasc.RecoveryStrategy.doRecover= y Begin buffering updates. core=3Dcollection1 [junit4:junit4] 2> 12867 T1531 C93 P47731 oasu.UpdateLog.bufferUpdates St= arting to buffer updates. FSUpdateLog{state=3DACTIVE, tlog=3Dnull} [junit4:junit4] 2> 12867 T1531 C93 P47731 oasc.RecoveryStrategy.replicate= Attempting to replicate from http://127.0.0.1:47640/solr/collection1/. cor= e=3Dcollection1 [junit4:junit4] 2> 12867 T1531 C93 P47731 oascsi.HttpClientUtil.createCli= ent Creating new http client, config:maxConnections=3D128&maxConnectionsPer= Host=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 12877 T1501 C92 P47640 oasu.DirectUpdateHandler2.commi= t start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue= ,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 12881 T1501 C92 P47640 oass.SolrIndexSearcher. O= pening Searcher@4fb2a82e main [junit4:junit4] 2> 12881 T1501 C92 P47640 oasu.DirectUpdateHandler2.commi= t end_commit_flush [junit4:junit4] 2> 12883 T1504 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@4fb2a82e main{StandardDirectoryReader(s= egments_1:1)} [junit4:junit4] 2> 12883 T1501 C92 P47640 /update {waitSearcher=3Dtrue&co= mmit=3Dtrue&commit_end_point=3Dtrue&wt=3Djavabin&softCommit=3Dfalse&version= =3D2} {commit=3D} 0 6 [junit4:junit4] 2> 12884 T1531 C93 P47731 oash.ReplicationHandler.inform = Commits will be reserved for 10000 [junit4:junit4] 2> 12884 T1531 C93 P47731 oash.SnapPuller. No valu= e set for 'pollInterval'. Timer Task not started. [junit4:junit4] 2> 12886 T1499 C92 P47640 REQ /replication {command=3Dind= exversion&wt=3Djavabin&qt=3D/replication&version=3D2} status=3D0 QTime=3D0= =20 [junit4:junit4] 2> 12890 T1531 C93 P47731 oasc.SolrDeletionPolicy.onInit = SolrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/jetty3/index,segFN=3Dsegments_1,genera= tion=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 12891 T1531 C93 P47731 oasc.SolrDeletionPolicy.updateC= ommits newest commit =3D 1 [junit4:junit4] 2> 12891 T1531 C93 P47731 oasu.DirectUpdateHandler2.commi= t start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue= ,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 12894 T1531 C93 P47731 oasc.SolrDeletionPolicy.onCommi= t SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/jetty3/index,segFN=3Dsegments_1,genera= tion=3D1,filenames=3D[segments_1] [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/jetty3/index,segFN=3Dsegments_2,genera= tion=3D2,filenames=3D[segments_2] [junit4:junit4] 2> 12894 T1531 C93 P47731 oasc.SolrDeletionPolicy.updateC= ommits newest commit =3D 2 [junit4:junit4] 2> 12897 T1531 C93 P47731 oass.SolrIndexSearcher. O= pening Searcher@6c456df5 main [junit4:junit4] 2> 12898 T1531 C93 P47731 oasu.DirectUpdateHandler2.commi= t end_commit_flush [junit4:junit4] 2> 12899 T1530 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@6c456df5 main{StandardDirectoryReader(s= egments_2:2)} [junit4:junit4] 2> 12900 T1531 C93 P47731 oasc.RecoveryStrategy.replay No= replay needed. core=3Dcollection1 [junit4:junit4] 2> 12900 T1531 C93 P47731 oasc.RecoveryStrategy.doRecover= y Replication Recovery was successful - registering as Active. core=3Dcolle= ction1 [junit4:junit4] 2> 12908 T1531 C93 P47731 oasc.RecoveryStrategy.doRecover= y Finished recovery process. core=3Dcollection1 [junit4:junit4] 2> 13040 T1469 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 13133 T1490 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 13145 T1543 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 13145 T1489 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 13145 T1529 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 13145 T1548 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 13145 T1515 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 13145 T1503 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 14042 T1469 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> C94_STATE=3Dcoll:collection1 core:collection1 props:{= shard=3Dshard1, roles=3Dnull, state=3Drecovering, core=3Dcollection1, colle= ction=3Dcollection1, node_name=3D127.0.0.1:47755_solr, base_url=3Dhttp://12= 7.0.0.1:47755/solr} [junit4:junit4] 2> 15024 T1545 C94 P47755 oasc.RecoveryStrategy.doRecover= y Begin buffering updates. core=3Dcollection1 [junit4:junit4] 2> 15024 T1545 C94 P47755 oasu.UpdateLog.bufferUpdates St= arting to buffer updates. FSUpdateLog{state=3DACTIVE, tlog=3Dnull} [junit4:junit4] 2> 15024 T1545 C94 P47755 oasc.RecoveryStrategy.replicate= Attempting to replicate from http://127.0.0.1:47640/solr/collection1/. cor= e=3Dcollection1 [junit4:junit4] 2> 15024 T1545 C94 P47755 oascsi.HttpClientUtil.createCli= ent Creating new http client, config:maxConnections=3D128&maxConnectionsPer= Host=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 15039 T1496 C92 P47640 oasu.DirectUpdateHandler2.commi= t start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue= ,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 15042 T1496 C92 P47640 oass.SolrIndexSearcher. O= pening Searcher@2d5385c6 main [junit4:junit4] 2> 15042 T1496 C92 P47640 oasu.DirectUpdateHandler2.commi= t end_commit_flush [junit4:junit4] 2> 15044 T1504 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@2d5385c6 main{StandardDirectoryReader(s= egments_1:1)} [junit4:junit4] 2> 15044 T1496 C92 P47640 /update {waitSearcher=3Dtrue&co= mmit=3Dtrue&commit_end_point=3Dtrue&wt=3Djavabin&softCommit=3Dfalse&version= =3D2} {commit=3D} 0 5 [junit4:junit4] 2> 15044 T1469 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 15045 T1545 C94 P47755 oash.ReplicationHandler.inform = Commits will be reserved for 10000 [junit4:junit4] 2> 15045 T1545 C94 P47755 oash.SnapPuller. No valu= e set for 'pollInterval'. Timer Task not started. [junit4:junit4] 2> 15047 T1499 C92 P47640 REQ /replication {command=3Dind= exversion&wt=3Djavabin&qt=3D/replication&version=3D2} status=3D0 QTime=3D0= =20 [junit4:junit4] 2> 15052 T1545 C94 P47755 oasc.SolrDeletionPolicy.onInit = SolrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/jetty4/index,segFN=3Dsegments_1,genera= tion=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 15052 T1545 C94 P47755 oasc.SolrDeletionPolicy.updateC= ommits newest commit =3D 1 [junit4:junit4] 2> 15053 T1545 C94 P47755 oasu.DirectUpdateHandler2.commi= t start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue= ,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 15055 T1545 C94 P47755 oasc.SolrDeletionPolicy.onCommi= t SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/jetty4/index,segFN=3Dsegments_1,genera= tion=3D1,filenames=3D[segments_1] [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/jetty4/index,segFN=3Dsegments_2,genera= tion=3D2,filenames=3D[segments_2] [junit4:junit4] 2> 15055 T1545 C94 P47755 oasc.SolrDeletionPolicy.updateC= ommits newest commit =3D 2 [junit4:junit4] 2> 15058 T1545 C94 P47755 oass.SolrIndexSearcher. O= pening Searcher@5fc12f83 main [junit4:junit4] 2> 15059 T1545 C94 P47755 oasu.DirectUpdateHandler2.commi= t end_commit_flush [junit4:junit4] 2> 15060 T1544 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@5fc12f83 main{StandardDirectoryReader(s= egments_2:2)} [junit4:junit4] 2> 15061 T1545 C94 P47755 oasc.RecoveryStrategy.replay No= replay needed. core=3Dcollection1 [junit4:junit4] 2> 15061 T1545 C94 P47755 oasc.RecoveryStrategy.doRecover= y Replication Recovery was successful - registering as Active. core=3Dcolle= ction1 [junit4:junit4] 2> 15106 T1545 C94 P47755 oasc.RecoveryStrategy.doRecover= y Finished recovery process. core=3Dcollection1 [junit4:junit4] 2> 15155 T1490 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 15183 T1489 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 15183 T1543 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 15183 T1548 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 15183 T1529 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 15183 T1503 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 15183 T1515 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 16046 T1469 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> ASYNC NEW_CORE C95 name=3Dcollection1 org.apache.solr= .core.SolrCore@4c389632 url=3Dhttp://127.0.0.1:47632/solr/collection1 node= =3D127.0.0.1:47632_solr [junit4:junit4] 2> 16056 T1481 C95 P47632 oasu.DirectUpdateHandler2.commi= t start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue= ,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 16061 T1481 C95 P47632 oasc.SolrDeletionPolicy.onInit = SolrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/control/data/index,segFN=3Dsegments_1,= generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 16061 T1481 C95 P47632 oasc.SolrDeletionPolicy.updateC= ommits newest commit =3D 1 [junit4:junit4] 2> 16063 T1481 C95 P47632 oass.SolrIndexSearcher. O= pening Searcher@63ad3450 main [junit4:junit4] 2> 16063 T1481 C95 P47632 oasu.DirectUpdateHandler2.commi= t end_commit_flush [junit4:junit4] 2> 16065 T1492 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@63ad3450 main{StandardDirectoryReader(s= egments_1:1)} [junit4:junit4] 2> 16065 T1481 C95 P47632 /update {waitSearcher=3Dtrue&wt= =3Djavabin&commit=3Dtrue&softCommit=3Dfalse&version=3D2} {commit=3D} 0 9 [junit4:junit4] 2> 16066 T1469 oaz.ZooKeeper. Initiating client con= nection, connectString=3D127.0.0.1:47624/solr sessionTimeout=3D10000 watche= r=3Dorg.apache.solr.common.cloud.ConnectionManager@45a350 [junit4:junit4] 2> 16067 T1549 oaz.ClientCnxn$SendThread.startConnect Ope= ning socket connection to server /127.0.0.1:47624 [junit4:junit4] 2> 16067 T1469 oascc.ConnectionManager.waitForConnected W= aiting for client to connect to ZooKeeper [junit4:junit4] 2> 16068 T1549 oaz.ClientCnxn$SendThread.primeConnection = Socket connection established to localhost/127.0.0.1:47624, initiating sess= ion [junit4:junit4] 2> 16068 T1471 oazs.NIOServerCnxn$Factory.run Accepted so= cket connection from /140.211.11.196:47768 [junit4:junit4] 2> 16068 T1471 oazs.NIOServerCnxn.readConnectRequest Clie= nt attempting to establish new session at /140.211.11.196:47768 [junit4:junit4] 2> 16070 T1473 oazs.NIOServerCnxn.finishSessionInit Estab= lished session 0x139e5037e640008 with negotiated timeout 10000 for client /= 140.211.11.196:47768 [junit4:junit4] 2> 16070 T1549 oaz.ClientCnxn$SendThread.readConnectResul= t Session establishment complete on server localhost/127.0.0.1:47624, sessi= onid =3D 0x139e5037e640008, negotiated timeout =3D 10000 [junit4:junit4] 2> 16071 T1550 oascc.ConnectionManager.process Watcher or= g.apache.solr.common.cloud.ConnectionManager@45a350 name:ZooKeeperConnectio= n Watcher:127.0.0.1:47624/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 16071 T1469 oascc.ConnectionManager.waitForConnected C= lient is connected to ZooKeeper [junit4:junit4] 2> 16072 T1469 oascc.ZkStateReader.createClusterStateWatc= hersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 16074 T1498 C92 P47640 oasu.DirectUpdateHandler2.commi= t start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue= ,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 16077 T1498 C92 P47640 oass.SolrIndexSearcher. O= pening Searcher@7fdacda0 main [junit4:junit4] 2> 16077 T1498 C92 P47640 oasu.DirectUpdateHandler2.commi= t end_commit_flush [junit4:junit4] 2> 16078 T1504 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@7fdacda0 main{StandardDirectoryReader(s= egments_1:1)} [junit4:junit4] 2> 16079 T1498 C92 P47640 oasu.SolrCmdDistributor.distrib= Commit Distrib commit to:[StdNode: http://127.0.0.1:47653/solr/collection1/= , StdNode: http://127.0.0.1:47731/solr/collection1/, StdNode: http://127.0.= 0.1:47755/solr/collection1/] [junit4:junit4] 2> C91_STATE=3Dcoll:collection1 core:collection1 props:{= shard=3Dshard1, roles=3Dnull, state=3Dactive, core=3Dcollection1, collectio= n=3Dcollection1, node_name=3D127.0.0.1:47653_solr, base_url=3Dhttp://127.0.= 0.1:47653/solr} [junit4:junit4] 2> 16086 T1507 C91 P47653 oasu.DirectUpdateHandler2.commi= t start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue= ,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> C93_STATE=3Dcoll:collection1 core:collection1 props:{= shard=3Dshard1, roles=3Dnull, state=3Dactive, core=3Dcollection1, collectio= n=3Dcollection1, node_name=3D127.0.0.1:47731_solr, base_url=3Dhttp://127.0.= 0.1:47731/solr} [junit4:junit4] 2> 16086 T1521 C93 P47731 oasu.DirectUpdateHandler2.commi= t start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue= ,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> C94_STATE=3Dcoll:collection1 core:collection1 props:{= shard=3Dshard1, roles=3Dnull, state=3Dactive, core=3Dcollection1, collectio= n=3Dcollection1, node_name=3D127.0.0.1:47755_solr, base_url=3Dhttp://127.0.= 0.1:47755/solr} [junit4:junit4] 2> 16087 T1535 C94 P47755 oasu.DirectUpdateHandler2.commi= t start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue= ,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 16091 T1535 C94 P47755 oass.SolrIndexSearcher. O= pening Searcher@614accfa main [junit4:junit4] 2> 16092 T1535 C94 P47755 oasu.DirectUpdateHandler2.commi= t end_commit_flush [junit4:junit4] 2> 16092 T1507 C91 P47653 oass.SolrIndexSearcher. O= pening Searcher@fa3cad5 main [junit4:junit4] 2> 16092 T1521 C93 P47731 oass.SolrIndexSearcher. O= pening Searcher@3542daea main [junit4:junit4] 2> 16092 T1507 C91 P47653 oasu.DirectUpdateHandler2.commi= t end_commit_flush [junit4:junit4] 2> 16093 T1521 C93 P47731 oasu.DirectUpdateHandler2.commi= t end_commit_flush [junit4:junit4] 2> 16093 T1516 oasc.JmxMonitoredMap.put WARNING Failed to= register info bean: filterCache javax.management.InstanceAlreadyExistsExce= ption: solr/collection1:type=3DfilterCache,id=3Dorg.apache.solr.search.Fast= LRUCache [junit4:junit4] 2> =09at com.sun.jmx.mbeanserver.Repository.addMBean(Repo= sitory.java:467) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.internal_addObject(DefaultMBeanServerInterceptor.java:1520) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.registerDynamicMBean(DefaultMBeanServerInterceptor.java:986) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.registerObject(DefaultMBeanServerInterceptor.java:938) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.registerMBean(DefaultMBeanServerInterceptor.java:330) [junit4:junit4] 2> =09at com.sun.jmx.mbeanserver.JmxMBeanServer.registerM= Bean(JmxMBeanServer.java:516) [junit4:junit4] 2> =09at org.apache.solr.core.JmxMonitoredMap.put(JmxMoni= toredMap.java:140) [junit4:junit4] 2> =09at org.apache.solr.core.JmxMonitoredMap.put(JmxMoni= toredMap.java:51) [junit4:junit4] 2> =09at org.apache.solr.search.SolrIndexSearcher.registe= r(SolrIndexSearcher.java:246) [junit4:junit4] 2> =09at org.apache.solr.core.SolrCore.registerSearcher(S= olrCore.java:1642) [junit4:junit4] 2> =09at org.apache.solr.core.SolrCore.access$000(SolrCor= e.java:121) [junit4:junit4] 2> =09at org.apache.solr.core.SolrCore$7.call(SolrCore.ja= va:1519) [junit4:junit4] 2> =09at java.util.concurrent.FutureTask$Sync.innerRun(Fu= tureTask.java:334) [junit4:junit4] 2> =09at java.util.concurrent.FutureTask.run(FutureTask.j= ava:166) [junit4:junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor.runWorke= r(ThreadPoolExecutor.java:1110) [junit4:junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor$Worker.r= un(ThreadPoolExecutor.java:603) [junit4:junit4] 2> =09at java.lang.Thread.run(Thread.java:679) [junit4:junit4] 2>=20 [junit4:junit4] 2> 16094 T1544 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@614accfa main{StandardDirectoryReader(s= egments_2:2)} [junit4:junit4] 2> 16094 T1530 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@3542daea main{StandardDirectoryReader(s= egments_2:2)} [junit4:junit4] 2> 16094 T1535 C94 P47755 /update {waitSearcher=3Dtrue&co= mmit=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&expungeDeletes=3Dfalse&sof= tCommit=3Dfalse&version=3D2} {commit=3D} 0 7 [junit4:junit4] 2> 16094 T1516 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@fa3cad5 main{StandardDirectoryReader(se= gments_2:2)} [junit4:junit4] 2> 16095 T1521 C93 P47731 /update {waitSearcher=3Dtrue&co= mmit=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&expungeDeletes=3Dfalse&sof= tCommit=3Dfalse&version=3D2} {commit=3D} 0 9 [junit4:junit4] 2> 16095 T1507 C91 P47653 /update {waitSearcher=3Dtrue&co= mmit=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&expungeDeletes=3Dfalse&sof= tCommit=3Dfalse&version=3D2} {commit=3D} 0 9 [junit4:junit4] 2> 16095 T1498 C92 P47640 /update {waitSearcher=3Dtrue&wt= =3Djavabin&commit=3Dtrue&softCommit=3Dfalse&version=3D2} {commit=3D} 0 21 [junit4:junit4] 2> 16096 T1469 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 16099 T1497 C92 P47640 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D0 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16101 T1508 C91 P47653 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D0 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16102 T1522 C93 P47731 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 16104 T1536 C94 P47755 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 18109 T1481 C95 P47632 oass.SolrIndexSearcher. O= pening Searcher@6b38438c realtime [junit4:junit4] 2> 18109 T1481 C95 P47632 /update {wt=3Djavabin&version= =3D2} {deleteByQuery=3D*:* (-1413655553030225920)} 0 2 [junit4:junit4] 2> 18113 T1498 C92 P47640 oass.SolrIndexSearcher. O= pening Searcher@7467caad realtime [junit4:junit4] 2> 18117 T1535 C94 P47755 oass.SolrIndexSearcher. O= pening Searcher@4bb7f124 realtime [junit4:junit4] 2> 18117 T1521 C93 P47731 oass.SolrIndexSearcher. O= pening Searcher@52d9e36d realtime [junit4:junit4] 2> 18117 T1507 C91 P47653 oass.SolrIndexSearcher. O= pening Searcher@54a9b279 realtime [junit4:junit4] 2> 18118 T1535 C94 P47755 REQ /update {update.distrib=3DF= ROMLEADER&_version_=3D-1413655553035468800&update.from=3Dhttp://127.0.0.1:4= 7640/solr/collection1/&wt=3Djavabin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 18118 T1521 C93 P47731 REQ /update {update.distrib=3DF= ROMLEADER&_version_=3D-1413655553035468800&update.from=3Dhttp://127.0.0.1:4= 7640/solr/collection1/&wt=3Djavabin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 18118 T1507 C91 P47653 REQ /update {update.distrib=3DF= ROMLEADER&_version_=3D-1413655553035468800&update.from=3Dhttp://127.0.0.1:4= 7640/solr/collection1/&wt=3Djavabin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 18118 T1498 C92 P47640 /update {wt=3Djavabin&version= =3D2} {deleteByQuery=3D*:* (-1413655553035468800)} 0 6 [junit4:junit4] 2> 18123 T1481 C95 P47632 /update {wt=3Djavabin&version= =3D2} {add=3D[0 (1413655553044905984)]} 0 2 [junit4:junit4] 2> 18134 T1507 C91 P47653 REQ /update {distrib.from=3Dhtt= p://127.0.0.1:47640/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djava= bin&version=3D2} status=3D0 QTime=3D3=20 [junit4:junit4] 2> 18134 T1535 C94 P47755 REQ /update {distrib.from=3Dhtt= p://127.0.0.1:47640/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djava= bin&version=3D2} status=3D0 QTime=3D3=20 [junit4:junit4] 2> 18134 T1521 C93 P47731 REQ /update {distrib.from=3Dhtt= p://127.0.0.1:47640/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djava= bin&version=3D2} status=3D0 QTime=3D3=20 [junit4:junit4] 2> 18134 T1498 C92 P47640 /update {wt=3Djavabin&version= =3D2} {add=3D[0 (1413655553050148864)]} 0 8 [junit4:junit4] 2> 18138 T1481 C95 P47632 /update {wt=3Djavabin&version= =3D2} {add=3D[1 (1413655553061683200)]} 0 1 [junit4:junit4] 2> 18144 T1521 C93 P47731 REQ /update {distrib.from=3Dhtt= p://127.0.0.1:47640/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djava= bin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 18144 T1535 C94 P47755 REQ /update {distrib.from=3Dhtt= p://127.0.0.1:47640/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djava= bin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 18144 T1507 C91 P47653 REQ /update {distrib.from=3Dhtt= p://127.0.0.1:47640/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djava= bin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 18144 T1498 C92 P47640 /update {wt=3Djavabin&version= =3D2} {add=3D[1 (1413655553064828928)]} 0 4 [junit4:junit4] 2> 18148 T1481 C95 P47632 /update {wt=3Djavabin&version= =3D2} {add=3D[2 (1413655553072168960)]} 0 1 [junit4:junit4] 2> 18154 T1521 C93 P47731 REQ /update {distrib.from=3Dhtt= p://127.0.0.1:47640/solr/collection1/&update.distrib=3DFROMLEADER&test.dist= rib.skip.servers=3Dhttp://127.0.0.1:47653/solr/collection1/&wt=3Djavabin&ve= rsion=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 18154 T1535 C94 P47755 REQ /update {distrib.from=3Dhtt= p://127.0.0.1:47640/solr/collection1/&update.distrib=3DFROMLEADER&test.dist= rib.skip.servers=3Dhttp://127.0.0.1:47653/solr/collection1/&wt=3Djavabin&ve= rsion=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 18154 T1498 C92 P47640 /update {test.distrib.skip.serv= ers=3Dhttp://127.0.0.1:47653/solr/collection1/&wt=3Djavabin&version=3D2} {a= dd=3D[2 (1413655553075314688)]} 0 4 [junit4:junit4] 2> 18158 T1481 C95 P47632 /update {wt=3Djavabin&version= =3D2} {add=3D[3 (1413655553082654720)]} 0 1 [junit4:junit4] 2> 18164 T1535 C94 P47755 REQ /update {distrib.from=3Dhtt= p://127.0.0.1:47640/solr/collection1/&update.distrib=3DFROMLEADER&test.dist= rib.skip.servers=3Dhttp://127.0.0.1:47653/solr/collection1/&test.distrib.sk= ip.servers=3Dhttp://127.0.0.1:47731/solr/collection1/&wt=3Djavabin&version= =3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 18164 T1498 C92 P47640 /update {test.distrib.skip.serv= ers=3Dhttp://127.0.0.1:47653/solr/collection1/&test.distrib.skip.servers=3D= http://127.0.0.1:47731/solr/collection1/&wt=3Djavabin&version=3D2} {add=3D[= 3 (1413655553085800448)]} 0 4 [junit4:junit4] 2> 18167 T1481 C95 P47632 oasu.DirectUpdateHandler2.commi= t start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue= ,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 18276 T1481 C95 P47632 oasc.SolrDeletionPolicy.onCommi= t SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/control/data/index,segFN=3Dsegments_1,= generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/control/data/index,segFN=3Dsegments_2,= generation=3D2,filenames=3D[_0_MockVariableIntBlock_0.doc, _0_TestBloomFilt= eredLucene40Postings_0.prx, _0_MockVariableIntBlock_0.skp, _0_MockVariableI= ntBlock_0.pyl, _0_Lucene40WithOrds_0.tii, _0_Lucene40WithOrds_0.frq, _0_Moc= kVariableIntBlock_0.tib, _0_MockVariableIntBlock_0.pos, _0_TestBloomFiltere= dLucene40Postings_0.frq, _0.si, _0_Lucene40WithOrds_0.tib, _0_MockVariableI= ntBlock_0.tii, _0_TestBloomFilteredLucene40Postings_0.tim, _0_MockRandom_0.= tib, _0_TestBloomFilteredLucene40Postings_0.blm, _0_TestBloomFilteredLucene= 40Postings_0.tip, _0.fnm, _0_nrm.cfs, _0_MockRandom_0.skp, _0_MockRandom_0.= sd, _0_MockRandom_0.frq, _0_MockRandom_0.tiv, _0_MockRandom_0.doc, _0_MockR= andom_0.pos, _0_MockVariableIntBlock_0.frq, _0_nrm.cfe, segments_2, _0.fdx,= _0_Lucene40WithOrds_0.prx, _0_MockRandom_0.pyl, _0.fdt] [junit4:junit4] 2> 18276 T1481 C95 P47632 oasc.SolrDeletionPolicy.updateC= ommits newest commit =3D 2 [junit4:junit4] 2> 18289 T1481 C95 P47632 oass.SolrIndexSearcher. O= pening Searcher@4b260384 main [junit4:junit4] 2> 18290 T1481 C95 P47632 oasu.DirectUpdateHandler2.commi= t end_commit_flush [junit4:junit4] 2> 18291 T1492 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@4b260384 main{StandardDirectoryReader(s= egments_2:3:nrt _0(4.0.0.2):C4)} [junit4:junit4] 2> 18291 T1481 C95 P47632 /update {waitSearcher=3Dtrue&wt= =3Djavabin&commit=3Dtrue&softCommit=3Dfalse&version=3D2} {commit=3D} 0 124 [junit4:junit4] 2> 18294 T1498 C92 P47640 oasu.DirectUpdateHandler2.commi= t start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue= ,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 18402 T1498 C92 P47640 oasc.SolrDeletionPolicy.onCommi= t SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/jetty1/index,segFN=3Dsegments_1,genera= tion=3D1,filenames=3D[segments_1] [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/jetty1/index,segFN=3Dsegments_2,genera= tion=3D2,filenames=3D[_0_MockVariableIntBlock_0.doc, _0_TestBloomFilteredLu= cene40Postings_0.prx, _0_MockVariableIntBlock_0.skp, _0_MockVariableIntBloc= k_0.pyl, _0_Lucene40WithOrds_0.tii, _0_Lucene40WithOrds_0.frq, _0_MockVaria= bleIntBlock_0.tib, _0_MockVariableIntBlock_0.pos, _0_TestBloomFilteredLucen= e40Postings_0.frq, _0.si, _0_Lucene40WithOrds_0.tib, _0_MockVariableIntBloc= k_0.tii, _0_TestBloomFilteredLucene40Postings_0.tim, _0_MockRandom_0.tib, _= 0_TestBloomFilteredLucene40Postings_0.blm, _0_TestBloomFilteredLucene40Post= ings_0.tip, _0.fnm, _0_nrm.cfs, _0_MockRandom_0.skp, _0_MockRandom_0.sd, _0= _MockRandom_0.frq, _0_MockRandom_0.tiv, _0_MockRandom_0.doc, _0_MockRandom_= 0.pos, _0_MockVariableIntBlock_0.frq, _0_nrm.cfe, segments_2, _0.fdx, _0_Lu= cene40WithOrds_0.prx, _0_MockRandom_0.pyl, _0.fdt] [junit4:junit4] 2> 18403 T1498 C92 P47640 oasc.SolrDeletionPolicy.updateC= ommits newest commit =3D 2 [junit4:junit4] 2> 18414 T1498 C92 P47640 oass.SolrIndexSearcher. O= pening Searcher@2b146da3 main [junit4:junit4] 2> 18415 T1498 C92 P47640 oasu.DirectUpdateHandler2.commi= t end_commit_flush [junit4:junit4] 2> 18416 T1504 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@2b146da3 main{StandardDirectoryReader(s= egments_2:3:nrt _0(4.0.0.2):C4)} [junit4:junit4] 2> 18417 T1498 C92 P47640 oasu.SolrCmdDistributor.distrib= Commit Distrib commit to:[StdNode: http://127.0.0.1:47653/solr/collection1/= , StdNode: http://127.0.0.1:47731/solr/collection1/, StdNode: http://127.0.= 0.1:47755/solr/collection1/] [junit4:junit4] 2> 18419 T1507 C91 P47653 oasu.DirectUpdateHandler2.commi= t start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue= ,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 18419 T1535 C94 P47755 oasu.DirectUpdateHandler2.commi= t start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue= ,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 18419 T1521 C93 P47731 oasu.DirectUpdateHandler2.commi= t start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue= ,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 18540 T1507 C91 P47653 oasc.SolrDeletionPolicy.onCommi= t SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/jetty2/index,segFN=3Dsegments_2,genera= tion=3D2,filenames=3D[segments_2] [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/jetty2/index,segFN=3Dsegments_3,genera= tion=3D3,filenames=3D[_0_MockVariableIntBlock_0.doc, _0_MockRandom_0.prx, _= 0_TestBloomFilteredLucene40Postings_0.prx, _0_MockVariableIntBlock_0.skp, _= 0_MockVariableIntBlock_0.pyl, _0_Lucene40WithOrds_0.tii, _0_Lucene40WithOrd= s_0.frq, _0_MockVariableIntBlock_0.tib, _0_MockVariableIntBlock_0.pos, _0_T= estBloomFilteredLucene40Postings_0.frq, _0.si, _0_Lucene40WithOrds_0.tib, _= 0_MockVariableIntBlock_0.tii, _0_TestBloomFilteredLucene40Postings_0.tim, _= 0_TestBloomFilteredLucene40Postings_0.tip, _0_TestBloomFilteredLucene40Post= ings_0.blm, _0.fnm, _0_nrm.cfs, _0_MockRandom_0.sd, _0_MockRandom_0.frq, _0= _MockRandom_0.tip, _0_MockVariableIntBlock_0.frq, _0_nrm.cfe, _0.fdx, _0_Lu= cene40WithOrds_0.prx, _0_MockRandom_0.tim, _0.fdt, segments_3] [junit4:junit4] 2> 18541 T1507 C91 P47653 oasc.SolrDeletionPolicy.updateC= ommits newest commit =3D 3 [junit4:junit4] 2> 18541 T1535 C94 P47755 oasc.SolrDeletionPolicy.onCommi= t SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/jetty4/index,segFN=3Dsegments_2,genera= tion=3D2,filenames=3D[segments_2] [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/jetty4/index,segFN=3Dsegments_3,genera= tion=3D3,filenames=3D[_0_MockVariableIntBlock_0.doc, _0_MockRandom_0.prx, _= 0_TestBloomFilteredLucene40Postings_0.prx, _0_MockVariableIntBlock_0.skp, _= 0_MockVariableIntBlock_0.pyl, _0_Lucene40WithOrds_0.tii, _0_Lucene40WithOrd= s_0.frq, _0_MockVariableIntBlock_0.tib, _0_MockVariableIntBlock_0.pos, _0_T= estBloomFilteredLucene40Postings_0.frq, _0.si, _0_Lucene40WithOrds_0.tib, _= 0_MockVariableIntBlock_0.tii, _0_TestBloomFilteredLucene40Postings_0.tim, _= 0_TestBloomFilteredLucene40Postings_0.tip, _0_TestBloomFilteredLucene40Post= ings_0.blm, _0.fnm, _0_nrm.cfs, _0_MockRandom_0.sd, _0_MockRandom_0.frq, _0= _MockRandom_0.tip, _0_MockVariableIntBlock_0.frq, _0_nrm.cfe, _0.fdx, _0_Lu= cene40WithOrds_0.prx, _0_MockRandom_0.tim, _0.fdt, segments_3] [junit4:junit4] 2> 18541 T1535 C94 P47755 oasc.SolrDeletionPolicy.updateC= ommits newest commit =3D 3 [junit4:junit4] 2> 18543 T1521 C93 P47731 oasc.SolrDeletionPolicy.onCommi= t SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/jetty3/index,segFN=3Dsegments_2,genera= tion=3D2,filenames=3D[segments_2] [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/jetty3/index,segFN=3Dsegments_3,genera= tion=3D3,filenames=3D[_0_MockVariableIntBlock_0.doc, _0_TestBloomFilteredLu= cene40Postings_0.prx, _0_MockVariableIntBlock_0.skp, _0_MockVariableIntBloc= k_0.pyl, _0_Lucene40WithOrds_0.tii, _0_Lucene40WithOrds_0.frq, _0_MockVaria= bleIntBlock_0.tib, _0_MockVariableIntBlock_0.pos, _0_TestBloomFilteredLucen= e40Postings_0.frq, _0.si, _0_Lucene40WithOrds_0.tib, _0_MockVariableIntBloc= k_0.tii, _0_TestBloomFilteredLucene40Postings_0.tim, _0_TestBloomFilteredLu= cene40Postings_0.blm, _0_TestBloomFilteredLucene40Postings_0.tip, _0.fnm, _= 0_nrm.cfs, _0_MockRandom_0.skp, _0_MockRandom_0.sd, _0_MockRandom_0.frq, _0= _MockRandom_0.tip, _0_MockRandom_0.doc, _0_MockRandom_0.pos, _0_nrm.cfe, _0= _MockVariableIntBlock_0.frq, _0.fdx, _0_Lucene40WithOrds_0.prx, _0_MockRand= om_0.pyl, _0_MockRandom_0.tim, _0.fdt, segments_3] [junit4:junit4] 2> 18544 T1521 C93 P47731 oasc.SolrDeletionPolicy.updateC= ommits newest commit =3D 3 [junit4:junit4] 2> 18556 T1507 C91 P47653 oass.SolrIndexSearcher. O= pening Searcher@558a4583 main [junit4:junit4] 2> 18556 T1535 C94 P47755 oass.SolrIndexSearcher. O= pening Searcher@7a30635f main [junit4:junit4] 2> 18556 T1507 C91 P47653 oasu.DirectUpdateHandler2.commi= t end_commit_flush [junit4:junit4] 2> 18557 T1535 C94 P47755 oasu.DirectUpdateHandler2.commi= t end_commit_flush [junit4:junit4] 2> 18557 T1544 oasc.JmxMonitoredMap.put WARNING Failed to= register info bean: searcher javax.management.InstanceAlreadyExistsExcepti= on: solr/collection1:type=3Dsearcher,id=3Dorg.apache.solr.search.SolrIndexS= earcher [junit4:junit4] 2> =09at com.sun.jmx.mbeanserver.Repository.addMBean(Repo= sitory.java:467) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.internal_addObject(DefaultMBeanServerInterceptor.java:1520) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.registerDynamicMBean(DefaultMBeanServerInterceptor.java:986) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.registerObject(DefaultMBeanServerInterceptor.java:938) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.registerMBean(DefaultMBeanServerInterceptor.java:330) [junit4:junit4] 2> =09at com.sun.jmx.mbeanserver.JmxMBeanServer.registerM= Bean(JmxMBeanServer.java:516) [junit4:junit4] 2> =09at org.apache.solr.core.JmxMonitoredMap.put(JmxMoni= toredMap.java:140) [junit4:junit4] 2> =09at org.apache.solr.core.JmxMonitoredMap.put(JmxMoni= toredMap.java:51) [junit4:junit4] 2> =09at org.apache.solr.search.SolrIndexSearcher.registe= r(SolrIndexSearcher.java:242) [junit4:junit4] 2> =09at org.apache.solr.core.SolrCore.registerSearcher(S= olrCore.java:1642) [junit4:junit4] 2> =09at org.apache.solr.core.SolrCore.access$000(SolrCor= e.java:121) [junit4:junit4] 2> =09at org.apache.solr.core.SolrCore$7.call(SolrCore.ja= va:1519) [junit4:junit4] 2> =09at java.util.concurrent.FutureTask$Sync.innerRun(Fu= tureTask.java:334) [junit4:junit4] 2> =09at java.util.concurrent.FutureTask.run(FutureTask.j= ava:166) [junit4:junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor.runWorke= r(ThreadPoolExecutor.java:1110) [junit4:junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor$Worker.r= un(ThreadPoolExecutor.java:603) [junit4:junit4] 2> =09at java.lang.Thread.run(Thread.java:679) [junit4:junit4] 2>=20 [junit4:junit4] 2> 18558 T1516 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@558a4583 main{StandardDirectoryReader(s= egments_3:4:nrt _0(4.0.0.2):C2)} [junit4:junit4] 2> 18559 T1507 C91 P47653 /update {waitSearcher=3Dtrue&co= mmit=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&expungeDeletes=3Dfalse&sof= tCommit=3Dfalse&version=3D2} {commit=3D} 0 140 [junit4:junit4] 2> 18559 T1544 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@7a30635f main{StandardDirectoryReader(s= egments_3:4:nrt _0(4.0.0.2):C4)} [junit4:junit4] 2> 18559 T1535 C94 P47755 /update {waitSearcher=3Dtrue&co= mmit=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&expungeDeletes=3Dfalse&sof= tCommit=3Dfalse&version=3D2} {commit=3D} 0 140 [junit4:junit4] 2> 18560 T1521 C93 P47731 oass.SolrIndexSearcher. O= pening Searcher@1b04b03d main [junit4:junit4] 2> 18560 T1521 C93 P47731 oasu.DirectUpdateHandler2.commi= t end_commit_flush [junit4:junit4] 2> 18562 T1530 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@1b04b03d main{StandardDirectoryReader(s= egments_3:4:nrt _0(4.0.0.2):C3)} [junit4:junit4] 2> 18562 T1521 C93 P47731 /update {waitSearcher=3Dtrue&co= mmit=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&expungeDeletes=3Dfalse&sof= tCommit=3Dfalse&version=3D2} {commit=3D} 0 143 [junit4:junit4] 2> 18563 T1498 C92 P47640 /update {waitSearcher=3Dtrue&wt= =3Djavabin&commit=3Dtrue&softCommit=3Dfalse&version=3D2} {commit=3D} 0 269 [junit4:junit4] 2> 18563 T1469 oasc.AbstractDistribZkTestBase.waitForReco= veriesToFinish Wait for recoveries to finish - collection: collection1 fail= OnTimeout:true timeout (sec):1800 [junit4:junit4] 2> 18564 T1469 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> check const of shard1 [junit4:junit4] 2> client0 [junit4:junit4] 2> PROPS:127.0.0.1:47640_solr_collection1:{"shard":"shard= 1","roles":null,"state":"active","core":"collection1","collection":"collect= ion1","node_name":"127.0.0.1:47640_solr","base_url":"http://127.0.0.1:47640= /solr","leader":"true"} [junit4:junit4] 2> 18567 T1497 C92 P47640 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D4 status=3D0 QTime=3D0=20 [junit4:junit4] 2> live:true [junit4:junit4] 2> num:4 [junit4:junit4] 2>=20 [junit4:junit4] 2> client1 [junit4:junit4] 2> PROPS:127.0.0.1:47653_solr_collection1:{"shard":"shard= 1","roles":null,"state":"active","core":"collection1","collection":"collect= ion1","node_name":"127.0.0.1:47653_solr","base_url":"http://127.0.0.1:47653= /solr"} [junit4:junit4] 2> 18570 T1508 C91 P47653 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D2 status=3D0 QTime=3D0=20 [junit4:junit4] 2> live:true [junit4:junit4] 2> num:2 [junit4:junit4] 2>=20 [junit4:junit4] 2> ######shard1 is not consistent. Got 4 from http://127= .0.0.1:47640/solr/collection1lastClient and got 2 from http://127.0.0.1:476= 53/solr/collection1 [junit4:junit4] 2> 18573 T1497 C92 P47640 REQ /select {fl=3Did,_version_&= sort=3Did+asc&q=3D*:*&distrib=3Dfalse&wt=3Djavabin&rows=3D1000&version=3D2}= hits=3D4 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 18576 T1508 C91 P47653 REQ /select {fl=3Did,_version_&= sort=3Did+asc&q=3D*:*&distrib=3Dfalse&wt=3Djavabin&rows=3D1000&version=3D2}= hits=3D2 status=3D0 QTime=3D0=20 [junit4:junit4] 2> ######http://127.0.0.1:47640/solr/collection1: {numFou= nd=3D4,start=3D0,docs=3D[SolrDocument{id=3D0, _version_=3D14136555530501488= 64}, SolrDocument{id=3D1, _version_=3D1413655553064828928}, SolrDocument{id= =3D2, _version_=3D1413655553075314688}, SolrDocument{id=3D3, _version_=3D14= 13655553085800448}]} [junit4:junit4] 2> ######http://127.0.0.1:47653/solr/collection1: {numFou= nd=3D2,start=3D0,docs=3D[SolrDocument{id=3D0, _version_=3D14136555530501488= 64}, SolrDocument{id=3D1, _version_=3D1413655553064828928}]} [junit4:junit4] 2> ###### sizes=3D4,2 [junit4:junit4] 2> ###### Only in http://127.0.0.1:47640/solr/collection1= : [{id=3D2, _version_=3D1413655553075314688}, {id=3D3, _version_=3D14136555= 53085800448}] [junit4:junit4] 2> client2 [junit4:junit4] 2> PROPS:127.0.0.1:47731_solr_collection1:{"shard":"shard= 1","roles":null,"state":"active","core":"collection1","collection":"collect= ion1","node_name":"127.0.0.1:47731_solr","base_url":"http://127.0.0.1:47731= /solr"} [junit4:junit4] 2> 18579 T1522 C93 P47731 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D3 status=3D0 QTime=3D0=20 [junit4:junit4] 2> live:true [junit4:junit4] 2> num:3 [junit4:junit4] 2>=20 [junit4:junit4] 2> client3 [junit4:junit4] 2> PROPS:127.0.0.1:47755_solr_collection1:{"shard":"shard= 1","roles":null,"state":"active","core":"collection1","collection":"collect= ion1","node_name":"127.0.0.1:47755_solr","base_url":"http://127.0.0.1:47755= /solr"} [junit4:junit4] 2> 18582 T1536 C94 P47755 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D4 status=3D0 QTime=3D0=20 [junit4:junit4] 2> live:true [junit4:junit4] 2> num:4 [junit4:junit4] 2>=20 [junit4:junit4] 2> 18583 T1469 oascsi.HttpClientUtil.createClient Creatin= g new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&f= ollowRedirects=3Dfalse [junit4:junit4] 2> 18597 T1523 oasha.CollectionsHandler.handleSyncShardAc= tion Syncing shard : action=3DSYNCSHARD&collection=3Dcollection1&shard=3Dsh= ard1&wt=3Djavabin&version=3D2 [junit4:junit4] 2> 18598 T1523 oascsi.HttpClientUtil.createClient Creatin= g new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&f= ollowRedirects=3Dfalse [junit4:junit4] 2> 18607 T1501 oasha.CoreAdminHandler.handleRequestSyncAc= tion I have been requested to sync up my shard [junit4:junit4] 2> 18607 T1501 oasc.SyncStrategy.sync Sync replicas to ht= tp://127.0.0.1:47640/solr/collection1/ [junit4:junit4] 2> 18608 T1501 oasu.PeerSync.sync PeerSync: core=3Dcollec= tion1 url=3Dhttp://127.0.0.1:47640/solr START replicas=3D[http://127.0.0.1:= 47653/solr/collection1/, http://127.0.0.1:47731/solr/collection1/, http://1= 27.0.0.1:47755/solr/collection1/] nUpdates=3D100 [junit4:junit4] 2> 18610 T1509 C91 P47653 REQ /get {distrib=3Dfalse&qt=3D= /get&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 18610 T1524 C93 P47731 REQ /get {distrib=3Dfalse&qt=3D= /get&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 18610 T1537 C94 P47755 REQ /get {distrib=3Dfalse&qt=3D= /get&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 18610 T1501 oasu.PeerSync.handleVersions PeerSync: cor= e=3Dcollection1 url=3Dhttp://127.0.0.1:47640/solr Received 3 versions from= 127.0.0.1:47653/solr/collection1/ [junit4:junit4] 2> 18611 T1501 oasu.PeerSync.handleVersions PeerSync: cor= e=3Dcollection1 url=3Dhttp://127.0.0.1:47640/solr Our versions are newer. = ourLowThreshold=3D1413655553035468800 otherHigh=3D1413655553064828928 [junit4:junit4] 2> 18611 T1501 oasu.PeerSync.handleVersions PeerSync: cor= e=3Dcollection1 url=3Dhttp://127.0.0.1:47640/solr Received 4 versions from= 127.0.0.1:47731/solr/collection1/ [junit4:junit4] 2> 18611 T1501 oasu.PeerSync.handleVersions PeerSync: cor= e=3Dcollection1 url=3Dhttp://127.0.0.1:47640/solr Our versions are newer. = ourLowThreshold=3D1413655553035468800 otherHigh=3D1413655553075314688 [junit4:junit4] 2> 18612 T1501 oasu.PeerSync.handleVersions PeerSync: cor= e=3Dcollection1 url=3Dhttp://127.0.0.1:47640/solr Received 5 versions from= 127.0.0.1:47755/solr/collection1/ [junit4:junit4] 2> 18612 T1501 oasu.PeerSync.handleVersions PeerSync: cor= e=3Dcollection1 url=3Dhttp://127.0.0.1:47640/solr Our versions are newer. = ourLowThreshold=3D1413655553035468800 otherHigh=3D1413655553075314688 [junit4:junit4] 2> 18612 T1501 oasu.PeerSync.sync PeerSync: core=3Dcollec= tion1 url=3Dhttp://127.0.0.1:47640/solr DONE. sync succeeded [junit4:junit4] 2> 18612 T1501 oasc.SyncStrategy.syncReplicas Sync Succes= s - now sync replicas to me [junit4:junit4] 2> 18613 T1501 oasc.SyncStrategy.syncToMe http://127.0.0.= 1:47640/solr/collection1/: try and ask http://127.0.0.1:47653/solr/collecti= on1/ to sync [junit4:junit4] 2> 18613 T1501 oasc.SyncStrategy.syncToMe http://127.0.0.= 1:47640/solr/collection1/: try and ask http://127.0.0.1:47731/solr/collecti= on1/ to sync [junit4:junit4] 2> 18614 T1501 oasc.SyncStrategy.syncToMe http://127.0.0.= 1:47640/solr/collection1/: try and ask http://127.0.0.1:47755/solr/collecti= on1/ to sync [junit4:junit4] 2> 18615 T1510 C91 P47653 oasu.PeerSync.sync PeerSync: co= re=3Dcollection1 url=3Dhttp://127.0.0.1:47653/solr START replicas=3D[http:/= /127.0.0.1:47640/solr/collection1/] nUpdates=3D100 [junit4:junit4] 2> 18615 T1525 C93 P47731 oasu.PeerSync.sync PeerSync: co= re=3Dcollection1 url=3Dhttp://127.0.0.1:47731/solr START replicas=3D[http:/= /127.0.0.1:47640/solr/collection1/] nUpdates=3D100 [junit4:junit4] 2> 18616 T1538 C94 P47755 oasu.PeerSync.sync PeerSync: co= re=3Dcollection1 url=3Dhttp://127.0.0.1:47755/solr START replicas=3D[http:/= /127.0.0.1:47640/solr/collection1/] nUpdates=3D100 [junit4:junit4] 2> 18617 T1500 C92 P47640 REQ /get {distrib=3Dfalse&qt=3D= /get&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 18617 T1496 C92 P47640 REQ /get {distrib=3Dfalse&qt=3D= /get&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 18618 T1525 C93 P47731 oasu.PeerSync.handleVersions Pe= erSync: core=3Dcollection1 url=3Dhttp://127.0.0.1:47731/solr Received 5 ve= rsions from 127.0.0.1:47640/solr/collection1/ [junit4:junit4] 2> 18618 T1538 C94 P47755 oasu.PeerSync.handleVersions Pe= erSync: core=3Dcollection1 url=3Dhttp://127.0.0.1:47755/solr Received 5 ve= rsions from 127.0.0.1:47640/solr/collection1/ [junit4:junit4] 2> 18618 T1495 C92 P47640 REQ /get {distrib=3Dfalse&qt=3D= /get&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 18618 T1538 C94 P47755 oasu.PeerSync.handleVersions Pe= erSync: core=3Dcollection1 url=3Dhttp://127.0.0.1:47755/solr Our versions = are newer. ourLowThreshold=3D1413655553035468800 otherHigh=3D14136555530753= 14688 [junit4:junit4] 2> 18618 T1525 C93 P47731 oasu.PeerSync.requestUpdates Pe= erSync: core=3Dcollection1 url=3Dhttp://127.0.0.1:47731/solr Requesting upd= ates from 127.0.0.1:47640/solr/collection1/n=3D1 versions=3D[14136555530858= 00448] [junit4:junit4] 2> 18619 T1510 C91 P47653 oasu.PeerSync.handleVersions Pe= erSync: core=3Dcollection1 url=3Dhttp://127.0.0.1:47653/solr Received 5 ve= rsions from 127.0.0.1:47640/solr/collection1/ [junit4:junit4] 2> 18619 T1538 C94 P47755 oasu.PeerSync.sync PeerSync: co= re=3Dcollection1 url=3Dhttp://127.0.0.1:47755/solr DONE. sync succeeded [junit4:junit4] 2> 18619 T1510 C91 P47653 oasu.PeerSync.requestUpdates Pe= erSync: core=3Dcollection1 url=3Dhttp://127.0.0.1:47653/solr Requesting upd= ates from 127.0.0.1:47640/solr/collection1/n=3D2 versions=3D[14136555530858= 00448, 1413655553075314688] [junit4:junit4] 2> 18619 T1538 C94 P47755 REQ /get {sync=3Dhttp://127.0.0= .1:47640/solr/collection1/&getVersions=3D100&distrib=3Dfalse&qt=3D/get&wt= =3Djavabin&version=3D2} status=3D0 QTime=3D3=20 [junit4:junit4] 2> 18620 T1501 oasc.SyncStrategy.syncToMe http://127.0.0.= 1:47640/solr/collection1/: sync completed with http://127.0.0.1:47755/solr= /collection1/ [junit4:junit4] 2> 18621 T1495 C92 P47640 REQ /get {distrib=3Dfalse&getUp= dates=3D1413655553085800448&qt=3D/get&wt=3Djavabin&version=3D2} status=3D0 = QTime=3D0=20 [junit4:junit4] 2> 18621 T1496 C92 P47640 REQ /get {distrib=3Dfalse&getUp= dates=3D1413655553085800448,1413655553075314688&qt=3D/get&wt=3Djavabin&vers= ion=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 18626 T1525 C93 P47731 oasu.PeerSync.sync PeerSync: co= re=3Dcollection1 url=3Dhttp://127.0.0.1:47731/solr DONE. sync succeeded [junit4:junit4] 2> 18626 T1525 C93 P47731 REQ /get {sync=3Dhttp://127.0.0= .1:47640/solr/collection1/&getVersions=3D100&distrib=3Dfalse&qt=3D/get&wt= =3Djavabin&version=3D2} status=3D0 QTime=3D11=20 [junit4:junit4] 2> 18627 T1510 C91 P47653 oasu.PeerSync.sync PeerSync: co= re=3Dcollection1 url=3Dhttp://127.0.0.1:47653/solr DONE. sync succeeded [junit4:junit4] 2> 18627 T1501 oasc.SyncStrategy.syncToMe http://127.0.0.= 1:47640/solr/collection1/: sync completed with http://127.0.0.1:47731/solr= /collection1/ [junit4:junit4] 2> 18627 T1510 C91 P47653 REQ /get {sync=3Dhttp://127.0.0= .1:47640/solr/collection1/&getVersions=3D100&distrib=3Dfalse&qt=3D/get&wt= =3Djavabin&version=3D2} status=3D0 QTime=3D12=20 [junit4:junit4] 2> 18628 T1501 oasc.SyncStrategy.syncToMe http://127.0.0.= 1:47640/solr/collection1/: sync completed with http://127.0.0.1:47653/solr= /collection1/ [junit4:junit4] 2> 18628 T1469 oasc.AbstractFullDistribZkTestBase.waitFor= ThingsToLevelOut Wait for recoveries to finish - wait 15 for each attempt [junit4:junit4] 2> 18629 T1469 oasc.AbstractDistribZkTestBase.waitForReco= veriesToFinish Wait for recoveries to finish - collection: collection1 fail= OnTimeout:true timeout (sec):15 [junit4:junit4] 2> 18630 T1469 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 18632 T1481 C95 P47632 oasu.DirectUpdateHandler2.commi= t start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue= ,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 18633 T1481 C95 P47632 oass.SolrIndexSearcher. O= pening Searcher@42b9cff8 main [junit4:junit4] 2> 18634 T1481 C95 P47632 oasu.DirectUpdateHandler2.commi= t end_commit_flush [junit4:junit4] 2> 18635 T1492 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@42b9cff8 main{StandardDirectoryReader(s= egments_2:3:nrt _0(4.0.0.2):C4)} [junit4:junit4] 2> 18636 T1481 C95 P47632 /update {waitSearcher=3Dtrue&wt= =3Djavabin&commit=3Dtrue&softCommit=3Dfalse&version=3D2} {commit=3D} 0 4 [junit4:junit4] 2> 18638 T1498 C92 P47640 oasu.DirectUpdateHandler2.commi= t start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue= ,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 18639 T1498 C92 P47640 oass.SolrIndexSearcher. O= pening Searcher@4c21dfd8 main [junit4:junit4] 2> 18640 T1498 C92 P47640 oasu.DirectUpdateHandler2.commi= t end_commit_flush [junit4:junit4] 2> 18641 T1504 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@4c21dfd8 main{StandardDirectoryReader(s= egments_2:3:nrt _0(4.0.0.2):C4)} [junit4:junit4] 2> 18642 T1498 C92 P47640 oasu.SolrCmdDistributor.distrib= Commit Distrib commit to:[StdNode: http://127.0.0.1:47653/solr/collection1/= , StdNode: http://127.0.0.1:47731/solr/collection1/, StdNode: http://127.0.= 0.1:47755/solr/collection1/] [junit4:junit4] 2> 18644 T1535 C94 P47755 oasu.DirectUpdateHandler2.commi= t start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue= ,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 18644 T1507 C91 P47653 oasu.DirectUpdateHandler2.commi= t start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue= ,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 18644 T1521 C93 P47731 oasu.DirectUpdateHandler2.commi= t start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtrue= ,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 18645 T1535 C94 P47755 oass.SolrIndexSearcher. O= pening Searcher@39dfc9c6 main [junit4:junit4] 2> 18646 T1535 C94 P47755 oasu.DirectUpdateHandler2.commi= t end_commit_flush [junit4:junit4] 2> 18647 T1544 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@39dfc9c6 main{StandardDirectoryReader(s= egments_3:4:nrt _0(4.0.0.2):C4)} [junit4:junit4] 2> 18648 T1535 C94 P47755 /update {waitSearcher=3Dtrue&co= mmit=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&expungeDeletes=3Dfalse&sof= tCommit=3Dfalse&version=3D2} {commit=3D} 0 4 [junit4:junit4] 2> 18776 T1521 C93 P47731 oasc.SolrDeletionPolicy.onCommi= t SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/jetty3/index,segFN=3Dsegments_3,genera= tion=3D3,filenames=3D[_0_MockVariableIntBlock_0.doc, _0_TestBloomFilteredLu= cene40Postings_0.prx, _0_MockVariableIntBlock_0.skp, _0_MockVariableIntBloc= k_0.pyl, _0_Lucene40WithOrds_0.tii, _0_Lucene40WithOrds_0.frq, _0_MockVaria= bleIntBlock_0.tib, _0_MockVariableIntBlock_0.pos, _0_TestBloomFilteredLucen= e40Postings_0.frq, _0.si, _0_Lucene40WithOrds_0.tib, _0_MockVariableIntBloc= k_0.tii, _0_TestBloomFilteredLucene40Postings_0.tim, _0_TestBloomFilteredLu= cene40Postings_0.blm, _0_TestBloomFilteredLucene40Postings_0.tip, _0.fnm, _= 0_nrm.cfs, _0_MockRandom_0.skp, _0_MockRandom_0.sd, _0_MockRandom_0.frq, _0= _MockRandom_0.tip, _0_MockRandom_0.doc, _0_MockRandom_0.pos, _0_nrm.cfe, _0= _MockVariableIntBlock_0.frq, _0.fdx, _0_Lucene40WithOrds_0.prx, _0_MockRand= om_0.pyl, _0_MockRandom_0.tim, _0.fdt, segments_3] [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/jetty3/index,segFN=3Dsegments_4,genera= tion=3D4,filenames=3D[_0_MockVariableIntBlock_0.skp, _1.fnm, _0_Lucene40Wit= hOrds_0.tii, _0_Lucene40WithOrds_0.frq, _1_Lucene40WithOrds_0.tib, _0_TestB= loomFilteredLucene40Postings_0.frq, _1_Lucene40WithOrds_0.tii, _0_Lucene40W= ithOrds_0.tib, _0_TestBloomFilteredLucene40Postings_0.tim, _1_Lucene40WithO= rds_0.frq, _1_MockRandom_0.tiv, _0_TestBloomFilteredLucene40Postings_0.tip,= _0_TestBloomFilteredLucene40Postings_0.blm, _0.fnm, _1_MockVariableIntBloc= k_0.tii, _1_MockRandom_0.sd, _0_MockRandom_0.skp, _1_TestBloomFilteredLucen= e40Postings_0.frq, _1_MockVariableIntBlock_0.tib, _1.fdx, _0_MockRandom_0.f= rq, _1_MockRandom_0.tib, _1.fdt, _0_MockRandom_0.doc, _0_MockVariableIntBlo= ck_0.frq, _0_MockRandom_0.pos, _1_MockRandom_0.frq, _1_MockVariableIntBlock= _0.skp, _0_MockVariableIntBlock_0.doc, _0_TestBloomFilteredLucene40Postings= _0.prx, _1_Lucene40WithOrds_0.prx, _1_MockVariableIntBlock_0.pyl, _1_TestBl= oomFilteredLucene40Postings_0.blm, _0_MockVariableIntBlock_0.pyl, _1_TestBl= oomFilteredLucene40Postings_0.tip, _0_MockVariableIntBlock_0.tib, _1_MockVa= riableIntBlock_0.doc, _0_MockVariableIntBlock_0.pos, _0.si, _1_TestBloomFil= teredLucene40Postings_0.tim, _0_MockVariableIntBlock_0.tii, _1_nrm.cfs, _1_= TestBloomFilteredLucene40Postings_0.prx, _1_MockVariableIntBlock_0.pos, _0_= nrm.cfs, _1_nrm.cfe, _1_MockVariableIntBlock_0.frq, _1_MockRandom_0.prx, _0= _MockRandom_0.tip, _0_MockRandom_0.sd, _1.si, _0_nrm.cfe, _0.fdx, _0_Lucene= 40WithOrds_0.prx, _0_MockRandom_0.pyl, _0_MockRandom_0.tim, _0.fdt, segment= s_4] [junit4:junit4] 2> 18777 T1521 C93 P47731 oasc.SolrDeletionPolicy.updateC= ommits newest commit =3D 4 [junit4:junit4] 2> 18779 T1507 C91 P47653 oasc.SolrDeletionPolicy.onCommi= t SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/jetty2/index,segFN=3Dsegments_3,genera= tion=3D3,filenames=3D[_0_MockVariableIntBlock_0.doc, _0_MockRandom_0.prx, _= 0_TestBloomFilteredLucene40Postings_0.prx, _0_MockVariableIntBlock_0.skp, _= 0_MockVariableIntBlock_0.pyl, _0_Lucene40WithOrds_0.tii, _0_Lucene40WithOrd= s_0.frq, _0_MockVariableIntBlock_0.tib, _0_MockVariableIntBlock_0.pos, _0_T= estBloomFilteredLucene40Postings_0.frq, _0.si, _0_Lucene40WithOrds_0.tib, _= 0_MockVariableIntBlock_0.tii, _0_TestBloomFilteredLucene40Postings_0.tim, _= 0_TestBloomFilteredLucene40Postings_0.tip, _0_TestBloomFilteredLucene40Post= ings_0.blm, _0.fnm, _0_nrm.cfs, _0_MockRandom_0.sd, _0_MockRandom_0.frq, _0= _MockRandom_0.tip, _0_MockVariableIntBlock_0.frq, _0_nrm.cfe, _0.fdx, _0_Lu= cene40WithOrds_0.prx, _0_MockRandom_0.tim, _0.fdt, segments_3] [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/jetty2/index,segFN=3Dsegments_4,genera= tion=3D4,filenames=3D[_0_MockRandom_0.prx, _0_MockVariableIntBlock_0.skp, _= 1.fnm, _1_MockRandom_0.pos, _0_Lucene40WithOrds_0.tii, _0_Lucene40WithOrds_= 0.frq, _1_Lucene40WithOrds_0.tib, _0_TestBloomFilteredLucene40Postings_0.fr= q, _1_Lucene40WithOrds_0.tii, _0_Lucene40WithOrds_0.tib, _0_TestBloomFilter= edLucene40Postings_0.tim, _1_Lucene40WithOrds_0.frq, _0_TestBloomFilteredLu= cene40Postings_0.blm, _0_TestBloomFilteredLucene40Postings_0.tip, _0.fnm, _= 1_MockVariableIntBlock_0.tii, _1_MockRandom_0.sd, _1_TestBloomFilteredLucen= e40Postings_0.frq, _1_MockRandom_0.tii, _1_MockVariableIntBlock_0.tib, _1.f= dx, _0_MockRandom_0.frq, _1_MockRandom_0.tib, _1.fdt, _0_MockVariableIntBlo= ck_0.frq, _1_MockRandom_0.doc, _1_MockRandom_0.frq, _1_MockVariableIntBlock= _0.skp, _0_MockVariableIntBlock_0.doc, _0_TestBloomFilteredLucene40Postings= _0.prx, _1_Lucene40WithOrds_0.prx, _1_TestBloomFilteredLucene40Postings_0.b= lm, _1_MockVariableIntBlock_0.pyl, _0_MockVariableIntBlock_0.pyl, _1_TestBl= oomFilteredLucene40Postings_0.tip, _0_MockVariableIntBlock_0.tib, _1_MockVa= riableIntBlock_0.doc, _0_MockVariableIntBlock_0.pos, _0.si, _1_TestBloomFil= teredLucene40Postings_0.tim, _0_MockVariableIntBlock_0.tii, _1_nrm.cfs, _1_= TestBloomFilteredLucene40Postings_0.prx, _1_MockVariableIntBlock_0.pos, _0_= nrm.cfs, _1_MockRandom_0.skp, _1_nrm.cfe, _1_MockVariableIntBlock_0.frq, _0= _MockRandom_0.tip, _0_MockRandom_0.sd, _1_MockRandom_0.pyl, _1.si, _0_nrm.c= fe, _0.fdx, _0_Lucene40WithOrds_0.prx, _0_MockRandom_0.tim, _0.fdt, segment= s_4] [junit4:junit4] 2> 18780 T1507 C91 P47653 oasc.SolrDeletionPolicy.updateC= ommits newest commit =3D 4 [junit4:junit4] 2> 18791 T1521 C93 P47731 oass.SolrIndexSearcher. O= pening Searcher@37b96ed main [junit4:junit4] 2> 18792 T1521 C93 P47731 oasu.DirectUpdateHandler2.commi= t end_commit_flush [junit4:junit4] 2> 18793 T1530 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@37b96ed main{StandardDirectoryReader(se= gments_4:6:nrt _0(4.0.0.2):C3 _1(4.0.0.2):C1)} [junit4:junit4] 2> 18794 T1521 C93 P47731 /update {waitSearcher=3Dtrue&co= mmit=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&expungeDeletes=3Dfalse&sof= tCommit=3Dfalse&version=3D2} {commit=3D} 0 150 [junit4:junit4] 2> 18794 T1507 C91 P47653 oass.SolrIndexSearcher. O= pening Searcher@76de11d5 main [junit4:junit4] 2> 18795 T1507 C91 P47653 oasu.DirectUpdateHandler2.commi= t end_commit_flush [junit4:junit4] 2> 18797 T1516 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@76de11d5 main{StandardDirectoryReader(s= egments_4:6:nrt _0(4.0.0.2):C2 _1(4.0.0.2):C2)} [junit4:junit4] 2> 18797 T1507 C91 P47653 /update {waitSearcher=3Dtrue&co= mmit=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&expungeDeletes=3Dfalse&sof= tCommit=3Dfalse&version=3D2} {commit=3D} 0 153 [junit4:junit4] 2> 18798 T1498 C92 P47640 /update {waitSearcher=3Dtrue&wt= =3Djavabin&commit=3Dtrue&softCommit=3Dfalse&version=3D2} {commit=3D} 0 160 [junit4:junit4] 2> 18799 T1469 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 18802 T1497 C92 P47640 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D4 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 18805 T1508 C91 P47653 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D4 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 18808 T1522 C93 P47731 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D4 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 18811 T1536 C94 P47755 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D4 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 20818 T1482 C95 P47632 REQ /select {fl=3Did,score&shar= d.url=3D127.0.0.1:47632/solr/collection1/&NOW=3D1348166995878&start=3D0&q= =3D*:*&distrib=3Dfalse&isShard=3Dtrue&wt=3Djavabin&fsv=3Dtrue&rows=3D10&ver= sion=3D2} hits=3D4 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 20822 T1482 C95 P47632 REQ /select {shard.url=3D127.0.= 0.1:47632/solr/collection1/&NOW=3D1348166995878&q=3D*:*&ids=3D3,2,1,0&distr= ib=3Dfalse&isShard=3Dtrue&wt=3Djavabin&version=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 20823 T1481 C95 P47632 REQ /select {wt=3Djavabin&q=3D*= :*&version=3D2} hits=3D4 status=3D0 QTime=3D8=20 [junit4:junit4] 2> Control Docs:4 [junit4:junit4] 2> 20825 T1469 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> check const of shard1 [junit4:junit4] 2> client0 [junit4:junit4] 2> PROPS:127.0.0.1:47640_solr_collection1:{"shard":"shard= 1","roles":null,"state":"active","core":"collection1","collection":"collect= ion1","node_name":"127.0.0.1:47640_solr","base_url":"http://127.0.0.1:47640= /solr","leader":"true"} [junit4:junit4] 2> 20828 T1497 C92 P47640 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D4 status=3D0 QTime=3D0=20 [junit4:junit4] 2> live:true [junit4:junit4] 2> num:4 [junit4:junit4] 2>=20 [junit4:junit4] 2> client1 [junit4:junit4] 2> PROPS:127.0.0.1:47653_solr_collection1:{"shard":"shard= 1","roles":null,"state":"active","core":"collection1","collection":"collect= ion1","node_name":"127.0.0.1:47653_solr","base_url":"http://127.0.0.1:47653= /solr"} [junit4:junit4] 2> 20831 T1508 C91 P47653 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D4 status=3D0 QTime=3D0=20 [junit4:junit4] 2> live:true [junit4:junit4] 2> num:4 [junit4:junit4] 2>=20 [junit4:junit4] 2> client2 [junit4:junit4] 2> PROPS:127.0.0.1:47731_solr_collection1:{"shard":"shard= 1","roles":null,"state":"active","core":"collection1","collection":"collect= ion1","node_name":"127.0.0.1:47731_solr","base_url":"http://127.0.0.1:47731= /solr"} [junit4:junit4] 2> 20834 T1522 C93 P47731 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D4 status=3D0 QTime=3D0=20 [junit4:junit4] 2> live:true [junit4:junit4] 2> num:4 [junit4:junit4] 2>=20 [junit4:junit4] 2> client3 [junit4:junit4] 2> PROPS:127.0.0.1:47755_solr_collection1:{"shard":"shard= 1","roles":null,"state":"active","core":"collection1","collection":"collect= ion1","node_name":"127.0.0.1:47755_solr","base_url":"http://127.0.0.1:47755= /solr"} [junit4:junit4] 2> 20837 T1536 C94 P47755 REQ /select {distrib=3Dfalse&wt= =3Djavabin&q=3D*:*&version=3D2} hits=3D4 status=3D0 QTime=3D0=20 [junit4:junit4] 2> live:true [junit4:junit4] 2> num:4 [junit4:junit4] 2>=20 [junit4:junit4] 2> 20841 T1526 C93 P47731 REQ /select {fl=3Did,score&shar= d.url=3D127.0.0.1:47640/solr/collection1/|127.0.0.1:47653/solr/collection1/= |127.0.0.1:47731/solr/collection1/|127.0.0.1:47755/solr/collection1/&NOW=3D= 1348166995902&start=3D0&q=3D*:*&distrib=3Dfalse&isShard=3Dtrue&wt=3Djavabin= &fsv=3Dtrue&rows=3D10&version=3D2} hits=3D4 status=3D0 QTime=3D1=20 [junit4:junit4] 2> 20844 T1526 C93 P47731 REQ /select {shard.url=3D127.0.= 0.1:47640/solr/collection1/|127.0.0.1:47653/solr/collection1/|127.0.0.1:477= 31/solr/collection1/|127.0.0.1:47755/solr/collection1/&NOW=3D1348166995902&= q=3D*:*&ids=3D3,2,1,0&distrib=3Dfalse&isShard=3Dtrue&wt=3Djavabin&version= =3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 20844 T1511 C91 P47653 REQ /select {wt=3Djavabin&q=3D*= :*&version=3D2} hits=3D4 status=3D0 QTime=3D5=20 [junit4:junit4] 2> 20850 T1481 C95 P47632 /update {wt=3Djavabin&version= =3D2} {add=3D[4 (1413655555903324160)]} 0 3 [junit4:junit4] 2> 20862 T1507 C91 P47653 REQ /update {distrib.from=3Dhtt= p://127.0.0.1:47640/solr/collection1/&update.distrib=3DFROMLEADER&test.dist= rib.skip.servers=3Dhttp://127.0.0.1:47731/solr/collection1/&wt=3Djavabin&ve= rsion=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 20862 T1535 C94 P47755 REQ /update {distrib.from=3Dhtt= p://127.0.0.1:47640/solr/collection1/&update.distrib=3DFROMLEADER&test.dist= rib.skip.servers=3Dhttp://127.0.0.1:47731/solr/collection1/&wt=3Djavabin&ve= rsion=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 20863 T1498 C92 P47640 /update {test.distrib.skip.serv= ers=3Dhttp://127.0.0.1:47731/solr/collection1/&wt=3Djavabin&version=3D2} {a= dd=3D[4 (1413655555909615616)]} 0 10 [junit4:junit4] 2> 20863 T1469 oasc.ChaosMonkey.monkeyLog monkey: kill sh= ard! 47640 [junit4:junit4] 2> 20864 T1469 oasc.CoreContainer.shutdown Shutting down = CoreContainer instance=3D1155221157 [junit4:junit4] 2> 20865 T1469 oasc.SolrCore.close [collection1] CLOSING= SolrCore org.apache.solr.core.SolrCore@1fa7ae9e [junit4:junit4] 2> 20883 T1469 oasc.SolrCore.closeSearcher [collection1] = Closing main searcher on request. [junit4:junit4] 2> 20883 T1469 oasu.DirectUpdateHandler2.close closing Di= rectUpdateHandler2{commits=3D6,autocommits=3D0,soft autocommits=3D0,optimiz= es=3D0,rollbacks=3D0,expungeDeletes=3D0,docsPending=3D1,adds=3D1,deletesByI= d=3D0,deletesByQuery=3D0,errors=3D0,cumulative_adds=3D5,cumulative_deletesB= yId=3D0,cumulative_deletesByQuery=3D1,cumulative_errors=3D0} [junit4:junit4] 2> 20883 T1469 oasu.DefaultSolrCoreState.decref SolrCoreS= tate ref count has reached 0 - closing IndexWriter [junit4:junit4] 2> 21012 T1469 C92 P47640 oasc.SolrDeletionPolicy.onCommi= t SolrDeletionPolicy.onCommit: commits:num=3D3 [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/jetty1/index,segFN=3Dsegments_1,genera= tion=3D1,filenames=3D[segments_1] [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/jetty1/index,segFN=3Dsegments_2,genera= tion=3D2,filenames=3D[_0_MockVariableIntBlock_0.doc, _0_TestBloomFilteredLu= cene40Postings_0.prx, _0_MockVariableIntBlock_0.skp, _0_MockVariableIntBloc= k_0.pyl, _0_Lucene40WithOrds_0.tii, _0_Lucene40WithOrds_0.frq, _0_MockVaria= bleIntBlock_0.tib, _0_MockVariableIntBlock_0.pos, _0_TestBloomFilteredLucen= e40Postings_0.frq, _0.si, _0_Lucene40WithOrds_0.tib, _0_MockVariableIntBloc= k_0.tii, _0_TestBloomFilteredLucene40Postings_0.tim, _0_MockRandom_0.tib, _= 0_TestBloomFilteredLucene40Postings_0.blm, _0_TestBloomFilteredLucene40Post= ings_0.tip, _0.fnm, _0_nrm.cfs, _0_MockRandom_0.skp, _0_MockRandom_0.sd, _0= _MockRandom_0.frq, _0_MockRandom_0.tiv, _0_MockRandom_0.doc, _0_MockRandom_= 0.pos, _0_MockVariableIntBlock_0.frq, _0_nrm.cfe, segments_2, _0.fdx, _0_Lu= cene40WithOrds_0.prx, _0_MockRandom_0.pyl, _0.fdt] [junit4:junit4] 2> =09=09commit{dir=3D/usr/home/hudson/hudson-slave/works= pace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.so= lr.cloud.SyncSliceTest-1348166975070/jetty1/index,segFN=3Dsegments_3,genera= tion=3D3,filenames=3D[_0_MockVariableIntBlock_0.skp, _1.fnm, _1_MockRandom_= 0.pos, _0_Lucene40WithOrds_0.tii, _0_Lucene40WithOrds_0.frq, _1_Lucene40Wit= hOrds_0.tib, _0_TestBloomFilteredLucene40Postings_0.frq, _1_Lucene40WithOrd= s_0.tii, _0_Lucene40WithOrds_0.tib, _0_TestBloomFilteredLucene40Postings_0.= tim, _1_Lucene40WithOrds_0.frq, _0_TestBloomFilteredLucene40Postings_0.tip,= _0_TestBloomFilteredLucene40Postings_0.blm, _0.fnm, _1_MockVariableIntBloc= k_0.tii, _1_MockRandom_0.tip, _1_MockRandom_0.tim, _1_MockRandom_0.sd, _1_T= estBloomFilteredLucene40Postings_0.frq, _0_MockRandom_0.skp, _1_MockVariabl= eIntBlock_0.tib, _1.fdx, _0_MockRandom_0.frq, _1.fdt, _0_MockRandom_0.doc, = _0_MockVariableIntBlock_0.frq, _0_MockRandom_0.pos, _1_MockRandom_0.doc, _1= _MockRandom_0.frq, _1_MockVariableIntBlock_0.skp, _0_MockVariableIntBlock_0= .doc, _0_TestBloomFilteredLucene40Postings_0.prx, _1_Lucene40WithOrds_0.prx= , _1_TestBloomFilteredLucene40Postings_0.blm, _1_MockVariableIntBlock_0.pyl= , _0_MockVariableIntBlock_0.pyl, _1_TestBloomFilteredLucene40Postings_0.tip= , _0_MockVariableIntBlock_0.tib, _1_MockVariableIntBlock_0.doc, _0_MockVari= ableIntBlock_0.pos, _0.si, _1_TestBloomFilteredLucene40Postings_0.tim, _0_M= ockVariableIntBlock_0.tii, _0_MockRandom_0.tib, _1_nrm.cfs, _1_TestBloomFil= teredLucene40Postings_0.prx, _1_MockVariableIntBlock_0.pos, _1_MockRandom_0= .skp, _0_nrm.cfs, _1_nrm.cfe, _1_MockVariableIntBlock_0.frq, _0_MockRandom_= 0.sd, _1_MockRandom_0.pyl, _0_MockRandom_0.tiv, _1.si, _0_nrm.cfe, _0.fdx, = _0_Lucene40WithOrds_0.prx, _0_MockRandom_0.pyl, segments_3, _0.fdt] [junit4:junit4] 2> 21013 T1469 C92 P47640 oasc.SolrDeletionPolicy.updateC= ommits newest commit =3D 3 [junit4:junit4] 2> 21015 T1469 oasu.DefaultSolrCoreState.decref Closing S= olrCoreState - canceling any ongoing recovery [junit4:junit4] 2> 21016 T1474 oazs.PrepRequestProcessor.pRequest Process= ed session termination for sessionid: 0x139e5037e640003 [junit4:junit4] 2> 21017 T1550 oascc.ZkStateReader$3.process Updating liv= e nodes [junit4:junit4] 2> 21017 T1489 oascc.ZkStateReader$3.process Updating liv= e nodes [junit4:junit4] 2> 21017 T1529 oascc.ZkStateReader$3.process Updating liv= e nodes [junit4:junit4] 2> 21017 T1548 oascc.ZkStateReader$3.process Updating liv= e nodes [junit4:junit4] 2> 21017 T1543 oascc.ZkStateReader$3.process Updating liv= e nodes [junit4:junit4] 2> 21018 T1471 oazs.NIOServerCnxn.closeSock Closed socket= connection for client /140.211.11.196:47641 which had sessionid 0x139e5037= e640003 [junit4:junit4] 2> 21017 T1503 oascc.ZkStateReader$3.process Updating liv= e nodes [junit4:junit4] 2> 21018 T1469 oaz.ZooKeeper.close Session: 0x139e5037e64= 0003 closed [junit4:junit4] 2> 21019 T1503 oascc.ZkStateReader$3.process WARNING ZooK= eeper watch triggered, but Solr cannot talk to ZK [junit4:junit4] 2> 21019 T1503 oaz.ClientCnxn$EventThread.run EventThread= shut down [junit4:junit4] 2> 21019 T1469 oejsh.ContextHandler.doStop stopped o.e.j.= s.ServletContextHandler{/solr,null} [junit4:junit4] 2> 21020 T1515 oascc.ZkStateReader$3.process Updating liv= e nodes [junit4:junit4] 2> 21021 T1474 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x139e5037e640004 type:de= lete cxid:0x4d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pa= th:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode =3D N= oNode for /solr/collections/collection1/leaders/shard1 [junit4:junit4] 2> 21023 T1515 oasc.ShardLeaderElectionContext.runLeaderP= rocess Running the leader process. [junit4:junit4] 2> 21023 T1474 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x139e5037e640004 type:cr= eate cxid:0x4e zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pa= th:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 21034 T1515 oasc.ShardLeaderElectionContext.shouldIBeL= eader Checking if I should try and be the leader. [junit4:junit4] 2> 21034 T1515 oasc.ShardLeaderElectionContext.shouldIBeL= eader My last published State was Active, it's okay to be the leader. [junit4:junit4] 2> 21034 T1515 oasc.ShardLeaderElectionContext.runLeaderP= rocess I may be the new leader - try and sync [junit4:junit4] 2> 21034 T1515 oasc.SyncStrategy.sync Sync replicas to ht= tp://127.0.0. [...truncated too long message...] atementAdapter.evaluate(StatementAdapter.java:36) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.ThreadLeakCon= trol$StatementRunner.run(ThreadLeakControl.java:358) [junit4:junit4] > =09at java.lang.Thread.run(Thread.java:679) [junit4:junit4] 2> 183066 T1469 oas.SolrTestCaseJ4.deleteCore ###deleteCo= re [junit4:junit4] 2> 186720 T1543 oasc.SolrException.log SEVERE Errir check= ing for the number of election participants:org.apache.zookeeper.KeeperExce= ption$SessionExpiredException: KeeperErrorCode =3D Session expired for /col= lections/collection1/leader_elect/shard1/election [junit4:junit4] 2> =09=09at org.apache.zookeeper.KeeperException.create(K= eeperException.java:118) [junit4:junit4] 2> =09=09at org.apache.zookeeper.KeeperException.create(K= eeperException.java:42) [junit4:junit4] 2> =09=09at org.apache.zookeeper.ZooKeeper.getChildren(Zo= oKeeper.java:1249) [junit4:junit4] 2> =09=09at org.apache.solr.common.cloud.SolrZkClient$6.e= xecute(SolrZkClient.java:269) [junit4:junit4] 2> =09=09at org.apache.solr.common.cloud.SolrZkClient$6.e= xecute(SolrZkClient.java:266) [junit4:junit4] 2> =09=09at org.apache.solr.common.cloud.ZkCmdExecutor.re= tryOperation(ZkCmdExecutor.java:67) [junit4:junit4] 2> =09=09at org.apache.solr.common.cloud.SolrZkClient.get= Children(SolrZkClient.java:266) [junit4:junit4] 2> =09=09at org.apache.solr.cloud.ShardLeaderElectionCont= ext.waitForReplicasToComeUp(ElectionContext.java:275) [junit4:junit4] 2> =09=09at org.apache.solr.cloud.ShardLeaderElectionCont= ext.runLeaderProcess(ElectionContext.java:142) [junit4:junit4] 2> =09=09at org.apache.solr.cloud.LeaderElector.runIamLea= derProcess(LeaderElector.java:158) [junit4:junit4] 2> =09=09at org.apache.solr.cloud.LeaderElector.checkIfIa= mLeader(LeaderElector.java:102) [junit4:junit4] 2> =09=09at org.apache.solr.cloud.LeaderElector.joinElect= ion(LeaderElector.java:274) [junit4:junit4] 2> =09=09at org.apache.solr.cloud.ShardLeaderElectionCont= ext.rejoinLeaderElection(ElectionContext.java:326) [junit4:junit4] 2> =09=09at org.apache.solr.cloud.ShardLeaderElectionCont= ext.runLeaderProcess(ElectionContext.java:159) [junit4:junit4] 2> =09=09at org.apache.solr.cloud.LeaderElector.runIamLea= derProcess(LeaderElector.java:158) [junit4:junit4] 2> =09=09at org.apache.solr.cloud.LeaderElector.checkIfIa= mLeader(LeaderElector.java:102) [junit4:junit4] 2> =09=09at org.apache.solr.cloud.LeaderElector.access$00= 0(LeaderElector.java:56) [junit4:junit4] 2> =09=09at org.apache.solr.cloud.LeaderElector$1.process= (LeaderElector.java:131) [junit4:junit4] 2> =09=09at org.apache.zookeeper.ClientCnxn$EventThread.p= rocessEvent(ClientCnxn.java:526) [junit4:junit4] 2> =09=09at org.apache.zookeeper.ClientCnxn$EventThread.r= un(ClientCnxn.java:502) [junit4:junit4] 2> =09 [junit4:junit4] 2> 186721 T1543 oasc.ShardLeaderElectionContext.waitForRe= plicasToComeUp Waiting until we see more replicas up: total=3D4 found=3D0 t= imeoutin=3D67996 [junit4:junit4] 2> 187222 T1543 oasc.SolrCore.close [collection1] CLOSIN= G SolrCore org.apache.solr.core.SolrCore@2130a310 [junit4:junit4] 2> 187223 T1543 oasc.SolrCore.closeSearcher [collection1]= Closing main searcher on request. [junit4:junit4] 2> 187224 T1543 oasu.DirectUpdateHandler2.close closing D= irectUpdateHandler2{commits=3D7,autocommits=3D0,soft autocommits=3D0,optimi= zes=3D0,rollbacks=3D0,expungeDeletes=3D0,docsPending=3D0,adds=3D0,deletesBy= Id=3D0,deletesByQuery=3D0,errors=3D0,cumulative_adds=3D5,cumulative_deletes= ById=3D0,cumulative_deletesByQuery=3D1,cumulative_errors=3D0} [junit4:junit4] 2> 187224 T1543 oasu.DefaultSolrCoreState.decref SolrCore= State ref count has reached 0 - closing IndexWriter [junit4:junit4] 2> 187227 T1543 oasu.DefaultSolrCoreState.decref Closing = SolrCoreState - canceling any ongoing recovery [junit4:junit4] 2> 187227 T1543 oasc.LeaderElector$1.process WARNING org= .apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = =3D Session expired for /collections/collection1/leader_elect/shard1/electi= on/88353470878580742-127.0.0.1:47755_solr_collection1-n_0000000016 [junit4:junit4] 2> =09at org.apache.zookeeper.KeeperException.create(Keep= erException.java:118) [junit4:junit4] 2> =09at org.apache.zookeeper.KeeperException.create(Keep= erException.java:42) [junit4:junit4] 2> =09at org.apache.zookeeper.ZooKeeper.delete(ZooKeeper.= java:734) [junit4:junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$2.exec= ute(SolrZkClient.java:169) [junit4:junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$2.exec= ute(SolrZkClient.java:166) [junit4:junit4] 2> =09at org.apache.solr.common.cloud.ZkCmdExecutor.retry= Operation(ZkCmdExecutor.java:67) [junit4:junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient.delete= (SolrZkClient.java:166) [junit4:junit4] 2> =09at org.apache.solr.cloud.ElectionContext.cancelElec= tion(ElectionContext.java:60) [junit4:junit4] 2> =09at org.apache.solr.cloud.ShardLeaderElectionContext= .runLeaderProcess(ElectionContext.java:151) [junit4:junit4] 2> =09at org.apache.solr.cloud.LeaderElector.runIamLeader= Process(LeaderElector.java:158) [junit4:junit4] 2> =09at org.apache.solr.cloud.LeaderElector.checkIfIamLe= ader(LeaderElector.java:102) [junit4:junit4] 2> =09at org.apache.solr.cloud.LeaderElector.joinElection= (LeaderElector.java:274) [junit4:junit4] 2> =09at org.apache.solr.cloud.ShardLeaderElectionContext= .rejoinLeaderElection(ElectionContext.java:326) [junit4:junit4] 2> =09at org.apache.solr.cloud.ShardLeaderElectionContext= .runLeaderProcess(ElectionContext.java:159) [junit4:junit4] 2> =09at org.apache.solr.cloud.LeaderElector.runIamLeader= Process(LeaderElector.java:158) [junit4:junit4] 2> =09at org.apache.solr.cloud.LeaderElector.checkIfIamLe= ader(LeaderElector.java:102) [junit4:junit4] 2> =09at org.apache.solr.cloud.LeaderElector.access$000(L= eaderElector.java:56) [junit4:junit4] 2> =09at org.apache.solr.cloud.LeaderElector$1.process(Le= aderElector.java:131) [junit4:junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.proc= essEvent(ClientCnxn.java:526) [junit4:junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.run(= ClientCnxn.java:502) [junit4:junit4] 2>=20 [junit4:junit4] 2> 187228 T1543 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> 187228 T1543 oascc.ZkStateReader$2.process WARNING Zoo= Keeper watch triggered, but Solr cannot talk to ZK [junit4:junit4] 2> 187228 T1543 oascc.ConnectionManager.process Watcher o= rg.apache.solr.common.cloud.ConnectionManager@77a7f0 name:ZooKeeperConnecti= on Watcher:127.0.0.1:47624/solr got event WatchedEvent state:Disconnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 187228 T1543 oascc.ConnectionManager.process Client->Z= ooKeeper status change trigger but we are already closed [junit4:junit4] 2> 187229 T1543 oaz.ClientCnxn$EventThread.run EventThrea= d shut down [junit4:junit4] 2> NOTE: test params are: codec=3DLucene40: {range_facet_= l=3DPostingsFormat(name=3DTestBloomFilteredLucene40Postings), text=3DPostin= gsFormat(name=3DMockRandom), _version_=3DPostingsFormat(name=3DTestBloomFil= teredLucene40Postings), rnd_b=3DMockVariableIntBlock(baseBlockSize=3D88), i= ntDefault=3DPostingsFormat(name=3DTestBloomFilteredLucene40Postings), id=3D= MockVariableIntBlock(baseBlockSize=3D88), timestamp=3DPostingsFormat(name= =3DTestBloomFilteredLucene40Postings), a_t=3DPostingsFormat(name=3DTestBloo= mFilteredLucene40Postings), range_facet_sl=3DPostingsFormat(name=3DMockRand= om), range_facet_si=3DPostingsFormat(name=3DLucene40WithOrds), other_tl1=3D= PostingsFormat(name=3DTestBloomFilteredLucene40Postings), multiDefault=3DPo= stingsFormat(name=3DLucene40WithOrds), a_si=3DPostingsFormat(name=3DLucene4= 0WithOrds)}, sim=3DDefaultSimilarity, locale=3Den_SG, timezone=3DEurope/Vad= uz [junit4:junit4] 2> NOTE: FreeBSD 9.0-RELEASE amd64/Sun Microsystems Inc. = 1.6.0_32 (64-bit)/cpus=3D16,threads=3D2,free=3D121970952,total=3D182583296 [junit4:junit4] 2> NOTE: All tests run in this JVM: [DistributedQueryElev= ationComponentTest, TestSolrDeletionPolicy1, SuggesterTest, TestSolrXMLSeri= alizer, RecoveryZkTest, TestSurroundQueryParser, StatsComponentTest, TestFo= ldingMultitermQuery, ScriptEngineTest, TestStressRecovery, TestRealTimeGet,= BasicFunctionalityTest, TestBinaryField, OutputWriterTest, TestRemoteStrea= ming, PathHierarchyTokenizerFactoryTest, BasicDistributedZk2Test, ShowFileR= equestHandlerTest, IndexSchemaTest, PrimitiveFieldTypeTest, TestRTGBase, Fi= leUtilsTest, SoftAutoCommitTest, TestDistributedSearch, ClusterStateUpdateT= est, TestQuerySenderListener, TermVectorComponentDistributedTest, TestDocSe= t, ZkSolrClientTest, DocumentAnalysisRequestHandlerTest, SolrCoreTest, Test= PropInject, TestUpdate, BasicZkTest, TestCSVLoader, SignatureUpdateProcesso= rFactoryTest, FieldMutatingUpdateProcessorTest, TestReplicationHandler, ZkN= odePropsTest, ReturnFieldsTest, SolrInfoMBeanTest, IndexReaderFactoryTest, = TestArbitraryIndexDir, DirectUpdateHandlerTest, TestJmxIntegration, PolyFie= ldTest, TestDocumentBuilder, MultiTermTest, EchoParamsTest, TestBM25Similar= ityFactory, TestQueryUtils, LeaderElectionTest, TestMultiCoreConfBootstrap,= SyncSliceTest] [junit4:junit4] Completed on J1 in 188.10s, 1 test, 1 error <<< FAILURES! [...truncated 360 lines...] BUILD FAILED /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/build.x= ml:32: The following error occurred while executing this line: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/bu= ild.xml:146: The following error occurred while executing this line: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/lucene/= module-build.xml:63: The following error occurred while executing this line= : /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/lucene/= common-build.xml:1140: The following error occurred while executing this li= ne: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/lucene/= common-build.xml:813: There were test failures: 231 suites, 954 tests, 1 er= ror, 7 ignored (2 assumptions) Total time: 43 minutes 54 seconds Build step 'Invoke Ant' marked build as failure Recording test results Email was triggered for: Failure Sending email for trigger: Failure ------=_Part_836_1397572148.1348167952450 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_836_1397572148.1348167952450--