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 EF535D84E for ; Sat, 22 Sep 2012 05:06:42 +0000 (UTC) Received: (qmail 78325 invoked by uid 500); 22 Sep 2012 05:06:41 -0000 Delivered-To: apmail-lucene-dev-archive@lucene.apache.org Received: (qmail 78137 invoked by uid 500); 22 Sep 2012 05:06:40 -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 78106 invoked by uid 99); 22 Sep 2012 05:06:39 -0000 Received: from athena.apache.org (HELO athena.apache.org) (140.211.11.136) by apache.org (qpsmtpd/0.29) with ESMTP; Sat, 22 Sep 2012 05:06:39 +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; Sat, 22 Sep 2012 05:06:30 +0000 Received: from aegis.apache.org (localhost [127.0.0.1]) by aegis.apache.org (Postfix) with ESMTP id E9BF8C00A0 for ; Sat, 22 Sep 2012 05:06:09 +0000 (UTC) Date: Sat, 22 Sep 2012 05:05:59 +0000 (UTC) From: Apache Jenkins Server To: dev@lucene.apache.org Message-ID: <2092023435.135.1348290369942.JavaMail.hudson@aegis.apache.org> In-Reply-To: <95552022.941.1348198884594.JavaMail.hudson@aegis.apache.org> References: <95552022.941.1348198884594.JavaMail.hudson@aegis.apache.org> Subject: [JENKINS] Lucene-Solr-NightlyTests-trunk - Build # 41 - Still Failing MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_134_1876322315.1348290359230" X-Virus-Checked: Checked by ClamAV on apache.org ------=_Part_134_1876322315.1348290359230 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/41/ 1 tests failed. FAILED: org.apache.solr.cloud.BasicDistributedZk2Test.testDistribSearch Error Message: Shard still reported as live in zk Stack Trace: java.lang.AssertionError: Shard still reported as live in zk =09at __randomizedtesting.SeedInfo.seed([1DDE25DD3CD8DC23:9C38ABC54B87BC1F]= :0) =09at org.junit.Assert.fail(Assert.java:93) =09at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitToSeeNotLive(= AbstractFullDistribZkTestBase.java:1225) =09at org.apache.solr.cloud.BasicDistributedZk2Test.brindDownShardIndexSome= DocsAndRecover(BasicDistributedZk2Test.java:190) =09at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributed= Zk2Test.java:83) =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 7391 lines...] [junit4:junit4] Suite: org.apache.solr.cloud.BasicDistributedZk2Test [junit4:junit4] 2> 4 T1276 oas.SolrTestCaseJ4.setUp ###Starting testDistr= ibSearch [junit4:junit4] 2> Creating dataDir: /usr/home/hudson/hudson-slave/worksp= ace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/./solrtest-= BasicDistributedZk2Test-1348289657218 [junit4:junit4] 2> 5 T1276 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4:junit4] 2> 9 T1277 oazs.ZooKeeperServer.setTickTime tickTime set = to 1000 [junit4:junit4] 2> 9 T1277 oazs.NIOServerCnxn$Factory. binding to p= ort 0.0.0.0/0.0.0.0:0 [junit4:junit4] 2> 10 T1277 oazsp.FileTxnSnapLog.save Snapshotting: 0 [junit4:junit4] 2> 107 T1276 oasc.ZkTestServer.run start zk server on por= t:21057 [junit4:junit4] 2> 107 T1276 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:21057 sessionTimeout=3D10000 watcher=3Dorg= .apache.solr.common.cloud.ConnectionManager@1567904d [junit4:junit4] 2> 108 T1282 oaz.ClientCnxn$SendThread.startConnect Openi= ng socket connection to server /127.0.0.1:21057 [junit4:junit4] 2> 108 T1276 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 109 T1282 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to localhost/127.0.0.1:21057, initiating sessio= n [junit4:junit4] 2> 110 T1278 oazs.NIOServerCnxn$Factory.run Accepted sock= et connection from /140.211.11.196:41284 [junit4:junit4] 2> 110 T1278 oazs.NIOServerCnxn.readConnectRequest Client= attempting to establish new session at /140.211.11.196:41284 [junit4:junit4] 2> 110 T1280 oazsp.FileTxnLog.append Creating new log fil= e: log.1 [junit4:junit4] 2> 117 T1280 oazs.NIOServerCnxn.finishSessionInit Establi= shed session 0x139ec5379880000 with negotiated timeout 10000 for client /14= 0.211.11.196:41284 [junit4:junit4] 2> 117 T1282 oaz.ClientCnxn$SendThread.readConnectResult = Session establishment complete on server localhost/127.0.0.1:21057, session= id =3D 0x139ec5379880000, negotiated timeout =3D 10000 [junit4:junit4] 2> 117 T1283 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@1567904d name:ZooKeeperConnectio= n Watcher:127.0.0.1:21057 got event WatchedEvent state:SyncConnected type:N= one path:null path:null type:None [junit4:junit4] 2> 118 T1276 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 118 T1276 oascc.SolrZkClient.makePath makePath: /solr [junit4:junit4] 2> 126 T1281 oazs.PrepRequestProcessor.pRequest Processed= session termination for sessionid: 0x139ec5379880000 [junit4:junit4] 2> 133 T1283 oaz.ClientCnxn$EventThread.run EventThread s= hut down [junit4:junit4] 2> 133 T1276 oaz.ZooKeeper.close Session: 0x139ec53798800= 00 closed [junit4:junit4] 2> 134 T1278 oazs.NIOServerCnxn.closeSock Closed socket c= onnection for client /140.211.11.196:41284 which had sessionid 0x139ec53798= 80000 [junit4:junit4] 2> 134 T1276 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:21057/solr sessionTimeout=3D10000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@437a50e2 [junit4:junit4] 2> 135 T1284 oaz.ClientCnxn$SendThread.startConnect Openi= ng socket connection to server /127.0.0.1:21057 [junit4:junit4] 2> 135 T1276 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 136 T1284 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to localhost/127.0.0.1:21057, initiating sessio= n [junit4:junit4] 2> 136 T1278 oazs.NIOServerCnxn$Factory.run Accepted sock= et connection from /140.211.11.196:27375 [junit4:junit4] 2> 136 T1278 oazs.NIOServerCnxn.readConnectRequest Client= attempting to establish new session at /140.211.11.196:27375 [junit4:junit4] 2> 145 T1280 oazs.NIOServerCnxn.finishSessionInit Establi= shed session 0x139ec5379880001 with negotiated timeout 10000 for client /14= 0.211.11.196:27375 [junit4:junit4] 2> 145 T1284 oaz.ClientCnxn$SendThread.readConnectResult = Session establishment complete on server localhost/127.0.0.1:21057, session= id =3D 0x139ec5379880001, negotiated timeout =3D 10000 [junit4:junit4] 2> 145 T1285 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@437a50e2 name:ZooKeeperConnectio= n Watcher:127.0.0.1:21057/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 145 T1276 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 146 T1276 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1 [junit4:junit4] 2> 166 T1276 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1/shards [junit4:junit4] 2> 175 T1276 oascc.SolrZkClient.makePath makePath: /colle= ctions/control_collection [junit4:junit4] 2> 178 T1276 oascc.SolrZkClient.makePath makePath: /colle= ctions/control_collection/shards [junit4:junit4] 2> 183 T1276 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build= /solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf= 1/solrconfig.xml [junit4:junit4] 2> 184 T1276 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/solrconfig.xml [junit4:junit4] 2> 197 T1276 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build= /solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/sc= hema.xml [junit4:junit4] 2> 198 T1276 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/schema.xml [junit4:junit4] 2> 303 T1276 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build= /solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf= 1/solrconfig.xml [junit4:junit4] 2> 304 T1276 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/solrconfig.xml [junit4:junit4] 2> 317 T1276 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build= /solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1= /stopwords.txt [junit4:junit4] 2> 318 T1276 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/stopwords.txt [junit4:junit4] 2> 328 T1276 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build= /solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1= /protwords.txt [junit4:junit4] 2> 329 T1276 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/protwords.txt [junit4:junit4] 2> 349 T1276 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build= /solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/= currency.xml [junit4:junit4] 2> 349 T1276 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/currency.xml [junit4:junit4] 2> 359 T1276 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build= /solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /co= nfigs/conf1/open-exchange-rates.json [junit4:junit4] 2> 360 T1276 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/open-exchange-rates.json [junit4:junit4] 2> 367 T1276 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build= /solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to = /configs/conf1/mapping-ISOLatin1Accent.txt [junit4:junit4] 2> 368 T1276 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/mapping-ISOLatin1Accent.txt [junit4:junit4] 2> 381 T1276 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build= /solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/co= nf1/old_synonyms.txt [junit4:junit4] 2> 382 T1276 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/old_synonyms.txt [junit4:junit4] 2> 393 T1276 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build= /solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/= synonyms.txt [junit4:junit4] 2> 394 T1276 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/synonyms.txt [junit4:junit4] 2> 407 T1281 oazs.PrepRequestProcessor.pRequest Processed= session termination for sessionid: 0x139ec5379880001 [junit4:junit4] 2> 408 T1285 oaz.ClientCnxn$EventThread.run EventThread s= hut down [junit4:junit4] 2> 408 T1276 oaz.ZooKeeper.close Session: 0x139ec53798800= 01 closed [junit4:junit4] 2> 408 T1278 oazs.NIOServerCnxn.closeSock Closed socket c= onnection for client /140.211.11.196:27375 which had sessionid 0x139ec53798= 80001 [junit4:junit4] 2> 578 T1276 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 581 T1276 oejs.AbstractConnector.doStart Started Socke= tConnector@0.0.0.0:47845 [junit4:junit4] 2> 582 T1276 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 582 T1276 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.solr= .cloud.BasicDistributedZk2Test-controljetty-1348289657622 [junit4:junit4] 2> 583 T1276 oasc.SolrResourceLoader. new SolrResou= rceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.sol= r.cloud.BasicDistributedZk2Test-controljetty-1348289657622/' [junit4:junit4] 2> 620 T1276 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() [junit4:junit4] 2> 621 T1276 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 621 T1276 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.solr= .cloud.BasicDistributedZk2Test-controljetty-1348289657622 [junit4:junit4] 2> 622 T1276 oasc.CoreContainer$Initializer.initialize lo= oking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Nig= htlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicD= istributedZk2Test-controljetty-1348289657622/solr.xml [junit4:junit4] 2> 622 T1276 oasc.CoreContainer. New CoreContainer = 1684132543 [junit4:junit4] 2> 623 T1276 oasc.CoreContainer.load Loading CoreContaine= r using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Nig= htlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicD= istributedZk2Test-controljetty-1348289657622/' [junit4:junit4] 2> 623 T1276 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-So= lr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud.= BasicDistributedZk2Test-controljetty-1348289657622/' [junit4:junit4] 2> 659 T1276 oasc.CoreContainer.load Registering Log List= ener [junit4:junit4] 2> 674 T1276 oasc.CoreContainer.initZooKeeper Zookeeper c= lient=3D127.0.0.1:21057/solr [junit4:junit4] 2> 675 T1276 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:21057/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@52b4e7ec [junit4:junit4] 2> 676 T1295 oaz.ClientCnxn$SendThread.startConnect Openi= ng socket connection to server /127.0.0.1:21057 [junit4:junit4] 2> 676 T1276 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 677 T1295 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to localhost/127.0.0.1:21057, initiating sessio= n [junit4:junit4] 2> 678 T1278 oazs.NIOServerCnxn$Factory.run Accepted sock= et connection from /140.211.11.196:40266 [junit4:junit4] 2> 678 T1278 oazs.NIOServerCnxn.readConnectRequest Client= attempting to establish new session at /140.211.11.196:40266 [junit4:junit4] 2> 680 T1280 oazs.NIOServerCnxn.finishSessionInit Establi= shed session 0x139ec5379880002 with negotiated timeout 5000 for client /140= .211.11.196:40266 [junit4:junit4] 2> 680 T1295 oaz.ClientCnxn$SendThread.readConnectResult = Session establishment complete on server localhost/127.0.0.1:21057, session= id =3D 0x139ec5379880002, negotiated timeout =3D 5000 [junit4:junit4] 2> 680 T1296 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@52b4e7ec name:ZooKeeperConnectio= n Watcher:127.0.0.1:21057/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 680 T1276 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 691 T1281 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139ec5379880002 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> 696 T1276 oascc.SolrZkClient.makePath makePath: /live_= nodes [junit4:junit4] 2> 708 T1276 oasc.ZkController.createEphemeralLiveNode Re= gister node as live in ZooKeeper:/live_nodes/127.0.0.1:47845_solr [junit4:junit4] 2> 709 T1281 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139ec5379880002 type:dele= te cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/live_nodes/127.0.0.1:47845_solr Error:KeeperErrorCode =3D NoNode for = /solr/live_nodes/127.0.0.1:47845_solr [junit4:junit4] 2> 719 T1276 oascc.SolrZkClient.makePath makePath: /live_= nodes/127.0.0.1:47845_solr [junit4:junit4] 2> 739 T1276 oashc.HttpShardHandlerFactory.getParameter S= etting socketTimeout to: 0 [junit4:junit4] 2> 739 T1276 oashc.HttpShardHandlerFactory.getParameter S= etting urlScheme to: http:// [junit4:junit4] 2> 740 T1276 oashc.HttpShardHandlerFactory.getParameter S= etting connTimeout to: 0 [junit4:junit4] 2> 740 T1276 oashc.HttpShardHandlerFactory.getParameter S= etting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 741 T1276 oashc.HttpShardHandlerFactory.getParameter S= etting corePoolSize to: 0 [junit4:junit4] 2> 741 T1276 oashc.HttpShardHandlerFactory.getParameter S= etting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 742 T1276 oashc.HttpShardHandlerFactory.getParameter S= etting maxThreadIdleTime to: 5 [junit4:junit4] 2> 742 T1276 oashc.HttpShardHandlerFactory.getParameter S= etting sizeOfQueue to: -1 [junit4:junit4] 2> 742 T1276 oashc.HttpShardHandlerFactory.getParameter S= etting fairnessPolicy to: false [junit4:junit4] 2> 743 T1276 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&s= ocketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 760 T1276 oascc.SolrZkClient.makePath makePath: /overs= eer_elect/election [junit4:junit4] 2> 767 T1281 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139ec5379880002 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> 776 T1276 oascc.SolrZkClient.makePath makePath: /overs= eer_elect/leader [junit4:junit4] 2> 779 T1276 oasc.Overseer.start Overseer (id=3D883615109= 75832066-127.0.0.1:47845_solr-n_0000000000) starting [junit4:junit4] 2> 780 T1281 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139ec5379880002 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> 781 T1281 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139ec5379880002 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> 789 T1281 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139ec5379880002 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> 791 T1281 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139ec5379880002 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> 793 T1298 oasc.OverseerCollectionProcessor.run Process= current queue of collection creations [junit4:junit4] 2> 794 T1276 oascc.SolrZkClient.makePath makePath: /clust= erstate.json [junit4:junit4] 2> 806 T1276 oascc.ZkStateReader.createClusterStateWatche= rsAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 813 T1276 oasc.CoreContainer.create Creating SolrCore = 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.solr= .cloud.BasicDistributedZk2Test-controljetty-1348289657622/collection1 [junit4:junit4] 2> 814 T1276 oasc.ZkController.createCollectionZkNode Che= ck for collection zkNode:control_collection [junit4:junit4] 2> 815 T1297 oasc.Overseer$ClusterStateUpdater.run Starti= ng to work on the main queue [junit4:junit4] 2> 815 T1276 oasc.ZkController.createCollectionZkNode Col= lection zkNode exists [junit4:junit4] 2> 815 T1276 oasc.ZkController.readConfigName Load collec= tion config from:/collections/control_collection [junit4:junit4] 2> 817 T1276 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-So= lr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud.= BasicDistributedZk2Test-controljetty-1348289657622/collection1/' [junit4:junit4] 2> 819 T1276 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTest= s-trunk/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributed= Zk2Test-controljetty-1348289657622/collection1/lib/classes/' to classloader [junit4:junit4] 2> 820 T1276 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTest= s-trunk/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributed= Zk2Test-controljetty-1348289657622/collection1/lib/README' to classloader [junit4:junit4] 2> 870 T1276 oasc.SolrConfig. Using Lucene MatchVer= sion: LUCENE_50 [junit4:junit4] 2> 948 T1276 oasc.SolrConfig. Loaded SolrConfig: so= lrconfig.xml [junit4:junit4] 2> 1049 T1276 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 1056 T1276 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 1673 T1276 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 1686 T1276 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 1691 T1276 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 1710 T1276 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 1716 T1276 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 1722 T1276 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 1724 T1276 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 1726 T1276 oasc.SolrCore. [collection1] Opening = new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Nightly= Tests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistr= ibutedZk2Test-controljetty-1348289657622/collection1/, dataDir=3D./org.apac= he.solr.cloud.BasicDistributedZk2Test-1348289657217/control/data/ [junit4:junit4] 2> 1726 T1276 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@27972e3a [junit4:junit4] 2> 1770 T1276 oasc.SolrCore.getNewIndexDir WARNING New in= dex directory detected: old=3Dnull new=3D./org.apache.solr.cloud.BasicDistr= ibutedZk2Test-1348289657217/control/data/index/ [junit4:junit4] 2> 1771 T1276 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-13= 48289657217/control/data/index' doesn't exist. Creating new index... [junit4:junit4] 2> 1772 T1276 oasc.CachingDirectoryFactory.get return new= directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyT= ests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistri= butedZk2Test-1348289657217/control/data/index forceNew:false [junit4:junit4] 2> 1788 T1276 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -NightlyTests-trunk/solr/build/solr-core/test/J1/org.apache.solr.cloud.Basi= cDistributedZk2Test-1348289657217/control/data/index lockFactory=3Dorg.apac= he.lucene.store.NativeFSLockFactory@1bf55430; maxCacheMB=3D48.0 maxMergeSiz= eMB=3D4.0),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 1789 T1276 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1 [junit4:junit4] 2> 1790 T1276 oasc.SolrCore.initWriters created xml: solr= .XMLResponseWriter [junit4:junit4] 2> 1792 T1276 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 1793 T1276 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe-allfields" [junit4:junit4] 2> 1793 T1276 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 1794 T1276 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "u= niq-fields" [junit4:junit4] 2> 1795 T1276 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 1795 T1276 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 1796 T1276 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 1796 T1276 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 1797 T1276 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 1797 T1276 oasc.RequestHandlers.initHandlersFromConfig= created dismax: solr.SearchHandler [junit4:junit4] 2> 1798 T1276 oasc.RequestHandlers.initHandlersFromConfig= created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 1799 T1276 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 1799 T1276 oasc.RequestHandlers.initHandlersFromConfig= created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 1800 T1276 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 1800 T1276 oasc.RequestHandlers.initHandlersFromConfig= created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 1801 T1276 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 1801 T1276 oasc.RequestHandlers.initHandlersFromConfig= created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 1802 T1276 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 1803 T1276 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 1803 T1276 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 1804 T1276 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.= SearchHandler [junit4:junit4] 2> 1804 T1276 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 1805 T1276 oasc.RequestHandlers.initHandlersFromConfig= created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 1806 T1276 oasc.RequestHandlers.initHandlersFromConfig= created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 1806 T1276 oasc.RequestHandlers.initHandlersFromConfig= created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 1811 T1276 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 1817 T1276 oasc.SolrCore.initDeprecatedSupport WARNING= solrconfig.xml uses deprecated , Please update your c= onfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 1818 T1276 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> 1823 T1276 oass.SolrIndexSearcher. Opening Searc= her@f042592 main [junit4:junit4] 2> 1824 T1276 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 1825 T1276 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 1825 T1276 oashc.SpellCheckComponent.inform Initializi= ng spell checkers [junit4:junit4] 2> 1842 T1276 oass.DirectSolrSpellChecker.init init: {nam= e=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLen= gth=3D3} [junit4:junit4] 2> 1909 T1299 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@f042592 main{StandardDirectoryReader(seg= ments_1:1)} [junit4:junit4] 2> 1913 T1281 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139ec5379880002 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> 1935 T1276 oasc.CoreContainer.register registering cor= e: collection1 [junit4:junit4] 2> 1936 T1276 oasc.ZkController.register Register shard -= core:collection1 address:http://127.0.0.1:47845/solr shardId:control_shard [junit4:junit4] 2> 1937 T1276 oascc.SolrZkClient.makePath makePath: /coll= ections/control_collection/leader_elect/control_shard/election [junit4:junit4] 2> 1973 T1281 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139ec5379880002 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> 1986 T1276 oasc.ShardLeaderElectionContext.runLeaderPr= ocess Running the leader process. [junit4:junit4] 2> 1986 T1281 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139ec5379880002 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> 2323 T1297 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 2324 T1281 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139ec5379880002 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> 2335 T1296 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 2502 T1276 oasc.ShardLeaderElectionContext.waitForRepl= icasToComeUp Enough replicas found to continue. [junit4:junit4] 2> 2503 T1276 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I may be the new leader - try and sync [junit4:junit4] 2> 2504 T1276 oasc.SyncStrategy.sync Sync replicas to htt= p://127.0.0.1:47845/solr/collection1/ [junit4:junit4] 2> 2504 T1276 oasc.SyncStrategy.syncReplicas Sync Success= - now sync replicas to me [junit4:junit4] 2> 2505 T1276 oasc.SyncStrategy.syncToMe http://127.0.0.1= :47845/solr/collection1/ has no replicas [junit4:junit4] 2> 2505 T1276 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I am the new leader: http://127.0.0.1:47845/solr/collection1/ [junit4:junit4] 2> 2506 T1276 oascc.SolrZkClient.makePath makePath: /coll= ections/control_collection/leaders/control_shard [junit4:junit4] 2> 2511 T1281 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139ec5379880002 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> 2842 T1297 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 2849 T1296 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 2877 T1276 oasc.ZkController.register We are http://12= 7.0.0.1:47845/solr/collection1/ and leader is http://127.0.0.1:47845/solr/c= ollection1/ [junit4:junit4] 2> 2877 T1276 oasc.ZkController.register No LogReplay nee= ded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:47845/solr [junit4:junit4] 2> 2878 T1276 oasc.ZkController.checkRecovery I am the le= ader, no recovery necessary [junit4:junit4] 2> 2886 T1276 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 2887 T1276 oass.SolrDispatchFilter.init user.dir=3D/us= r/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/bu= ild/solr-core/test/J1 [junit4:junit4] 2> 2888 T1276 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() done [junit4:junit4] 2> 2888 T1276 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fo= llowRedirects=3Dfalse [junit4:junit4] 2> 3119 T1276 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 3121 T1276 oejs.AbstractConnector.doStart Started Sock= etConnector@0.0.0.0:23059 [junit4:junit4] 2> 3122 T1276 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 3122 T1276 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.sol= r.cloud.BasicDistributedZk2Test-jetty1-1348289660118 [junit4:junit4] 2> 3123 T1276 oasc.SolrResourceLoader. new SolrReso= urceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/= Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.so= lr.cloud.BasicDistributedZk2Test-jetty1-1348289660118/' [junit4:junit4] 2> 3162 T1276 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() [junit4:junit4] 2> 3163 T1276 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 3164 T1276 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.sol= r.cloud.BasicDistributedZk2Test-jetty1-1348289660118 [junit4:junit4] 2> 3164 T1276 oasc.CoreContainer$Initializer.initialize l= ooking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Ni= ghtlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud.Basic= DistributedZk2Test-jetty1-1348289660118/solr.xml [junit4:junit4] 2> 3164 T1276 oasc.CoreContainer. New CoreContainer= 1395425719 [junit4:junit4] 2> 3165 T1276 oasc.CoreContainer.load Loading CoreContain= er using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Ni= ghtlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud.Basic= DistributedZk2Test-jetty1-1348289660118/' [junit4:junit4] 2> 3165 T1276 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud= .BasicDistributedZk2Test-jetty1-1348289660118/' [junit4:junit4] 2> 3191 T1276 oasc.CoreContainer.load Registering Log Lis= tener [junit4:junit4] 2> 3207 T1276 oasc.CoreContainer.initZooKeeper Zookeeper = client=3D127.0.0.1:21057/solr [junit4:junit4] 2> 3207 T1276 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:21057/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@4c345833 [junit4:junit4] 2> 3208 T1309 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:21057 [junit4:junit4] 2> 3208 T1276 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 3210 T1309 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost/127.0.0.1:21057, initiating sessi= on [junit4:junit4] 2> 3210 T1278 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /140.211.11.196:51028 [junit4:junit4] 2> 3210 T1278 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /140.211.11.196:51028 [junit4:junit4] 2> 3212 T1280 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x139ec5379880003 with negotiated timeout 5000 for client /14= 0.211.11.196:51028 [junit4:junit4] 2> 3212 T1309 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server localhost/127.0.0.1:21057, sessio= nid =3D 0x139ec5379880003, negotiated timeout =3D 5000 [junit4:junit4] 2> 3212 T1310 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@4c345833 name:ZooKeeperConnecti= on Watcher:127.0.0.1:21057/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4:junit4] 2> 3213 T1276 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 3214 T1281 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139ec5379880003 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> 3215 T1281 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139ec5379880003 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> 3217 T1276 oasc.ZkController.createEphemeralLiveNode R= egister node as live in ZooKeeper:/live_nodes/127.0.0.1:23059_solr [junit4:junit4] 2> 3218 T1281 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139ec5379880003 type:del= ete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/live_nodes/127.0.0.1:23059_solr Error:KeeperErrorCode =3D NoNode for= /solr/live_nodes/127.0.0.1:23059_solr [junit4:junit4] 2> 3225 T1276 oascc.SolrZkClient.makePath makePath: /live= _nodes/127.0.0.1:23059_solr [junit4:junit4] 2> 3228 T1296 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 3229 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting socketTimeout to: 0 [junit4:junit4] 2> 3230 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting urlScheme to: http:// [junit4:junit4] 2> 3230 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting connTimeout to: 0 [junit4:junit4] 2> 3230 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 3231 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting corePoolSize to: 0 [junit4:junit4] 2> 3231 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 3232 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 3232 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting sizeOfQueue to: -1 [junit4:junit4] 2> 3232 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting fairnessPolicy to: false [junit4:junit4] 2> 3233 T1276 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&= socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 3255 T1276 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 3263 T1276 oasc.CoreContainer.create Creating SolrCore= 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.sol= r.cloud.BasicDistributedZk2Test-jetty1-1348289660118/collection1 [junit4:junit4] 2> 3263 T1276 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:collection1 [junit4:junit4] 2> 3265 T1276 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 3265 T1276 oasc.ZkController.readConfigName Load colle= ction config from:/collections/collection1 [junit4:junit4] 2> 3267 T1276 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud= .BasicDistributedZk2Test-jetty1-1348289660118/collection1/' [junit4:junit4] 2> 3269 T1276 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTes= ts-trunk/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistribute= dZk2Test-jetty1-1348289660118/collection1/lib/classes/' to classloader [junit4:junit4] 2> 3269 T1276 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTes= ts-trunk/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistribute= dZk2Test-jetty1-1348289660118/collection1/lib/README' to classloader [junit4:junit4] 2> 3319 T1276 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_50 [junit4:junit4] 2> 3355 T1297 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 3376 T1296 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 3376 T1310 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 3396 T1276 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 3497 T1276 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 3504 T1276 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 4165 T1276 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 4183 T1276 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 4189 T1276 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 4211 T1276 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 4218 T1276 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 4224 T1276 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 4226 T1276 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 4229 T1276 oasc.SolrCore. [collection1] Opening = new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Nightly= Tests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistr= ibutedZk2Test-jetty1-1348289660118/collection1/, dataDir=3D./org.apache.sol= r.cloud.BasicDistributedZk2Test-1348289657217/jetty1/ [junit4:junit4] 2> 4229 T1276 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@27972e3a [junit4:junit4] 2> 4231 T1276 oasc.SolrCore.getNewIndexDir WARNING New in= dex directory detected: old=3Dnull new=3D./org.apache.solr.cloud.BasicDistr= ibutedZk2Test-1348289657217/jetty1/index/ [junit4:junit4] 2> 4232 T1276 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-13= 48289657217/jetty1/index' doesn't exist. Creating new index... [junit4:junit4] 2> 4233 T1276 oasc.CachingDirectoryFactory.get return new= directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyT= ests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistri= butedZk2Test-1348289657217/jetty1/index forceNew:false [junit4:junit4] 2> 4241 T1276 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -NightlyTests-trunk/solr/build/solr-core/test/J1/org.apache.solr.cloud.Basi= cDistributedZk2Test-1348289657217/jetty1/index lockFactory=3Dorg.apache.luc= ene.store.NativeFSLockFactory@2f7e1991; maxCacheMB=3D48.0 maxMergeSizeMB=3D= 4.0),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 4242 T1276 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1 [junit4:junit4] 2> 4243 T1276 oasc.SolrCore.initWriters created xml: solr= .XMLResponseWriter [junit4:junit4] 2> 4246 T1276 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 4247 T1276 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe-allfields" [junit4:junit4] 2> 4248 T1276 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 4249 T1276 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "u= niq-fields" [junit4:junit4] 2> 4250 T1276 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 4250 T1276 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 4251 T1276 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 4252 T1276 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 4253 T1276 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 4254 T1276 oasc.RequestHandlers.initHandlersFromConfig= created dismax: solr.SearchHandler [junit4:junit4] 2> 4255 T1276 oasc.RequestHandlers.initHandlersFromConfig= created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 4256 T1276 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 4257 T1276 oasc.RequestHandlers.initHandlersFromConfig= created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 4257 T1276 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 4258 T1276 oasc.RequestHandlers.initHandlersFromConfig= created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 4259 T1276 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 4260 T1276 oasc.RequestHandlers.initHandlersFromConfig= created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 4261 T1276 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 4262 T1276 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 4263 T1276 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 4266 T1276 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.= SearchHandler [junit4:junit4] 2> 4267 T1276 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 4268 T1276 oasc.RequestHandlers.initHandlersFromConfig= created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 4269 T1276 oasc.RequestHandlers.initHandlersFromConfig= created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 4270 T1276 oasc.RequestHandlers.initHandlersFromConfig= created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 4277 T1276 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 4285 T1276 oasc.SolrCore.initDeprecatedSupport WARNING= solrconfig.xml uses deprecated , Please update your c= onfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 4287 T1276 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> 4293 T1276 oass.SolrIndexSearcher. Opening Searc= her@1c16062b main [junit4:junit4] 2> 4295 T1276 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 4295 T1276 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 4296 T1276 oashc.SpellCheckComponent.inform Initializi= ng spell checkers [junit4:junit4] 2> 4317 T1276 oass.DirectSolrSpellChecker.init init: {nam= e=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLen= gth=3D3} [junit4:junit4] 2> 4655 T1311 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@1c16062b main{StandardDirectoryReader(se= gments_1:1)} [junit4:junit4] 2> 4891 T1297 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 4906 T1296 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 4906 T1310 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 5164 T1276 oasc.CoreContainer.register registering cor= e: collection1 [junit4:junit4] 2> 5164 T1276 oasc.ZkController.register Register shard -= core:collection1 address:http://127.0.0.1:23059/solr shardId:shard1 [junit4:junit4] 2> 5166 T1276 oascc.SolrZkClient.makePath makePath: /coll= ections/collection1/leader_elect/shard1/election [junit4:junit4] 2> 5189 T1281 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139ec5379880003 type:del= ete cxid:0x43 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> 5201 T1276 oasc.ShardLeaderElectionContext.runLeaderPr= ocess Running the leader process. [junit4:junit4] 2> 5202 T1281 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139ec5379880003 type:cre= ate cxid:0x44 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 5219 T1276 oasc.ShardLeaderElectionContext.waitForRepl= icasToComeUp Enough replicas found to continue. [junit4:junit4] 2> 5220 T1276 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I may be the new leader - try and sync [junit4:junit4] 2> 5220 T1276 oasc.SyncStrategy.sync Sync replicas to htt= p://127.0.0.1:23059/solr/collection1/ [junit4:junit4] 2> 5221 T1276 oasc.SyncStrategy.syncReplicas Sync Success= - now sync replicas to me [junit4:junit4] 2> 5222 T1276 oasc.SyncStrategy.syncToMe http://127.0.0.1= :23059/solr/collection1/ has no replicas [junit4:junit4] 2> 5222 T1276 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I am the new leader: http://127.0.0.1:23059/solr/collection1/ [junit4:junit4] 2> 5223 T1276 oascc.SolrZkClient.makePath makePath: /coll= ections/collection1/leaders/shard1 [junit4:junit4] 2> 5228 T1281 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139ec5379880003 type:cre= ate cxid:0x4e zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 5412 T1297 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 5428 T1296 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 5428 T1310 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 5442 T1276 oasc.ZkController.register We are http://12= 7.0.0.1:23059/solr/collection1/ and leader is http://127.0.0.1:23059/solr/c= ollection1/ [junit4:junit4] 2> 5442 T1276 oasc.ZkController.register No LogReplay nee= ded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:23059/solr [junit4:junit4] 2> 5443 T1276 oasc.ZkController.checkRecovery I am the le= ader, no recovery necessary [junit4:junit4] 2> 5449 T1276 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 5450 T1276 oass.SolrDispatchFilter.init user.dir=3D/us= r/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/bu= ild/solr-core/test/J1 [junit4:junit4] 2> 5450 T1276 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() done [junit4:junit4] 2> 5450 T1276 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fo= llowRedirects=3Dfalse [junit4:junit4] 2> 5607 T1276 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 5610 T1276 oejs.AbstractConnector.doStart Started Sock= etConnector@0.0.0.0:18297 [junit4:junit4] 2> 5610 T1276 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 5611 T1276 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.sol= r.cloud.BasicDistributedZk2Test-jetty2-1348289662676 [junit4:junit4] 2> 5611 T1276 oasc.SolrResourceLoader. new SolrReso= urceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/= Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.so= lr.cloud.BasicDistributedZk2Test-jetty2-1348289662676/' [junit4:junit4] 2> 5645 T1276 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() [junit4:junit4] 2> 5646 T1276 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 5646 T1276 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.sol= r.cloud.BasicDistributedZk2Test-jetty2-1348289662676 [junit4:junit4] 2> 5647 T1276 oasc.CoreContainer$Initializer.initialize l= ooking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Ni= ghtlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud.Basic= DistributedZk2Test-jetty2-1348289662676/solr.xml [junit4:junit4] 2> 5647 T1276 oasc.CoreContainer. New CoreContainer= 726955724 [junit4:junit4] 2> 5648 T1276 oasc.CoreContainer.load Loading CoreContain= er using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Ni= ghtlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud.Basic= DistributedZk2Test-jetty2-1348289662676/' [junit4:junit4] 2> 5648 T1276 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud= .BasicDistributedZk2Test-jetty2-1348289662676/' [junit4:junit4] 2> 5672 T1276 oasc.CoreContainer.load Registering Log Lis= tener [junit4:junit4] 2> 5688 T1276 oasc.CoreContainer.initZooKeeper Zookeeper = client=3D127.0.0.1:21057/solr [junit4:junit4] 2> 5689 T1276 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:21057/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@1a92210 [junit4:junit4] 2> 5690 T1321 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:21057 [junit4:junit4] 2> 5690 T1276 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 5691 T1321 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost/127.0.0.1:21057, initiating sessi= on [junit4:junit4] 2> 5691 T1278 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /140.211.11.196:19128 [junit4:junit4] 2> 5692 T1278 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /140.211.11.196:19128 [junit4:junit4] 2> 5694 T1280 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x139ec5379880004 with negotiated timeout 5000 for client /14= 0.211.11.196:19128 [junit4:junit4] 2> 5694 T1321 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server localhost/127.0.0.1:21057, sessio= nid =3D 0x139ec5379880004, negotiated timeout =3D 5000 [junit4:junit4] 2> 5694 T1322 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@1a92210 name:ZooKeeperConnectio= n Watcher:127.0.0.1:21057/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 5695 T1276 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 5696 T1281 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139ec5379880004 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> 5702 T1281 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139ec5379880004 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> 5708 T1276 oasc.ZkController.createEphemeralLiveNode R= egister node as live in ZooKeeper:/live_nodes/127.0.0.1:18297_solr [junit4:junit4] 2> 5709 T1281 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139ec5379880004 type:del= ete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/live_nodes/127.0.0.1:18297_solr Error:KeeperErrorCode =3D NoNode for= /solr/live_nodes/127.0.0.1:18297_solr [junit4:junit4] 2> 5719 T1276 oascc.SolrZkClient.makePath makePath: /live= _nodes/127.0.0.1:18297_solr [junit4:junit4] 2> 5726 T1296 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 5726 T1310 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 5728 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting socketTimeout to: 0 [junit4:junit4] 2> 5728 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting urlScheme to: http:// [junit4:junit4] 2> 5729 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting connTimeout to: 0 [junit4:junit4] 2> 5729 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 5729 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting corePoolSize to: 0 [junit4:junit4] 2> 5730 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 5730 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 5731 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting sizeOfQueue to: -1 [junit4:junit4] 2> 5731 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting fairnessPolicy to: false [junit4:junit4] 2> 5731 T1276 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&= socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 5753 T1276 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 5760 T1276 oasc.CoreContainer.create Creating SolrCore= 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.sol= r.cloud.BasicDistributedZk2Test-jetty2-1348289662676/collection1 [junit4:junit4] 2> 5760 T1276 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:collection1 [junit4:junit4] 2> 5761 T1276 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 5761 T1276 oasc.ZkController.readConfigName Load colle= ction config from:/collections/collection1 [junit4:junit4] 2> 5763 T1276 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud= .BasicDistributedZk2Test-jetty2-1348289662676/collection1/' [junit4:junit4] 2> 5764 T1276 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTes= ts-trunk/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistribute= dZk2Test-jetty2-1348289662676/collection1/lib/README' to classloader [junit4:junit4] 2> 5765 T1276 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTes= ts-trunk/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistribute= dZk2Test-jetty2-1348289662676/collection1/lib/classes/' to classloader [junit4:junit4] 2> 5816 T1276 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_50 [junit4:junit4] 2> 5888 T1276 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 5943 T1297 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 5967 T1296 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 5967 T1310 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 5989 T1322 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 5989 T1276 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 5996 T1276 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 6659 T1276 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 6671 T1276 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 6675 T1276 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 6695 T1276 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 6701 T1276 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 6706 T1276 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 6708 T1276 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 6710 T1276 oasc.SolrCore. [collection1] Opening = new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Nightly= Tests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistr= ibutedZk2Test-jetty2-1348289662676/collection1/, dataDir=3D./org.apache.sol= r.cloud.BasicDistributedZk2Test-1348289657217/jetty2/ [junit4:junit4] 2> 6710 T1276 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@27972e3a [junit4:junit4] 2> 6712 T1276 oasc.SolrCore.getNewIndexDir WARNING New in= dex directory detected: old=3Dnull new=3D./org.apache.solr.cloud.BasicDistr= ibutedZk2Test-1348289657217/jetty2/index/ [junit4:junit4] 2> 6713 T1276 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-13= 48289657217/jetty2/index' doesn't exist. Creating new index... [junit4:junit4] 2> 6714 T1276 oasc.CachingDirectoryFactory.get return new= directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyT= ests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistri= butedZk2Test-1348289657217/jetty2/index forceNew:false [junit4:junit4] 2> 6727 T1276 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -NightlyTests-trunk/solr/build/solr-core/test/J1/org.apache.solr.cloud.Basi= cDistributedZk2Test-1348289657217/jetty2/index lockFactory=3Dorg.apache.luc= ene.store.NativeFSLockFactory@48931b44; maxCacheMB=3D48.0 maxMergeSizeMB=3D= 4.0),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 6728 T1276 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1 [junit4:junit4] 2> 6729 T1276 oasc.SolrCore.initWriters created xml: solr= .XMLResponseWriter [junit4:junit4] 2> 6731 T1276 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 6732 T1276 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe-allfields" [junit4:junit4] 2> 6732 T1276 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 6733 T1276 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "u= niq-fields" [junit4:junit4] 2> 6733 T1276 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 6734 T1276 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 6734 T1276 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 6735 T1276 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 6736 T1276 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 6736 T1276 oasc.RequestHandlers.initHandlersFromConfig= created dismax: solr.SearchHandler [junit4:junit4] 2> 6737 T1276 oasc.RequestHandlers.initHandlersFromConfig= created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 6738 T1276 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 6738 T1276 oasc.RequestHandlers.initHandlersFromConfig= created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 6739 T1276 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 6739 T1276 oasc.RequestHandlers.initHandlersFromConfig= created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 6740 T1276 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 6741 T1276 oasc.RequestHandlers.initHandlersFromConfig= created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 6741 T1276 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 6742 T1276 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 6743 T1276 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 6743 T1276 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.= SearchHandler [junit4:junit4] 2> 6744 T1276 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 6744 T1276 oasc.RequestHandlers.initHandlersFromConfig= created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 6745 T1276 oasc.RequestHandlers.initHandlersFromConfig= created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 6746 T1276 oasc.RequestHandlers.initHandlersFromConfig= created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 6749 T1276 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 6754 T1276 oasc.SolrCore.initDeprecatedSupport WARNING= solrconfig.xml uses deprecated , Please update your c= onfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 6755 T1276 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> 6759 T1276 oass.SolrIndexSearcher. Opening Searc= her@38942215 main [junit4:junit4] 2> 6760 T1276 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 6761 T1276 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 6761 T1276 oashc.SpellCheckComponent.inform Initializi= ng spell checkers [junit4:junit4] 2> 6778 T1276 oass.DirectSolrSpellChecker.init init: {nam= e=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLen= gth=3D3} [junit4:junit4] 2> 6826 T1323 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@38942215 main{StandardDirectoryReader(se= gments_1:1)} [junit4:junit4] 2> 6984 T1297 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 7004 T1296 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 7004 T1310 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 7004 T1322 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 7334 T1276 oasc.CoreContainer.register registering cor= e: collection1 [junit4:junit4] 2> 7334 T1276 oasc.ZkController.register Register shard -= core:collection1 address:http://127.0.0.1:18297/solr shardId:shard2 [junit4:junit4] 2> 7335 T1276 oascc.SolrZkClient.makePath makePath: /coll= ections/collection1/leader_elect/shard2/election [junit4:junit4] 2> 7351 T1281 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139ec5379880004 type:del= ete cxid:0x42 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/collections/collection1/leaders/shard2 Error:KeeperErrorCode =3D No= Node for /solr/collections/collection1/leaders/shard2 [junit4:junit4] 2> 7352 T1276 oasc.ShardLeaderElectionContext.runLeaderPr= ocess Running the leader process. [junit4:junit4] 2> 7353 T1281 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139ec5379880004 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> 7363 T1276 oasc.ShardLeaderElectionContext.waitForRepl= icasToComeUp Enough replicas found to continue. [junit4:junit4] 2> 7363 T1276 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I may be the new leader - try and sync [junit4:junit4] 2> 7364 T1276 oasc.SyncStrategy.sync Sync replicas to htt= p://127.0.0.1:18297/solr/collection1/ [junit4:junit4] 2> 7364 T1276 oasc.SyncStrategy.syncReplicas Sync Success= - now sync replicas to me [junit4:junit4] 2> 7365 T1276 oasc.SyncStrategy.syncToMe http://127.0.0.1= :18297/solr/collection1/ has no replicas [junit4:junit4] 2> 7365 T1276 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I am the new leader: http://127.0.0.1:18297/solr/collection1/ [junit4:junit4] 2> 7366 T1276 oascc.SolrZkClient.makePath makePath: /coll= ections/collection1/leaders/shard2 [junit4:junit4] 2> 7372 T1281 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139ec5379880004 type:cre= ate cxid:0x4c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 7517 T1297 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 7538 T1296 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 7538 T1322 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 7538 T1310 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 7543 T1276 oasc.ZkController.register We are http://12= 7.0.0.1:18297/solr/collection1/ and leader is http://127.0.0.1:18297/solr/c= ollection1/ [junit4:junit4] 2> 7543 T1276 oasc.ZkController.register No LogReplay nee= ded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:18297/solr [junit4:junit4] 2> 7544 T1276 oasc.ZkController.checkRecovery I am the le= ader, no recovery necessary [junit4:junit4] 2> 7550 T1276 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 7551 T1276 oass.SolrDispatchFilter.init user.dir=3D/us= r/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/bu= ild/solr-core/test/J1 [junit4:junit4] 2> 7552 T1276 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() done [junit4:junit4] 2> 7552 T1276 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fo= llowRedirects=3Dfalse [junit4:junit4] 2> 7735 T1276 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 7738 T1276 oejs.AbstractConnector.doStart Started Sock= etConnector@0.0.0.0:22060 [junit4:junit4] 2> 7739 T1276 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 7740 T1276 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.sol= r.cloud.BasicDistributedZk2Test-jetty3-1348289664781 [junit4:junit4] 2> 7741 T1276 oasc.SolrResourceLoader. new SolrReso= urceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/= Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.so= lr.cloud.BasicDistributedZk2Test-jetty3-1348289664781/' [junit4:junit4] 2> 7780 T1276 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() [junit4:junit4] 2> 7780 T1276 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 7781 T1276 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.sol= r.cloud.BasicDistributedZk2Test-jetty3-1348289664781 [junit4:junit4] 2> 7781 T1276 oasc.CoreContainer$Initializer.initialize l= ooking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Ni= ghtlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud.Basic= DistributedZk2Test-jetty3-1348289664781/solr.xml [junit4:junit4] 2> 7781 T1276 oasc.CoreContainer. New CoreContainer= 454334650 [junit4:junit4] 2> 7782 T1276 oasc.CoreContainer.load Loading CoreContain= er using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Ni= ghtlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud.Basic= DistributedZk2Test-jetty3-1348289664781/' [junit4:junit4] 2> 7782 T1276 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud= .BasicDistributedZk2Test-jetty3-1348289664781/' [junit4:junit4] 2> 7809 T1276 oasc.CoreContainer.load Registering Log Lis= tener [junit4:junit4] 2> 7827 T1276 oasc.CoreContainer.initZooKeeper Zookeeper = client=3D127.0.0.1:21057/solr [junit4:junit4] 2> 7828 T1276 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:21057/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@7809d118 [junit4:junit4] 2> 7829 T1333 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:21057 [junit4:junit4] 2> 7829 T1276 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 7830 T1333 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost/127.0.0.1:21057, initiating sessi= on [junit4:junit4] 2> 7830 T1278 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /140.211.11.196:28531 [junit4:junit4] 2> 7831 T1278 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /140.211.11.196:28531 [junit4:junit4] 2> 7832 T1280 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x139ec5379880005 with negotiated timeout 5000 for client /14= 0.211.11.196:28531 [junit4:junit4] 2> 7832 T1333 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server localhost/127.0.0.1:21057, sessio= nid =3D 0x139ec5379880005, negotiated timeout =3D 5000 [junit4:junit4] 2> 7833 T1334 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@7809d118 name:ZooKeeperConnecti= on Watcher:127.0.0.1:21057/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4:junit4] 2> 7833 T1276 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 7834 T1281 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139ec5379880005 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> 7836 T1281 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139ec5379880005 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> 7847 T1276 oasc.ZkController.createEphemeralLiveNode R= egister node as live in ZooKeeper:/live_nodes/127.0.0.1:22060_solr [junit4:junit4] 2> 7848 T1281 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139ec5379880005 type:del= ete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/live_nodes/127.0.0.1:22060_solr Error:KeeperErrorCode =3D NoNode for= /solr/live_nodes/127.0.0.1:22060_solr [junit4:junit4] 2> 7854 T1276 oascc.SolrZkClient.makePath makePath: /live= _nodes/127.0.0.1:22060_solr [junit4:junit4] 2> 7865 T1296 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 7865 T1310 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 7865 T1322 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 7867 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting socketTimeout to: 0 [junit4:junit4] 2> 7867 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting urlScheme to: http:// [junit4:junit4] 2> 7867 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting connTimeout to: 0 [junit4:junit4] 2> 7868 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 7868 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting corePoolSize to: 0 [junit4:junit4] 2> 7869 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 7869 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 7869 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting sizeOfQueue to: -1 [junit4:junit4] 2> 7870 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting fairnessPolicy to: false [junit4:junit4] 2> 7870 T1276 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&= socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 7892 T1276 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 7901 T1276 oasc.CoreContainer.create Creating SolrCore= 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.sol= r.cloud.BasicDistributedZk2Test-jetty3-1348289664781/collection1 [junit4:junit4] 2> 7902 T1276 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:collection1 [junit4:junit4] 2> 7903 T1276 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 7903 T1276 oasc.ZkController.readConfigName Load colle= ction config from:/collections/collection1 [junit4:junit4] 2> 7905 T1276 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud= .BasicDistributedZk2Test-jetty3-1348289664781/collection1/' [junit4:junit4] 2> 7906 T1276 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTes= ts-trunk/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistribute= dZk2Test-jetty3-1348289664781/collection1/lib/classes/' to classloader [junit4:junit4] 2> 7907 T1276 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTes= ts-trunk/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistribute= dZk2Test-jetty3-1348289664781/collection1/lib/README' to classloader [junit4:junit4] 2> 7973 T1276 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_50 [junit4:junit4] 2> 8053 T1297 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 8058 T1276 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 8071 T1296 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 8071 T1322 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 8071 T1310 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 8160 T1334 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 8160 T1276 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 8167 T1276 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 8788 T1276 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 8800 T1276 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 8803 T1276 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 8820 T1276 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 8825 T1276 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 8830 T1276 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 8831 T1276 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 8833 T1276 oasc.SolrCore. [collection1] Opening = new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Nightly= Tests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistr= ibutedZk2Test-jetty3-1348289664781/collection1/, dataDir=3D./org.apache.sol= r.cloud.BasicDistributedZk2Test-1348289657217/jetty3/ [junit4:junit4] 2> 8834 T1276 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@27972e3a [junit4:junit4] 2> 8835 T1276 oasc.SolrCore.getNewIndexDir WARNING New in= dex directory detected: old=3Dnull new=3D./org.apache.solr.cloud.BasicDistr= ibutedZk2Test-1348289657217/jetty3/index/ [junit4:junit4] 2> 8836 T1276 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-13= 48289657217/jetty3/index' doesn't exist. Creating new index... [junit4:junit4] 2> 8836 T1276 oasc.CachingDirectoryFactory.get return new= directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyT= ests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistri= butedZk2Test-1348289657217/jetty3/index forceNew:false [junit4:junit4] 2> 8851 T1276 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -NightlyTests-trunk/solr/build/solr-core/test/J1/org.apache.solr.cloud.Basi= cDistributedZk2Test-1348289657217/jetty3/index lockFactory=3Dorg.apache.luc= ene.store.NativeFSLockFactory@6e61b3ff; maxCacheMB=3D48.0 maxMergeSizeMB=3D= 4.0),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 8852 T1276 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1 [junit4:junit4] 2> 8853 T1276 oasc.SolrCore.initWriters created xml: solr= .XMLResponseWriter [junit4:junit4] 2> 8855 T1276 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 8856 T1276 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe-allfields" [junit4:junit4] 2> 8856 T1276 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 8856 T1276 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "u= niq-fields" [junit4:junit4] 2> 8857 T1276 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 8858 T1276 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 8858 T1276 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 8859 T1276 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 8860 T1276 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 8860 T1276 oasc.RequestHandlers.initHandlersFromConfig= created dismax: solr.SearchHandler [junit4:junit4] 2> 8861 T1276 oasc.RequestHandlers.initHandlersFromConfig= created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 8862 T1276 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 8863 T1276 oasc.RequestHandlers.initHandlersFromConfig= created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 8864 T1276 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 8864 T1276 oasc.RequestHandlers.initHandlersFromConfig= created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 8865 T1276 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 8866 T1276 oasc.RequestHandlers.initHandlersFromConfig= created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 8866 T1276 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 8867 T1276 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 8868 T1276 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 8868 T1276 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.= SearchHandler [junit4:junit4] 2> 8869 T1276 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 8870 T1276 oasc.RequestHandlers.initHandlersFromConfig= created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 8870 T1276 oasc.RequestHandlers.initHandlersFromConfig= created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 8871 T1276 oasc.RequestHandlers.initHandlersFromConfig= created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 8876 T1276 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 8882 T1276 oasc.SolrCore.initDeprecatedSupport WARNING= solrconfig.xml uses deprecated , Please update your c= onfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 8883 T1276 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> 8888 T1276 oass.SolrIndexSearcher. Opening Searc= her@43ff887 main [junit4:junit4] 2> 8890 T1276 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 8890 T1276 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 8891 T1276 oashc.SpellCheckComponent.inform Initializi= ng spell checkers [junit4:junit4] 2> 8908 T1276 oass.DirectSolrSpellChecker.init init: {nam= e=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLen= gth=3D3} [junit4:junit4] 2> 8992 T1335 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@43ff887 main{StandardDirectoryReader(seg= ments_1:1)} [junit4:junit4] 2> 9079 T1297 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 9091 T1296 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 9091 T1310 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 9091 T1322 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 9091 T1334 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 9500 T1276 oasc.CoreContainer.register registering cor= e: collection1 [junit4:junit4] 2> 9500 T1276 oasc.ZkController.register Register shard -= core:collection1 address:http://127.0.0.1:22060/solr shardId:shard1 [junit4:junit4] 2> 9505 T1276 oasc.ZkController.register We are http://12= 7.0.0.1:22060/solr/collection1/ and leader is http://127.0.0.1:23059/solr/c= ollection1/ [junit4:junit4] 2> 9506 T1276 oasc.ZkController.register No LogReplay nee= ded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:22060/solr [junit4:junit4] 2> 9506 T1276 oasc.ZkController.checkRecovery Core needs = to recover:collection1 [junit4:junit4] 2> 9506 T1276 oasu.DefaultSolrCoreState.doRecovery Runnin= g recovery - first canceling any ongoing recovery [junit4:junit4] 2> ASYNC NEW_CORE C118 name=3Dcollection1 org.apache.sol= r.core.SolrCore@5855207d url=3Dhttp://127.0.0.1:22060/solr/collection1 node= =3D127.0.0.1:22060_solr [junit4:junit4] 2> 9515 T1336 C118 P22060 oasc.RecoveryStrategy.run Start= ing recovery process. core=3Dcollection1 recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 9516 T1336 C118 P22060 oasc.RecoveryStrategy.doRecover= y ###### startupVersions=3D[] [junit4:junit4] 2> 9516 T1276 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 9517 T1276 oass.SolrDispatchFilter.init user.dir=3D/us= r/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/bu= ild/solr-core/test/J1 [junit4:junit4] 2> 9518 T1276 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() done [junit4:junit4] 2> 9518 T1276 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fo= llowRedirects=3Dfalse [junit4:junit4] 2> 9540 T1336 C118 P22060 oasc.RecoveryStrategy.doRecover= y Attempting to PeerSync from http://127.0.0.1:23059/solr/collection1/ core= =3Dcollection1 - recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 9557 T1336 C118 P22060 oascsi.HttpClientUtil.createCli= ent Creating new http client, config:maxConnectionsPerHost=3D20&maxConnecti= ons=3D10000&connTimeout=3D30000&socketTimeout=3D30000&retry=3Dfalse [junit4:junit4] 2> 9577 T1336 C118 P22060 oasu.PeerSync.sync PeerSync: co= re=3Dcollection1 url=3Dhttp://127.0.0.1:22060/solr START replicas=3D[http:/= /127.0.0.1:23059/solr/collection1/] nUpdates=3D100 [junit4:junit4] 2> 9578 T1336 C118 P22060 oasu.PeerSync.sync WARNING no f= rame of reference to tell of we've missed updates [junit4:junit4] 2> 9579 T1336 C118 P22060 oasc.RecoveryStrategy.doRecover= y PeerSync Recovery was not successful - trying replication. core=3Dcollect= ion1 [junit4:junit4] 2> 9579 T1336 C118 P22060 oasc.RecoveryStrategy.doRecover= y Starting Replication Recovery. core=3Dcollection1 [junit4:junit4] 2> 9579 T1336 C118 P22060 oascsi.HttpClientUtil.createCli= ent Creating new http client, config:maxConnections=3D128&maxConnectionsPer= Host=3D32&followRedirects=3Dfalse [junit4:junit4] 2> ASYNC NEW_CORE C119 name=3Dcollection1 org.apache.sol= r.core.SolrCore@d44d2cc url=3Dhttp://127.0.0.1:23059/solr/collection1 node= =3D127.0.0.1:23059_solr [junit4:junit4] 2> 9589 T1302 C119 P23059 REQ /get {distrib=3Dfalse&qt=3D= /get&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 9597 T1297 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 9700 T1276 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 9702 T1276 oejs.AbstractConnector.doStart Started Sock= etConnector@0.0.0.0:56003 [junit4:junit4] 2> 9703 T1276 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 9704 T1276 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.sol= r.cloud.BasicDistributedZk2Test-jetty4-1348289666750 [junit4:junit4] 2> 9704 T1276 oasc.SolrResourceLoader. new SolrReso= urceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/= Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.so= lr.cloud.BasicDistributedZk2Test-jetty4-1348289666750/' [junit4:junit4] 2> 9739 T1276 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() [junit4:junit4] 2> 9739 T1276 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 9740 T1276 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.sol= r.cloud.BasicDistributedZk2Test-jetty4-1348289666750 [junit4:junit4] 2> 9740 T1276 oasc.CoreContainer$Initializer.initialize l= ooking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Ni= ghtlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud.Basic= DistributedZk2Test-jetty4-1348289666750/solr.xml [junit4:junit4] 2> 9740 T1276 oasc.CoreContainer. New CoreContainer= 2128408255 [junit4:junit4] 2> 9741 T1276 oasc.CoreContainer.load Loading CoreContain= er using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Ni= ghtlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud.Basic= DistributedZk2Test-jetty4-1348289666750/' [junit4:junit4] 2> 9741 T1276 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud= .BasicDistributedZk2Test-jetty4-1348289666750/' [junit4:junit4] 2> 9765 T1276 oasc.CoreContainer.load Registering Log Lis= tener [junit4:junit4] 2> 9771 T1296 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 9771 T1322 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 9771 T1310 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 9771 T1334 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 9780 T1276 oasc.CoreContainer.initZooKeeper Zookeeper = client=3D127.0.0.1:21057/solr [junit4:junit4] 2> 9780 T1276 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:21057/solr sessionTimeout=3D5000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@d016b48 [junit4:junit4] 2> 9781 T1347 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:21057 [junit4:junit4] 2> 9781 T1276 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 9782 T1347 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost/127.0.0.1:21057, initiating sessi= on [junit4:junit4] 2> 9782 T1278 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /140.211.11.196:37706 [junit4:junit4] 2> 9783 T1278 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /140.211.11.196:37706 [junit4:junit4] 2> 9848 T1280 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x139ec5379880006 with negotiated timeout 5000 for client /14= 0.211.11.196:37706 [junit4:junit4] 2> 9848 T1347 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server localhost/127.0.0.1:21057, sessio= nid =3D 0x139ec5379880006, negotiated timeout =3D 5000 [junit4:junit4] 2> 9848 T1348 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@d016b48 name:ZooKeeperConnectio= n Watcher:127.0.0.1:21057/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 9848 T1276 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 9850 T1281 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139ec5379880006 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> 9881 T1281 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139ec5379880006 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> 9894 T1276 oasc.ZkController.createEphemeralLiveNode R= egister node as live in ZooKeeper:/live_nodes/127.0.0.1:56003_solr [junit4:junit4] 2> 9894 T1281 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139ec5379880006 type:del= ete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/live_nodes/127.0.0.1:56003_solr Error:KeeperErrorCode =3D NoNode for= /solr/live_nodes/127.0.0.1:56003_solr [junit4:junit4] 2> 9904 T1276 oascc.SolrZkClient.makePath makePath: /live= _nodes/127.0.0.1:56003_solr [junit4:junit4] 2> 9907 T1334 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 9907 T1296 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 9907 T1322 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 9907 T1310 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 9909 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting socketTimeout to: 0 [junit4:junit4] 2> 9909 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting urlScheme to: http:// [junit4:junit4] 2> 9910 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting connTimeout to: 0 [junit4:junit4] 2> 9910 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 9910 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting corePoolSize to: 0 [junit4:junit4] 2> 9911 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 9911 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 9912 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting sizeOfQueue to: -1 [junit4:junit4] 2> 9912 T1276 oashc.HttpShardHandlerFactory.getParameter = Setting fairnessPolicy to: false [junit4:junit4] 2> 9913 T1276 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&= socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 9933 T1276 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 9940 T1276 oasc.CoreContainer.create Creating SolrCore= 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.sol= r.cloud.BasicDistributedZk2Test-jetty4-1348289666750/collection1 [junit4:junit4] 2> 9940 T1276 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:collection1 [junit4:junit4] 2> 9941 T1276 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 9941 T1276 oasc.ZkController.readConfigName Load colle= ction config from:/collections/collection1 [junit4:junit4] 2> 9943 T1276 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud= .BasicDistributedZk2Test-jetty4-1348289666750/collection1/' [junit4:junit4] 2> 9945 T1276 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTes= ts-trunk/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistribute= dZk2Test-jetty4-1348289666750/collection1/lib/README' to classloader [junit4:junit4] 2> 9946 T1276 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTes= ts-trunk/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistribute= dZk2Test-jetty4-1348289666750/collection1/lib/classes/' to classloader [junit4:junit4] 2> 10000 T1276 oasc.SolrConfig. Using Lucene MatchV= ersion: LUCENE_50 [junit4:junit4] 2> 10081 T1276 oasc.SolrConfig. Loaded SolrConfig: = solrconfig.xml [junit4:junit4] 2> 10182 T1276 oass.IndexSchema.readSchema Reading Solr S= chema [junit4:junit4] 2> 10189 T1276 oass.IndexSchema.readSchema Schema name=3D= test [junit4:junit4] 2> 10774 T1276 oass.OpenExchangeRatesOrgProvider.init Ini= tialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 10783 T1276 oass.IndexSchema.readSchema default search= field in schema is text [junit4:junit4] 2> 10787 T1276 oass.IndexSchema.readSchema unique key fie= ld: id [junit4:junit4] 2> 10803 T1276 oass.FileExchangeRateProvider.reload Reloa= ding exchange rates from file currency.xml [junit4:junit4] 2> 10807 T1276 oass.FileExchangeRateProvider.reload Reloa= ding exchange rates from file currency.xml [junit4:junit4] 2> 10814 T1276 oass.OpenExchangeRatesOrgProvider.reload R= eloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 10815 T1276 oass.OpenExchangeRatesOrgProvider.reload R= eloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 10816 T1276 oasc.SolrCore. [collection1] Opening= new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Nightl= yTests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDist= ributedZk2Test-jetty4-1348289666750/collection1/, dataDir=3D./org.apache.so= lr.cloud.BasicDistributedZk2Test-1348289657217/jetty4/ [junit4:junit4] 2> 10817 T1276 oasc.JmxMonitoredMap. JMX monitoring= is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxM= BeanServer@27972e3a [junit4:junit4] 2> 10817 T1276 oasc.SolrCore.getNewIndexDir WARNING New i= ndex directory detected: old=3Dnull new=3D./org.apache.solr.cloud.BasicDist= ributedZk2Test-1348289657217/jetty4/index/ [junit4:junit4] 2> 10818 T1276 oasc.SolrCore.initIndex WARNING [collectio= n1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1= 348289657217/jetty4/index' doesn't exist. Creating new index... [junit4:junit4] 2> 10819 T1276 oasc.CachingDirectoryFactory.get return ne= w directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Nightly= Tests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistr= ibutedZk2Test-1348289657217/jetty4/index forceNew:false [junit4:junit4] 2> 10837 T1276 oasc.SolrDeletionPolicy.onCommit SolrDelet= ionPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -NightlyTests-trunk/solr/build/solr-core/test/J1/org.apache.solr.cloud.Basi= cDistributedZk2Test-1348289657217/jetty4/index lockFactory=3Dorg.apache.luc= ene.store.NativeFSLockFactory@7e2fa610; maxCacheMB=3D48.0 maxMergeSizeMB=3D= 4.0),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 10837 T1276 oasc.SolrDeletionPolicy.updateCommits newe= st commit =3D 1 [junit4:junit4] 2> 10838 T1276 oasc.SolrCore.initWriters created xml: sol= r.XMLResponseWriter [junit4:junit4] 2> 10840 T1276 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= dedupe" [junit4:junit4] 2> 10840 T1276 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= dedupe-allfields" [junit4:junit4] 2> 10840 T1276 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= stored_sig" [junit4:junit4] 2> 10841 T1276 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= uniq-fields" [junit4:junit4] 2> 10841 T1276 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= distrib-dup-test-chain-implicit" [junit4:junit4] 2> 10842 T1276 oasc.RequestHandlers.initHandlersFromConfi= g adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 10842 T1276 oasc.RequestHandlers.initHandlersFromConfi= g created /replication: solr.ReplicationHandler [junit4:junit4] 2> 10842 T1276 oasc.RequestHandlers.initHandlersFromConfi= g created standard: solr.StandardRequestHandler [junit4:junit4] 2> 10843 T1276 oasc.RequestHandlers.initHandlersFromConfi= g created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 10843 T1276 oasc.RequestHandlers.initHandlersFromConfi= g created dismax: solr.SearchHandler [junit4:junit4] 2> 10844 T1276 oasc.RequestHandlers.initHandlersFromConfi= g created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 10844 T1276 oasc.RequestHandlers.initHandlersFromConfi= g created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 10845 T1276 oasc.RequestHandlers.initHandlersFromConfi= g created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 10845 T1276 oasc.RequestHandlers.initHandlersFromConfi= g adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 10845 T1276 oasc.RequestHandlers.initHandlersFromConfi= g created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 10846 T1276 oasc.RequestHandlers.initHandlersFromConfi= g created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 10846 T1276 oasc.RequestHandlers.initHandlersFromConfi= g created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 10847 T1276 oasc.RequestHandlers.initHandlersFromConfi= g created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 10847 T1276 oasc.RequestHandlers.initHandlersFromConfi= g created spellCheckCompRH_Direct: org.apache.solr.handler.component.Search= Handler [junit4:junit4] 2> 10848 T1276 oasc.RequestHandlers.initHandlersFromConfi= g created spellCheckWithWordbreak: org.apache.solr.handler.component.Search= Handler [junit4:junit4] 2> 10848 T1276 oasc.RequestHandlers.initHandlersFromConfi= g created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component= .SearchHandler [junit4:junit4] 2> 10849 T1276 oasc.RequestHandlers.initHandlersFromConfi= g created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandle= r [junit4:junit4] 2> 10849 T1276 oasc.RequestHandlers.initHandlersFromConfi= g created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 10850 T1276 oasc.RequestHandlers.initHandlersFromConfi= g created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 10850 T1276 oasc.RequestHandlers.initHandlersFromConfi= g created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 10853 T1276 oashl.XMLLoader.init xsltCacheLifetimeSeco= nds=3D60 [junit4:junit4] 2> 10856 T1276 oasc.SolrCore.initDeprecatedSupport WARNIN= G solrconfig.xml uses deprecated , Please update your = config to use the ShowFileRequestHandler. [junit4:junit4] 2> 10857 T1276 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> 10860 T1276 oass.SolrIndexSearcher. Opening Sear= cher@4d016caf main [junit4:junit4] 2> 10861 T1276 oasu.CommitTracker. Hard AutoCommit:= disabled [junit4:junit4] 2> 10861 T1276 oasu.CommitTracker. Soft AutoCommit:= disabled [junit4:junit4] 2> 10862 T1276 oashc.SpellCheckComponent.inform Initializ= ing spell checkers [junit4:junit4] 2> 10877 T1276 oass.DirectSolrSpellChecker.init init: {na= me=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLe= ngth=3D3} [junit4:junit4] 2> 10966 T1349 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@4d016caf main{StandardDirectoryReader(s= egments_1:1)} [junit4:junit4] 2> 11355 T1297 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 11378 T1334 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 11378 T1348 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 11378 T1310 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 11378 T1322 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 11378 T1296 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 11478 T1276 oasc.CoreContainer.register registering co= re: collection1 [junit4:junit4] 2> 11478 T1276 oasc.ZkController.register Register shard = - core:collection1 address:http://127.0.0.1:56003/solr shardId:shard2 [junit4:junit4] 2> 11483 T1276 oasc.ZkController.register We are http://1= 27.0.0.1:56003/solr/collection1/ and leader is http://127.0.0.1:18297/solr/= collection1/ [junit4:junit4] 2> 11483 T1276 oasc.ZkController.register No LogReplay ne= eded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:56003/solr [junit4:junit4] 2> 11483 T1276 oasc.ZkController.checkRecovery Core needs= to recover:collection1 [junit4:junit4] 2> 11484 T1276 oasu.DefaultSolrCoreState.doRecovery Runni= ng recovery - first canceling any ongoing recovery [junit4:junit4] 2> ASYNC NEW_CORE C120 name=3Dcollection1 org.apache.sol= r.core.SolrCore@24c560f1 url=3Dhttp://127.0.0.1:56003/solr/collection1 node= =3D127.0.0.1:56003_solr [junit4:junit4] 2> 11485 T1350 C120 P56003 oasc.RecoveryStrategy.run Star= ting recovery process. core=3Dcollection1 recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 11485 T1350 C120 P56003 oasc.RecoveryStrategy.doRecove= ry ###### startupVersions=3D[] [junit4:junit4] 2> 11485 T1276 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 11491 T1350 C120 P56003 oasc.RecoveryStrategy.doRecove= ry Attempting to PeerSync from http://127.0.0.1:18297/solr/collection1/ cor= e=3Dcollection1 - recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 11491 T1350 C120 P56003 oasu.PeerSync.sync PeerSync: c= ore=3Dcollection1 url=3Dhttp://127.0.0.1:56003/solr START replicas=3D[http:= //127.0.0.1:18297/solr/collection1/] nUpdates=3D100 [junit4:junit4] 2> 11491 T1276 oass.SolrDispatchFilter.init user.dir=3D/u= sr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/b= uild/solr-core/test/J1 [junit4:junit4] 2> 11491 T1350 C120 P56003 oasu.PeerSync.sync WARNING no = frame of reference to tell of we've missed updates [junit4:junit4] 2> 11491 T1276 oass.SolrDispatchFilter.init SolrDispatchF= ilter.init() done [junit4:junit4] 2> 11492 T1350 C120 P56003 oasc.RecoveryStrategy.doRecove= ry PeerSync Recovery was not successful - trying replication. core=3Dcollec= tion1 [junit4:junit4] 2> 11492 T1276 oascsi.HttpClientUtil.createClient Creatin= g new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&f= ollowRedirects=3Dfalse [junit4:junit4] 2> 11492 T1350 C120 P56003 oasc.RecoveryStrategy.doRecove= ry Starting Replication Recovery. core=3Dcollection1 [junit4:junit4] 2> 11492 T1350 C120 P56003 oascsi.HttpClientUtil.createCl= ient Creating new http client, config:maxConnections=3D128&maxConnectionsPe= rHost=3D32&followRedirects=3Dfalse [junit4:junit4] 2> ASYNC NEW_CORE C121 name=3Dcollection1 org.apache.sol= r.core.SolrCore@73714f4a url=3Dhttp://127.0.0.1:18297/solr/collection1 node= =3D127.0.0.1:18297_solr [junit4:junit4] 2> 11498 T1314 C121 P18297 REQ /get {distrib=3Dfalse&qt= =3D/get&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 11509 T1276 oaz.ZooKeeper. Initiating client con= nection, connectString=3D127.0.0.1:21057/solr sessionTimeout=3D10000 watche= r=3Dorg.apache.solr.common.cloud.ConnectionManager@4fbea764 [junit4:junit4] 2> 11509 T1276 oascc.ConnectionManager.waitForConnected W= aiting for client to connect to ZooKeeper [junit4:junit4] 2> 11510 T1352 oaz.ClientCnxn$SendThread.startConnect Ope= ning socket connection to server /127.0.0.1:21057 [junit4:junit4] 2> 11511 T1352 oaz.ClientCnxn$SendThread.primeConnection = Socket connection established to localhost/127.0.0.1:21057, initiating sess= ion [junit4:junit4] 2> 11511 T1278 oazs.NIOServerCnxn$Factory.run Accepted so= cket connection from /140.211.11.196:34848 [junit4:junit4] 2> 11511 T1278 oazs.NIOServerCnxn.readConnectRequest Clie= nt attempting to establish new session at /140.211.11.196:34848 [junit4:junit4] 2> 11530 T1280 oazs.NIOServerCnxn.finishSessionInit Estab= lished session 0x139ec5379880007 with negotiated timeout 10000 for client /= 140.211.11.196:34848 [junit4:junit4] 2> 11530 T1352 oaz.ClientCnxn$SendThread.readConnectResul= t Session establishment complete on server localhost/127.0.0.1:21057, sessi= onid =3D 0x139ec5379880007, negotiated timeout =3D 10000 [junit4:junit4] 2> 11531 T1353 oascc.ConnectionManager.process Watcher or= g.apache.solr.common.cloud.ConnectionManager@4fbea764 name:ZooKeeperConnect= ion Watcher:127.0.0.1:21057/solr got event WatchedEvent state:SyncConnected= type:None path:null path:null type:None [junit4:junit4] 2> 11531 T1276 oascc.ConnectionManager.waitForConnected C= lient is connected to ZooKeeper [junit4:junit4] 2> 11532 T1276 oascc.ZkStateReader.createClusterStateWatc= hersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 11534 T1276 oasc.ChaosMonkey.monkeyLog monkey: init - = expire sessions:true cause connection loss:true [junit4:junit4] 2> 11534 T1276 oascsi.HttpClientUtil.createClient Creatin= g new http client, config: [junit4:junit4] 2> 11541 T1276 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> ASYNC NEW_CORE C122 name=3Dcollection1 org.apache.sol= r.core.SolrCore@9574cdc url=3Dhttp://127.0.0.1:47845/solr/collection1 node= =3D127.0.0.1:47845_solr [junit4:junit4] 2> 11559 T1288 C122 P47845 oascsi.HttpClientUtil.createCl= ient Creating new http client, config:maxConnections=3D500&maxConnectionsPe= rHost=3D16 [junit4:junit4] 2> 11574 T1288 C122 P47845 oasc.SolrDeletionPolicy.onInit= SolrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -NightlyTests-trunk/solr/build/solr-core/test/J1/org.apache.solr.cloud.Basi= cDistributedZk2Test-1348289657217/control/data/index lockFactory=3Dorg.apac= he.lucene.store.NativeFSLockFactory@1bf55430; maxCacheMB=3D48.0 maxMergeSiz= eMB=3D4.0),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 11574 T1288 C122 P47845 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 1 [junit4:junit4] 2> 11579 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[1 (1413784187733803008)]} 0 31 [junit4:junit4] 2> 11579 T1276 oaz.ZooKeeper. Initiating client con= nection, connectString=3D127.0.0.1:21057/solr sessionTimeout=3D10000 watche= r=3Dorg.apache.solr.common.cloud.ConnectionManager@4cb83248 [junit4:junit4] 2> 11580 T1354 oaz.ClientCnxn$SendThread.startConnect Ope= ning socket connection to server /127.0.0.1:21057 [junit4:junit4] 2> 11580 T1276 oascc.ConnectionManager.waitForConnected W= aiting for client to connect to ZooKeeper [junit4:junit4] 2> 11581 T1354 oaz.ClientCnxn$SendThread.primeConnection = Socket connection established to localhost/127.0.0.1:21057, initiating sess= ion [junit4:junit4] 2> 11582 T1278 oazs.NIOServerCnxn$Factory.run Accepted so= cket connection from /140.211.11.196:18163 [junit4:junit4] 2> 11582 T1278 oazs.NIOServerCnxn.readConnectRequest Clie= nt attempting to establish new session at /140.211.11.196:18163 [junit4:junit4] 2> 11589 T1280 oazs.NIOServerCnxn.finishSessionInit Estab= lished session 0x139ec5379880008 with negotiated timeout 10000 for client /= 140.211.11.196:18163 [junit4:junit4] 2> 11589 T1354 oaz.ClientCnxn$SendThread.readConnectResul= t Session establishment complete on server localhost/127.0.0.1:21057, sessi= onid =3D 0x139ec5379880008, negotiated timeout =3D 10000 [junit4:junit4] 2> 11589 T1355 oascc.ConnectionManager.process Watcher or= g.apache.solr.common.cloud.ConnectionManager@4cb83248 name:ZooKeeperConnect= ion Watcher:127.0.0.1:21057/solr got event WatchedEvent state:SyncConnected= type:None path:null path:null type:None [junit4:junit4] 2> 11589 T1276 oascc.ConnectionManager.waitForConnected C= lient is connected to ZooKeeper [junit4:junit4] 2> 11590 T1276 oascc.ZkStateReader.createClusterStateWatc= hersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 11601 T1304 C119 P23059 oasc.SolrDeletionPolicy.onInit= SolrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -NightlyTests-trunk/solr/build/solr-core/test/J1/org.apache.solr.cloud.Basi= cDistributedZk2Test-1348289657217/jetty1/index lockFactory=3Dorg.apache.luc= ene.store.NativeFSLockFactory@2f7e1991; maxCacheMB=3D48.0 maxMergeSizeMB=3D= 4.0),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 11602 T1304 C119 P23059 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 1 [junit4:junit4] 2> C118_STATE=3Dcoll:collection1 core:collection1 props:= {shard=3Dshard1, roles=3Dnull, state=3Drecovering, core=3Dcollection1, coll= ection=3Dcollection1, node_name=3D127.0.0.1:22060_solr, base_url=3Dhttp://1= 27.0.0.1:22060/solr} [junit4:junit4] 2> 11636 T1326 C118 P22060 oasc.SolrDeletionPolicy.onInit= SolrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -NightlyTests-trunk/solr/build/solr-core/test/J1/org.apache.solr.cloud.Basi= cDistributedZk2Test-1348289657217/jetty3/index lockFactory=3Dorg.apache.luc= ene.store.NativeFSLockFactory@6e61b3ff; maxCacheMB=3D48.0 maxMergeSizeMB=3D= 4.0),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 11637 T1326 C118 P22060 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 1 [junit4:junit4] 2> 11639 T1326 C118 P22060 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D7=20 [junit4:junit4] 2> 11640 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[1 (1413784187764211712)]} 0 47 [junit4:junit4] 2> 11641 T1276 oasc.AbstractDistribZkTestBase.waitForReco= veriesToFinish Wait for recoveries to finish - collection: collection1 fail= OnTimeout:true timeout (sec):3600 [junit4:junit4] 2> 11642 T1276 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 11889 T1297 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 11913 T1353 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 11913 T1348 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 11914 T1310 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 11914 T1322 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 11913 T1334 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 11913 T1355 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 11913 T1296 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 12601 T1336 C118 P22060 oasc.RecoveryStrategy.doRecove= ry Begin buffering updates. core=3Dcollection1 [junit4:junit4] 2> 12601 T1336 C118 P22060 oasu.UpdateLog.bufferUpdates S= tarting to buffer updates. FSUpdateLog{state=3DACTIVE, tlog=3Dtlog{file=3D.= /org.apache.solr.cloud.BasicDistributedZk2Test-1348289657217/jetty3/tlog/tl= og.0000000000000000000 refcount=3D1}} [junit4:junit4] 2> 12602 T1336 C118 P22060 oasc.RecoveryStrategy.replicat= e Attempting to replicate from http://127.0.0.1:23059/solr/collection1/. co= re=3Dcollection1 [junit4:junit4] 2> 12602 T1336 C118 P22060 oascsi.HttpClientUtil.createCl= ient Creating new http client, config:maxConnections=3D128&maxConnectionsPe= rHost=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 12617 T1305 C119 P23059 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 12644 T1276 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 12718 T1305 C119 P23059 oasc.SolrDeletionPolicy.onComm= it SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -NightlyTests-trunk/solr/build/solr-core/test/J1/org.apache.solr.cloud.Basi= cDistributedZk2Test-1348289657217/jetty1/index lockFactory=3Dorg.apache.luc= ene.store.NativeFSLockFactory@2f7e1991; maxCacheMB=3D48.0 maxMergeSizeMB=3D= 4.0),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -NightlyTests-trunk/solr/build/solr-core/test/J1/org.apache.solr.cloud.Basi= cDistributedZk2Test-1348289657217/jetty1/index lockFactory=3Dorg.apache.luc= ene.store.NativeFSLockFactory@2f7e1991; maxCacheMB=3D48.0 maxMergeSizeMB=3D= 4.0),segFN=3Dsegments_2,generation=3D2,filenames=3D[_0_Asserting_0.tim, _0_= Asserting_0.tip, _0_Lucene40_0.prx, _0_Asserting_0.prx, _0_Lucene40_0.frq, = _0.si, _0_MockRandom_0.tib, _0_Lucene40_0.tim, _0.fnm, _0_nrm.cfs, _0_Direc= t_0.frq, _0_MockRandom_0.skp, _0_Lucene40_0.tip, _0_MockRandom_0.frq, _0_Mo= ckRandom_0.sd, _0_Asserting_0.frq, _0_MockRandom_0.doc, _0_MockRandom_0.pos= , _0_nrm.cfe, segments_2, _0_MockRandom_0.tii, _0_Direct_0.tip, _0.fdx, _0_= Direct_0.prx, _0_MockRandom_0.pyl, _0_Direct_0.tim, _0.fdt] [junit4:junit4] 2> 12718 T1305 C119 P23059 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 2 [junit4:junit4] 2> 12798 T1305 C119 P23059 oass.SolrIndexSearcher. = Opening Searcher@75589a02 main [junit4:junit4] 2> 12799 T1305 C119 P23059 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 12801 T1311 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@75589a02 main{StandardDirectoryReader(s= egments_2:3 _0(5.0):C1)} [junit4:junit4] 2> 12801 T1305 C119 P23059 /update {waitSearcher=3Dtrue&c= ommit=3Dtrue&commit_end_point=3Dtrue&wt=3Djavabin&softCommit=3Dfalse&versio= n=3D2} {commit=3D} 0 184 [junit4:junit4] 2> 12802 T1336 C118 P22060 oash.ReplicationHandler.inform= Commits will be reserved for 10000 [junit4:junit4] 2> 12802 T1336 C118 P22060 oash.SnapPuller. No val= ue set for 'pollInterval'. Timer Task not started. [junit4:junit4] 2> 12804 T1306 C119 P23059 oash.ReplicationHandler.inform= Commits will be reserved for 10000 [junit4:junit4] 2> 12804 T1306 C119 P23059 REQ /replication {command=3Din= dexversion&wt=3Djavabin&qt=3D/replication&version=3D2} status=3D0 QTime=3D1= =20 [junit4:junit4] 2> 12805 T1336 C118 P22060 oash.SnapPuller.fetchLatestInd= ex Master's generation: 2 [junit4:junit4] 2> 12805 T1336 C118 P22060 oash.SnapPuller.fetchLatestInd= ex Slave's generation: 1 [junit4:junit4] 2> 12805 T1336 C118 P22060 oash.SnapPuller.fetchLatestInd= ex Starting replication process [junit4:junit4] 2> 12810 T1306 C119 P23059 REQ /replication {command=3Dfi= lelist&generation=3D2&wt=3Djavabin&qt=3D/replication&version=3D2} status=3D= 0 QTime=3D3=20 [junit4:junit4] 2> 12810 T1336 C118 P22060 oash.SnapPuller.fetchLatestInd= ex Number of files in latest index in master: 27 [junit4:junit4] 2> 12819 T1336 C118 P22060 oash.SnapPuller.fetchLatestInd= ex Starting download to ./org.apache.solr.cloud.BasicDistributedZk2Test-134= 8289657217/jetty3/index.20120922015430024 fullCopy=3Dtrue [junit4:junit4] 2> 12822 T1306 C119 P23059 REQ /replication {file=3D_0_As= serting_0.tim&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/re= plication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 12827 T1306 C119 P23059 REQ /replication {file=3D_0_As= serting_0.tip&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/re= plication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 12831 T1306 C119 P23059 REQ /replication {file=3D_0_Lu= cene40_0.prx&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/rep= lication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 12835 T1306 C119 P23059 REQ /replication {file=3D_0_As= serting_0.prx&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/re= plication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 12839 T1306 C119 P23059 REQ /replication {file=3D_0_Lu= cene40_0.frq&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/rep= lication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 12843 T1306 C119 P23059 REQ /replication {file=3D_0.si= &command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/replication&wt= =3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 12847 T1306 C119 P23059 REQ /replication {file=3D_0_Mo= ckRandom_0.tib&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/r= eplication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 12851 T1306 C119 P23059 REQ /replication {file=3D_0_Lu= cene40_0.tim&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/rep= lication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 12855 T1306 C119 P23059 REQ /replication {file=3D_0.fn= m&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/replication&wt= =3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 12859 T1306 C119 P23059 REQ /replication {file=3D_0_nr= m.cfs&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/replicatio= n&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 12863 T1306 C119 P23059 REQ /replication {file=3D_0_Di= rect_0.frq&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/repli= cation&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 12867 T1306 C119 P23059 REQ /replication {file=3D_0_Mo= ckRandom_0.skp&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/r= eplication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 12868 T1336 C118 P22060 oash.SnapPuller$FileFetcher.fe= tchPackets WARNING No content recieved for file: {name=3D_0_MockRandom_0.sk= p, lastmodified=3D1348289669000, size=3D0} [junit4:junit4] 2> 12872 T1306 C119 P23059 REQ /replication {file=3D_0_Lu= cene40_0.tip&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/rep= lication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 12876 T1306 C119 P23059 REQ /replication {file=3D_0_As= serting_0.frq&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/re= plication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 12880 T1306 C119 P23059 REQ /replication {file=3D_0_Mo= ckRandom_0.sd&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/re= plication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 12884 T1306 C119 P23059 REQ /replication {file=3D_0_Mo= ckRandom_0.frq&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/r= eplication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 12888 T1306 C119 P23059 REQ /replication {file=3D_0_Mo= ckRandom_0.doc&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/r= eplication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 12892 T1306 C119 P23059 REQ /replication {file=3D_0_nr= m.cfe&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/replicatio= n&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 12897 T1306 C119 P23059 REQ /replication {file=3D_0_Mo= ckRandom_0.pos&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/r= eplication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 12901 T1306 C119 P23059 REQ /replication {file=3D_0_Mo= ckRandom_0.tii&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/r= eplication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 12905 T1306 C119 P23059 REQ /replication {file=3Dsegme= nts_2&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/replicatio= n&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 12909 T1306 C119 P23059 REQ /replication {file=3D_0.fd= x&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/replication&wt= =3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 12913 T1306 C119 P23059 REQ /replication {file=3D_0_Di= rect_0.tip&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/repli= cation&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 12918 T1306 C119 P23059 REQ /replication {file=3D_0_Di= rect_0.prx&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/repli= cation&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 12922 T1306 C119 P23059 REQ /replication {file=3D_0_Mo= ckRandom_0.pyl&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/r= eplication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 12923 T1336 C118 P22060 oash.SnapPuller$FileFetcher.fe= tchPackets WARNING No content recieved for file: {name=3D_0_MockRandom_0.py= l, lastmodified=3D1348289669000, size=3D0} [junit4:junit4] 2> 12926 T1306 C119 P23059 REQ /replication {file=3D_0_Di= rect_0.tim&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/repli= cation&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 12930 T1306 C119 P23059 REQ /replication {file=3D_0.fd= t&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/replication&wt= =3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 12931 T1336 C118 P22060 oash.SnapPuller.fetchLatestInd= ex Total time taken for download : 0 secs [junit4:junit4] 2> 12934 T1336 C118 P22060 oash.SnapPuller.modifyIndexPro= ps New index installed. Updating index properties... index=3Dindex.20120922= 015430024 [junit4:junit4] 2> 12935 T1336 C118 P22060 oasu.DefaultSolrCoreState.newI= ndexWriter Creating new IndexWriter... [junit4:junit4] 2> 12936 T1336 C118 P22060 oasu.DefaultSolrCoreState.newI= ndexWriter Waiting until IndexWriter is unused... core=3Dcollection1 [junit4:junit4] 2> 12936 T1336 C118 P22060 oasu.DefaultSolrCoreState.newI= ndexWriter Rollback old IndexWriter... core=3Dcollection1 [junit4:junit4] 2> 12940 T1336 C118 P22060 oasc.SolrCore.getNewIndexDir W= ARNING New index directory detected: old=3D./org.apache.solr.cloud.BasicDis= tributedZk2Test-1348289657217/jetty3/index/ new=3D./org.apache.solr.cloud.B= asicDistributedZk2Test-1348289657217/jetty3/index.20120922015430024 [junit4:junit4] 2> 12941 T1336 C118 P22060 oasc.CachingDirectoryFactory.g= et return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-= Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/./org.apache.solr.clou= d.BasicDistributedZk2Test-1348289657217/jetty3/index.20120922015430024 forc= eNew:true [junit4:junit4] 2> 12953 T1336 C118 P22060 oasc.SolrDeletionPolicy.onInit= SolrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -NightlyTests-trunk/solr/build/solr-core/test/J1/org.apache.solr.cloud.Basi= cDistributedZk2Test-1348289657217/jetty3/index.20120922015430024 lockFactor= y=3Dorg.apache.lucene.store.NativeFSLockFactory@4fd20361; maxCacheMB=3D48.0= maxMergeSizeMB=3D4.0),segFN=3Dsegments_2,generation=3D2,filenames=3D[_0_As= serting_0.tim, _0_Asserting_0.tip, _0_Lucene40_0.prx, _0_Asserting_0.prx, _= 0_Lucene40_0.frq, _0.si, _0_MockRandom_0.tib, _0_Lucene40_0.tim, _0.fnm, _0= _nrm.cfs, _0_Direct_0.frq, _0_MockRandom_0.skp, _0_Lucene40_0.tip, _0_MockR= andom_0.frq, _0_MockRandom_0.sd, _0_Asserting_0.frq, _0_MockRandom_0.doc, _= 0_MockRandom_0.pos, _0_nrm.cfe, segments_2, _0_MockRandom_0.tii, _0_Direct_= 0.tip, _0.fdx, _0_Direct_0.prx, _0_MockRandom_0.pyl, _0_Direct_0.tim, _0.fd= t] [junit4:junit4] 2> 12953 T1336 C118 P22060 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 2 [junit4:junit4] 2> 12954 T1336 C118 P22060 oasu.DefaultSolrCoreState.newI= ndexWriter New IndexWriter is ready to be used. [junit4:junit4] 2> 12960 T1336 C118 P22060 oass.SolrIndexSearcher. = Opening Searcher@31b8f748 main [junit4:junit4] 2> 12962 T1335 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@31b8f748 main{StandardDirectoryReader(s= egments_2:3:nrt _0(5.0):C1)} [junit4:junit4] 2> 12963 T1335 oasc.CachingDirectoryFactory.close Closing= directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests= -trunk/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistribute= dZk2Test-1348289657217/jetty3/index [junit4:junit4] 2> 12963 T1335 oash.SnapPuller$2.onClose removing old ind= ex directory ./org.apache.solr.cloud.BasicDistributedZk2Test-1348289657217/= jetty3/index [junit4:junit4] 2> 12965 T1336 C118 P22060 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dfal= se,waitSearcher=3Dfalse,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 12966 T1336 C118 P22060 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 12972 T1336 C118 P22060 oasc.RecoveryStrategy.replay R= eplaying buffered documents. core=3Dcollection1 [junit4:junit4] 2> 12972 T1358 C118 P22060 oasu.UpdateLog$LogReplayer.doR= eplay WARNING Starting log replay tlog{file=3D./org.apache.solr.cloud.Basic= DistributedZk2Test-1348289657217/jetty3/tlog/tlog.0000000000000000000 refco= unt=3D2} active=3Dtrue starting pos=3D434 [junit4:junit4] 2> 12977 T1358 C118 P22060 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D2,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 12979 T1358 C118 P22060 oass.SolrIndexSearcher. = Opening Searcher@73e7287a main [junit4:junit4] 2> 12981 T1335 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@73e7287a main{StandardDirectoryReader(s= egments_2:3:nrt _0(5.0):C1)} [junit4:junit4] 2> 12981 T1358 C118 P22060 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 12982 T1358 C118 P22060 oasu.UpdateLog$LogReplayer.run= WARNING Log replay finished. recoveryInfo=3DRecoveryInfo{adds=3D0 deletes= =3D0 deleteByQuery=3D0 errors=3D0 positionOfStart=3D434} [junit4:junit4] 2> 12982 T1336 C118 P22060 oasc.RecoveryStrategy.doRecove= ry Replication Recovery was successful - registering as Active. core=3Dcoll= ection1 [junit4:junit4] 2> 12984 T1336 C118 P22060 oasc.RecoveryStrategy.doRecove= ry Finished recovery process. core=3Dcollection1 [junit4:junit4] 2> 13430 T1297 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 13448 T1355 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 13448 T1353 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 13448 T1310 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 13448 T1334 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 13448 T1322 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 13448 T1348 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 13448 T1296 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 13647 T1276 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> C120_STATE=3Dcoll:collection1 core:collection1 props:= {shard=3Dshard2, roles=3Dnull, state=3Drecovering, core=3Dcollection1, coll= ection=3Dcollection1, node_name=3D127.0.0.1:56003_solr, base_url=3Dhttp://1= 27.0.0.1:56003/solr} [junit4:junit4] 2> 14509 T1350 C120 P56003 oasc.RecoveryStrategy.doRecove= ry Begin buffering updates. core=3Dcollection1 [junit4:junit4] 2> 14509 T1350 C120 P56003 oasu.UpdateLog.bufferUpdates S= tarting to buffer updates. FSUpdateLog{state=3DACTIVE, tlog=3Dnull} [junit4:junit4] 2> 14509 T1350 C120 P56003 oasc.RecoveryStrategy.replicat= e Attempting to replicate from http://127.0.0.1:18297/solr/collection1/. co= re=3Dcollection1 [junit4:junit4] 2> 14509 T1350 C120 P56003 oascsi.HttpClientUtil.createCl= ient Creating new http client, config:maxConnections=3D128&maxConnectionsPe= rHost=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 14525 T1316 C121 P18297 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 14531 T1316 C121 P18297 oasc.SolrDeletionPolicy.onInit= SolrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -NightlyTests-trunk/solr/build/solr-core/test/J1/org.apache.solr.cloud.Basi= cDistributedZk2Test-1348289657217/jetty2/index lockFactory=3Dorg.apache.luc= ene.store.NativeFSLockFactory@48931b44; maxCacheMB=3D48.0 maxMergeSizeMB=3D= 4.0),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 14532 T1316 C121 P18297 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 1 [junit4:junit4] 2> 14533 T1316 C121 P18297 oass.SolrIndexSearcher. = Opening Searcher@7c7780c2 main [junit4:junit4] 2> 14534 T1316 C121 P18297 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 14536 T1323 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@7c7780c2 main{StandardDirectoryReader(s= egments_1:1)} [junit4:junit4] 2> 14536 T1316 C121 P18297 /update {waitSearcher=3Dtrue&c= ommit=3Dtrue&commit_end_point=3Dtrue&wt=3Djavabin&softCommit=3Dfalse&versio= n=3D2} {commit=3D} 0 11 [junit4:junit4] 2> 14537 T1350 C120 P56003 oash.ReplicationHandler.inform= Commits will be reserved for 10000 [junit4:junit4] 2> 14537 T1350 C120 P56003 oash.SnapPuller. No val= ue set for 'pollInterval'. Timer Task not started. [junit4:junit4] 2> 14538 T1317 C121 P18297 oash.ReplicationHandler.inform= Commits will be reserved for 10000 [junit4:junit4] 2> 14539 T1317 C121 P18297 REQ /replication {command=3Din= dexversion&wt=3Djavabin&qt=3D/replication&version=3D2} status=3D0 QTime=3D1= =20 [junit4:junit4] 2> 14544 T1350 C120 P56003 oasc.SolrDeletionPolicy.onInit= SolrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -NightlyTests-trunk/solr/build/solr-core/test/J1/org.apache.solr.cloud.Basi= cDistributedZk2Test-1348289657217/jetty4/index lockFactory=3Dorg.apache.luc= ene.store.NativeFSLockFactory@7e2fa610; maxCacheMB=3D48.0 maxMergeSizeMB=3D= 4.0),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 14544 T1350 C120 P56003 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 1 [junit4:junit4] 2> 14545 T1350 C120 P56003 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 14585 T1350 C120 P56003 oasc.SolrDeletionPolicy.onComm= it SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -NightlyTests-trunk/solr/build/solr-core/test/J1/org.apache.solr.cloud.Basi= cDistributedZk2Test-1348289657217/jetty4/index lockFactory=3Dorg.apache.luc= ene.store.NativeFSLockFactory@7e2fa610; maxCacheMB=3D48.0 maxMergeSizeMB=3D= 4.0),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -NightlyTests-trunk/solr/build/solr-core/test/J1/org.apache.solr.cloud.Basi= cDistributedZk2Test-1348289657217/jetty4/index lockFactory=3Dorg.apache.luc= ene.store.NativeFSLockFactory@7e2fa610; maxCacheMB=3D48.0 maxMergeSizeMB=3D= 4.0),segFN=3Dsegments_2,generation=3D2,filenames=3D[segments_2] [junit4:junit4] 2> 14585 T1350 C120 P56003 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 2 [junit4:junit4] 2> 14589 T1350 C120 P56003 oass.SolrIndexSearcher. = Opening Searcher@58ce9a12 main [junit4:junit4] 2> 14589 T1350 C120 P56003 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 14591 T1349 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@58ce9a12 main{StandardDirectoryReader(s= egments_2:2)} [junit4:junit4] 2> 14592 T1350 C120 P56003 oasc.RecoveryStrategy.replay N= o replay needed. core=3Dcollection1 [junit4:junit4] 2> 14592 T1350 C120 P56003 oasc.RecoveryStrategy.doRecove= ry Replication Recovery was successful - registering as Active. core=3Dcoll= ection1 [junit4:junit4] 2> 14606 T1350 C120 P56003 oasc.RecoveryStrategy.doRecove= ry Finished recovery process. core=3Dcollection1 [junit4:junit4] 2> 14649 T1276 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 14959 T1297 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 14976 T1353 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 14976 T1355 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 14976 T1322 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 14976 T1310 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 14976 T1334 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 14976 T1296 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 14976 T1348 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 15651 T1276 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 15655 T1288 C122 P47845 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 15749 T1288 C122 P47845 oasc.SolrDeletionPolicy.onComm= it SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -NightlyTests-trunk/solr/build/solr-core/test/J1/org.apache.solr.cloud.Basi= cDistributedZk2Test-1348289657217/control/data/index lockFactory=3Dorg.apac= he.lucene.store.NativeFSLockFactory@1bf55430; maxCacheMB=3D48.0 maxMergeSiz= eMB=3D4.0),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -NightlyTests-trunk/solr/build/solr-core/test/J1/org.apache.solr.cloud.Basi= cDistributedZk2Test-1348289657217/control/data/index lockFactory=3Dorg.apac= he.lucene.store.NativeFSLockFactory@1bf55430; maxCacheMB=3D48.0 maxMergeSiz= eMB=3D4.0),segFN=3Dsegments_2,generation=3D2,filenames=3D[_0_Asserting_0.ti= m, _0_Asserting_0.tip, _0_Lucene40_0.prx, _0_Asserting_0.prx, _0_Lucene40_0= .frq, _0.si, _0_MockRandom_0.tib, _0_Lucene40_0.tim, _0.fnm, _0_nrm.cfs, _0= _Direct_0.frq, _0_MockRandom_0.skp, _0_Lucene40_0.tip, _0_MockRandom_0.frq,= _0_MockRandom_0.sd, _0_Asserting_0.frq, _0_MockRandom_0.doc, _0_MockRandom= _0.pos, _0_nrm.cfe, segments_2, _0_MockRandom_0.tii, _0_Direct_0.tip, _0.fd= x, _0_Direct_0.prx, _0_MockRandom_0.pyl, _0_Direct_0.tim, _0.fdt] [junit4:junit4] 2> 15750 T1288 C122 P47845 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 2 [junit4:junit4] 2> 15770 T1288 C122 P47845 oass.SolrIndexSearcher. = Opening Searcher@6f7579cf main [junit4:junit4] 2> 15770 T1288 C122 P47845 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 15772 T1299 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@6f7579cf main{StandardDirectoryReader(s= egments_2:3 _0(5.0):C1)} [junit4:junit4] 2> 15772 T1288 C122 P47845 /update {waitSearcher=3Dtrue&w= t=3Djavabin&commit=3Dtrue&softCommit=3Dfalse&version=3D2} {commit=3D} 0 117 [junit4:junit4] 2> 15775 T1304 C119 P23059 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 15787 T1304 C119 P23059 oass.SolrIndexSearcher. = Opening Searcher@74f8f156 main [junit4:junit4] 2> 15787 T1304 C119 P23059 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 15789 T1311 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@74f8f156 main{StandardDirectoryReader(s= egments_2:3 _0(5.0):C1)} [junit4:junit4] 2> 15790 T1304 C119 P23059 oasu.SolrCmdDistributor.distri= bCommit Distrib commit to:[StdNode: http://127.0.0.1:22060/solr/collection1= /, StdNode: http://127.0.0.1:18297/solr/collection1/, StdNode: http://127.0= .0.1:56003/solr/collection1/] [junit4:junit4] 2> C118_STATE=3Dcoll:collection1 core:collection1 props:= {shard=3Dshard1, roles=3Dnull, state=3Dactive, core=3Dcollection1, collecti= on=3Dcollection1, node_name=3D127.0.0.1:22060_solr, base_url=3Dhttp://127.0= .0.1:22060/solr} [junit4:junit4] 2> 15792 T1326 C118 P22060 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 15792 T1318 C121 P18297 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 15795 T1326 C118 P22060 oass.SolrIndexSearcher. = Opening Searcher@6b3755b5 main [junit4:junit4] 2> 15796 T1326 C118 P22060 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 15801 T1318 C121 P18297 oass.SolrIndexSearcher. = Opening Searcher@62bc2ca4 main [junit4:junit4] 2> 15802 T1335 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@6b3755b5 main{StandardDirectoryReader(s= egments_2:3:nrt _0(5.0):C1)} [junit4:junit4] 2> 15802 T1318 C121 P18297 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 15802 T1326 C118 P22060 /update {waitSearcher=3Dtrue&c= ommit=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&expungeDeletes=3Dfalse&so= ftCommit=3Dfalse&version=3D2} {commit=3D} 0 10 [junit4:junit4] 2> 15803 T1323 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@62bc2ca4 main{StandardDirectoryReader(s= egments_1:1)} [junit4:junit4] 2> 15804 T1318 C121 P18297 /update {waitSearcher=3Dtrue&c= ommit=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&expungeDeletes=3Dfalse&so= ftCommit=3Dfalse&version=3D2} {commit=3D} 0 12 [junit4:junit4] 2> C120_STATE=3Dcoll:collection1 core:collection1 props:= {shard=3Dshard2, roles=3Dnull, state=3Dactive, core=3Dcollection1, collecti= on=3Dcollection1, node_name=3D127.0.0.1:56003_solr, base_url=3Dhttp://127.0= .0.1:56003/solr} [junit4:junit4] 2> 15805 T1340 C120 P56003 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 15809 T1340 C120 P56003 oass.SolrIndexSearcher. = Opening Searcher@5e012913 main [junit4:junit4] 2> 15809 T1340 C120 P56003 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 15811 T1349 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@5e012913 main{StandardDirectoryReader(s= egments_2:2)} [junit4:junit4] 2> 15811 T1340 C120 P56003 /update {waitSearcher=3Dtrue&c= ommit=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&expungeDeletes=3Dfalse&so= ftCommit=3Dfalse&version=3D2} {commit=3D} 0 6 [junit4:junit4] 2> 15812 T1304 C119 P23059 /update {waitSearcher=3Dtrue&w= t=3Djavabin&commit=3Dtrue&softCommit=3Dfalse&version=3D2} {commit=3D} 0 37 [junit4:junit4] 2> 15817 T1289 C122 P47845 REQ /select {fl=3Did,score&sha= rd.url=3D127.0.0.1:47845/solr/collection1/&NOW=3D1348289673027&start=3D0&q= =3D*:*&distrib=3Dfalse&isShard=3Dtrue&wt=3Djavabin&fsv=3Dtrue&rows=3D10&ver= sion=3D2} hits=3D1 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 15820 T1289 C122 P47845 REQ /select {shard.url=3D127.0= .0.1:47845/solr/collection1/&NOW=3D1348289673027&q=3D*:*&ids=3D1&distrib=3D= false&isShard=3Dtrue&wt=3Djavabin&version=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 15821 T1288 C122 P47845 REQ /select {wt=3Djavabin&q=3D= *:*&version=3D2} hits=3D1 status=3D0 QTime=3D7=20 [junit4:junit4] 2> 15821 T1276 oaz.ZooKeeper. Initiating client con= nection, connectString=3D127.0.0.1:21057/solr sessionTimeout=3D10000 watche= r=3Dorg.apache.solr.common.cloud.ConnectionManager@4fc2b545 [junit4:junit4] 2> 15822 T1362 oaz.ClientCnxn$SendThread.startConnect Ope= ning socket connection to server /127.0.0.1:21057 [junit4:junit4] 2> 15822 T1276 oascc.ConnectionManager.waitForConnected W= aiting for client to connect to ZooKeeper [junit4:junit4] 2> 15824 T1362 oaz.ClientCnxn$SendThread.primeConnection = Socket connection established to localhost/127.0.0.1:21057, initiating sess= ion [junit4:junit4] 2> 15824 T1278 oazs.NIOServerCnxn$Factory.run Accepted so= cket connection from /140.211.11.196:12593 [junit4:junit4] 2> 15824 T1278 oazs.NIOServerCnxn.readConnectRequest Clie= nt attempting to establish new session at /140.211.11.196:12593 [junit4:junit4] 2> 15826 T1280 oazs.NIOServerCnxn.finishSessionInit Estab= lished session 0x139ec5379880009 with negotiated timeout 10000 for client /= 140.211.11.196:12593 [junit4:junit4] 2> 15826 T1362 oaz.ClientCnxn$SendThread.readConnectResul= t Session establishment complete on server localhost/127.0.0.1:21057, sessi= onid =3D 0x139ec5379880009, negotiated timeout =3D 10000 [junit4:junit4] 2> 15826 T1363 oascc.ConnectionManager.process Watcher or= g.apache.solr.common.cloud.ConnectionManager@4fc2b545 name:ZooKeeperConnect= ion Watcher:127.0.0.1:21057/solr got event WatchedEvent state:SyncConnected= type:None path:null path:null type:None [junit4:junit4] 2> 15826 T1276 oascc.ConnectionManager.waitForConnected C= lient is connected to ZooKeeper [junit4:junit4] 2> 15827 T1276 oascc.ZkStateReader.createClusterStateWatc= hersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 15829 T1281 oazs.PrepRequestProcessor.pRequest Process= ed session termination for sessionid: 0x139ec5379880009 [junit4:junit4] 2> 15830 T1363 oaz.ClientCnxn$EventThread.run EventThread= shut down [junit4:junit4] 2> 15830 T1276 oaz.ZooKeeper.close Session: 0x139ec537988= 0009 closed [junit4:junit4] 2> 15831 T1278 oazs.NIOServerCnxn.closeSock Closed socket= connection for client /140.211.11.196:12593 which had sessionid 0x139ec537= 9880009 [junit4:junit4] 2> 15832 T1307 C119 P23059 REQ /select {distrib=3Dfalse&w= t=3Djavabin&q=3D*:*&version=3D2} hits=3D1 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 15834 T1319 C121 P18297 REQ /select {distrib=3Dfalse&w= t=3Djavabin&q=3D*:*&version=3D2} hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 15838 T1320 C121 P18297 REQ /select {fl=3Did,score&sha= rd.url=3D127.0.0.1:18297/solr/collection1/|127.0.0.1:56003/solr/collection1= /&NOW=3D1348289673049&start=3D0&q=3D*:*&distrib=3Dfalse&isShard=3Dtrue&wt= =3Djavabin&fsv=3Dtrue&rows=3D10&version=3D2} hits=3D0 status=3D0 QTime=3D0= =20 [junit4:junit4] 2> 15838 T1328 C118 P22060 REQ /select {fl=3Did,score&sha= rd.url=3D127.0.0.1:23059/solr/collection1/|127.0.0.1:22060/solr/collection1= /&NOW=3D1348289673049&start=3D0&q=3D*:*&distrib=3Dfalse&isShard=3Dtrue&wt= =3Djavabin&fsv=3Dtrue&rows=3D10&version=3D2} hits=3D1 status=3D0 QTime=3D0= =20 [junit4:junit4] 2> 15841 T1328 C118 P22060 REQ /select {shard.url=3D127.0= .0.1:23059/solr/collection1/|127.0.0.1:22060/solr/collection1/&NOW=3D134828= 9673049&q=3D*:*&ids=3D1&distrib=3Dfalse&isShard=3Dtrue&wt=3Djavabin&version= =3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 15842 T1327 C118 P22060 REQ /select {wt=3Djavabin&q=3D= *:*&version=3D2} hits=3D1 status=3D0 QTime=3D6=20 [junit4:junit4] 2> 15848 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[2 (1413784192215416832)]} 0 4 [junit4:junit4] 2> 15860 T1340 C120 P56003 REQ /update {distrib.from=3Dht= tp://127.0.0.1:18297/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 15860 T1315 C121 P18297 /update {wt=3Djavabin&version= =3D2} {add=3D[2 (1413784192221708288)]} 0 10 [junit4:junit4] 2> 15864 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[3 (1413784192235339776)]} 0 1 [junit4:junit4] 2> 15871 T1340 C120 P56003 REQ /update {distrib.from=3Dht= tp://127.0.0.1:18297/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 15872 T1315 C121 P18297 /update {wt=3Djavabin&version= =3D2} {add=3D[3 (1413784192239534080)]} 0 5 [junit4:junit4] 2> 15876 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[4 (1413784192247922688)]} 0 1 [junit4:junit4] 2> 15887 T1326 C118 P22060 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D3=20 [junit4:junit4] 2> 15888 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[4 (1413784192252116992)]} 0 9 [junit4:junit4] 2> 15892 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[5 (1413784192264699904)]} 0 1 [junit4:junit4] 2> 15908 T1340 C120 P56003 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:18297/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 15908 T1318 C121 P18297 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D6=20 [junit4:junit4] 2> 15909 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[5]} 0 14 [junit4:junit4] 2> 15912 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[6 (1413784192285671424)]} 0 1 [junit4:junit4] 2> 15919 T1340 C120 P56003 REQ /update {distrib.from=3Dht= tp://127.0.0.1:18297/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 15920 T1315 C121 P18297 /update {wt=3Djavabin&version= =3D2} {add=3D[6 (1413784192289865728)]} 0 5 [junit4:junit4] 2> 15923 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[7 (1413784192297205760)]} 0 1 [junit4:junit4] 2> 15932 T1340 C120 P56003 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:18297/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 15933 T1318 C121 P18297 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 15933 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[7]} 0 7 [junit4:junit4] 2> 15937 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[8 (1413784192311885824)]} 0 1 [junit4:junit4] 2> 15944 T1326 C118 P22060 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 15945 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[8 (1413784192316080128)]} 0 5 [junit4:junit4] 2> 15949 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[9 (1413784192324468736)]} 0 1 [junit4:junit4] 2> 15958 T1340 C120 P56003 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:18297/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 15959 T1318 C121 P18297 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 15959 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[9]} 0 7 [junit4:junit4] 2> 15963 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[10 (1413784192339148800)]} 0 1 [junit4:junit4] 2> 15973 T1326 C118 P22060 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:23059/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 15974 T1308 C119 P23059 REQ /update {distrib.from=3Dht= tp://127.0.0.1:18297/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D6=20 [junit4:junit4] 2> 15975 T1315 C121 P18297 /update {wt=3Djavabin&version= =3D2} {add=3D[10]} 0 9 [junit4:junit4] 2> 15999 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[11 (1413784192354877440)]} 0 22 [junit4:junit4] 2> 16010 T1326 C118 P22060 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:23059/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 16010 T1308 C119 P23059 REQ /update {distrib.from=3Dht= tp://127.0.0.1:18297/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D6=20 [junit4:junit4] 2> 16011 T1315 C121 P18297 /update {wt=3Djavabin&version= =3D2} {add=3D[11]} 0 9 [junit4:junit4] 2> 16017 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[12 (1413784192393674752)]} 0 3 [junit4:junit4] 2> 16023 T1326 C118 P22060 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16024 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[12 (1413784192398917632)]} 0 5 [junit4:junit4] 2> 16028 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[13 (1413784192407306240)]} 0 1 [junit4:junit4] 2> 16036 T1326 C118 P22060 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 16037 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[13 (1413784192411500544)]} 0 6 [junit4:junit4] 2> 16040 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[14 (1413784192419889152)]} 0 1 [junit4:junit4] 2> 16050 T1326 C118 P22060 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:23059/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16051 T1308 C119 P23059 REQ /update {distrib.from=3Dht= tp://127.0.0.1:18297/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D6=20 [junit4:junit4] 2> 16051 T1315 C121 P18297 /update {wt=3Djavabin&version= =3D2} {add=3D[14]} 0 8 [junit4:junit4] 2> 16055 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[15 (1413784192435617792)]} 0 1 [junit4:junit4] 2> 16063 T1326 C118 P22060 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16064 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[15 (1413784192439812096)]} 0 6 [junit4:junit4] 2> 16068 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[16 (1413784192449249280)]} 0 1 [junit4:junit4] 2> 16076 T1326 C118 P22060 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16077 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[16 (1413784192454492160)]} 0 6 [junit4:junit4] 2> 16083 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[17 (1413784192462880768)]} 0 3 [junit4:junit4] 2> 16095 T1340 C120 P56003 REQ /update {distrib.from=3Dht= tp://127.0.0.1:18297/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D3=20 [junit4:junit4] 2> 16096 T1315 C121 P18297 /update {wt=3Djavabin&version= =3D2} {add=3D[17 (1413784192469172224)]} 0 10 [junit4:junit4] 2> 16100 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[100 (1413784192482803712)]} 0 1 [junit4:junit4] 2> 16139 T1326 C118 P22060 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D18=20 [junit4:junit4] 2> 16140 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[100 (1413784192486998016)]} 0 37 [junit4:junit4] 2> 16145 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[101 (1413784192528941056)]} 0 2 [junit4:junit4] 2> 16158 T1326 C118 P22060 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:23059/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 16159 T1308 C119 P23059 REQ /update {distrib.from=3Dht= tp://127.0.0.1:18297/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D10=20 [junit4:junit4] 2> 16160 T1315 C121 P18297 /update {wt=3Djavabin&version= =3D2} {add=3D[101]} 0 13 [junit4:junit4] 2> 16165 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[102 (1413784192549912576)]} 0 2 [junit4:junit4] 2> 16176 T1326 C118 P22060 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:23059/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 16177 T1308 C119 P23059 REQ /update {distrib.from=3Dht= tp://127.0.0.1:18297/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D7=20 [junit4:junit4] 2> 16177 T1315 C121 P18297 /update {wt=3Djavabin&version= =3D2} {add=3D[102]} 0 9 [junit4:junit4] 2> 16208 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[103 (1413784192567738368)]} 0 28 [junit4:junit4] 2> 16218 T1340 C120 P56003 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:18297/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16219 T1318 C121 P18297 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 16219 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[103]} 0 8 [junit4:junit4] 2> 16226 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[104 (1413784192611778560)]} 0 4 [junit4:junit4] 2> 16236 T1340 C120 P56003 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:18297/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16237 T1318 C121 P18297 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 16238 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[104]} 0 9 [junit4:junit4] 2> 16243 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[105 (1413784192632750080)]} 0 2 [junit4:junit4] 2> 16302 T1340 C120 P56003 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:18297/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D30=20 [junit4:junit4] 2> 16303 T1318 C121 P18297 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D54=20 [junit4:junit4] 2> 16303 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[105]} 0 57 [junit4:junit4] 2> 16308 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[106 (1413784192700907520)]} 0 2 [junit4:junit4] 2> 16322 T1340 C120 P56003 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:18297/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D3=20 [junit4:junit4] 2> 16323 T1318 C121 P18297 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D10=20 [junit4:junit4] 2> 16323 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[106]} 0 12 [junit4:junit4] 2> 16328 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[107 (1413784192721879040)]} 0 2 [junit4:junit4] 2> 16343 T1326 C118 P22060 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 16343 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[107 (1413784192726073344)]} 0 12 [junit4:junit4] 2> 16347 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[108 (1413784192741801984)]} 0 1 [junit4:junit4] 2> 16356 T1326 C118 P22060 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 16357 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[108 (1413784192747044864)]} 0 7 [junit4:junit4] 2> 16361 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[109 (1413784192755433472)]} 0 2 [junit4:junit4] 2> 16369 T1326 C118 P22060 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 16370 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[109 (1413784192760676352)]} 0 6 [junit4:junit4] 2> 16376 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[110 (1413784192771162112)]} 0 3 [junit4:junit4] 2> 16386 T1326 C118 P22060 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:23059/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16387 T1308 C119 P23059 REQ /update {distrib.from=3Dht= tp://127.0.0.1:18297/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 16388 T1315 C121 P18297 /update {wt=3Djavabin&version= =3D2} {add=3D[110]} 0 9 [junit4:junit4] 2> 16391 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[111 (1413784192787939328)]} 0 1 [junit4:junit4] 2> 16407 T1326 C118 P22060 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D3=20 [junit4:junit4] 2> 16408 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[111 (1413784192792133632)]} 0 14 [junit4:junit4] 2> 16412 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[112 (1413784192809959424)]} 0 1 [junit4:junit4] 2> 16423 T1340 C120 P56003 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:18297/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16424 T1318 C121 P18297 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D6=20 [junit4:junit4] 2> 16425 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[112]} 0 10 [junit4:junit4] 2> 16443 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[113 (1413784192827785216)]} 0 15 [junit4:junit4] 2> 16453 T1326 C118 P22060 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 16454 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[113 (1413784192847708160)]} 0 7 [junit4:junit4] 2> 16460 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[114 (1413784192858193920)]} 0 3 [junit4:junit4] 2> 16469 T1326 C118 P22060 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 16469 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[114 (1413784192864485376)]} 0 6 [junit4:junit4] 2> 16473 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[115 (1413784192873922560)]} 0 1 [junit4:junit4] 2> 16483 T1340 C120 P56003 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:18297/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16484 T1318 C121 P18297 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D6=20 [junit4:junit4] 2> 16485 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[115]} 0 9 [junit4:junit4] 2> 16488 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[116 (1413784192889651200)]} 0 1 [junit4:junit4] 2> 16497 T1340 C120 P56003 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:18297/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16498 T1318 C121 P18297 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 16498 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[116]} 0 7 [junit4:junit4] 2> 16502 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[117 (1413784192904331264)]} 0 1 [junit4:junit4] 2> 16510 T1340 C120 P56003 REQ /update {distrib.from=3Dht= tp://127.0.0.1:18297/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16511 T1315 C121 P18297 /update {wt=3Djavabin&version= =3D2} {add=3D[117 (1413784192908525568)]} 0 6 [junit4:junit4] 2> 16515 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[118 (1413784192917962752)]} 0 1 [junit4:junit4] 2> 16550 T1326 C118 P22060 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:23059/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D14=20 [junit4:junit4] 2> 16551 T1308 C119 P23059 REQ /update {distrib.from=3Dht= tp://127.0.0.1:18297/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D31=20 [junit4:junit4] 2> 16552 T1315 C121 P18297 /update {wt=3Djavabin&version= =3D2} {add=3D[118]} 0 34 [junit4:junit4] 2> 16556 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[119 (1413784192960954368)]} 0 1 [junit4:junit4] 2> 16563 T1340 C120 P56003 REQ /update {distrib.from=3Dht= tp://127.0.0.1:18297/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16564 T1315 C121 P18297 /update {wt=3Djavabin&version= =3D2} {add=3D[119 (1413784192965148672)]} 0 5 [junit4:junit4] 2> 16568 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[120 (1413784192973537280)]} 0 1 [junit4:junit4] 2> 16579 T1340 C120 P56003 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:18297/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16580 T1318 C121 P18297 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D6=20 [junit4:junit4] 2> 16580 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[120]} 0 9 [junit4:junit4] 2> 16584 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[121 (1413784192990314496)]} 0 1 [junit4:junit4] 2> 16593 T1340 C120 P56003 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:18297/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16594 T1318 C121 P18297 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 16595 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[121]} 0 8 [junit4:junit4] 2> 16598 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[122 (1413784193004994560)]} 0 1 [junit4:junit4] 2> 16609 T1326 C118 P22060 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D3=20 [junit4:junit4] 2> 16609 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[122 (1413784193009188864)]} 0 8 [junit4:junit4] 2> 16627 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[123 (1413784193020723200)]} 0 15 [junit4:junit4] 2> 16637 T1326 C118 P22060 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:23059/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16638 T1308 C119 P23059 REQ /update {distrib.from=3Dht= tp://127.0.0.1:18297/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 16639 T1315 C121 P18297 /update {wt=3Djavabin&version= =3D2} {add=3D[123]} 0 9 [junit4:junit4] 2> 16644 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[124 (1413784193051131904)]} 0 3 [junit4:junit4] 2> 16652 T1326 C118 P22060 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:23059/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16653 T1308 C119 P23059 REQ /update {distrib.from=3Dht= tp://127.0.0.1:18297/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 16654 T1315 C121 P18297 /update {wt=3Djavabin&version= =3D2} {add=3D[124]} 0 8 [junit4:junit4] 2> 16657 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[125 (1413784193066860544)]} 0 1 [junit4:junit4] 2> 16664 T1326 C118 P22060 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16665 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[125 (1413784193071054848)]} 0 5 [junit4:junit4] 2> 16669 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[126 (1413784193079443456)]} 0 1 [junit4:junit4] 2> 16676 T1340 C120 P56003 REQ /update {distrib.from=3Dht= tp://127.0.0.1:18297/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16677 T1315 C121 P18297 /update {wt=3Djavabin&version= =3D2} {add=3D[126 (1413784193083637760)]} 0 5 [junit4:junit4] 2> 16680 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[127 (1413784193090977792)]} 0 1 [junit4:junit4] 2> 16715 T1340 C120 P56003 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:18297/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D14=20 [junit4:junit4] 2> 16716 T1318 C121 P18297 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D31=20 [junit4:junit4] 2> 16717 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[127]} 0 34 [junit4:junit4] 2> 16720 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[128 (1413784193132920832)]} 0 1 [junit4:junit4] 2> 16732 T1340 C120 P56003 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:18297/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D3=20 [junit4:junit4] 2> 16732 T1318 C121 P18297 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D7=20 [junit4:junit4] 2> 16733 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[128]} 0 10 [junit4:junit4] 2> 16737 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[129 (1413784193150746624)]} 0 1 [junit4:junit4] 2> 16748 T1326 C118 P22060 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:23059/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16749 T1308 C119 P23059 REQ /update {distrib.from=3Dht= tp://127.0.0.1:18297/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D6=20 [junit4:junit4] 2> 16750 T1315 C121 P18297 /update {wt=3Djavabin&version= =3D2} {add=3D[129]} 0 10 [junit4:junit4] 2> 16754 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[130 (1413784193168572416)]} 0 1 [junit4:junit4] 2> 16763 T1340 C120 P56003 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:18297/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16764 T1318 C121 P18297 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 16765 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[130]} 0 8 [junit4:junit4] 2> 16768 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[131 (1413784193183252480)]} 0 1 [junit4:junit4] 2> 16777 T1340 C120 P56003 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:18297/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16778 T1318 C121 P18297 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 16779 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[131]} 0 8 [junit4:junit4] 2> 16782 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[132 (1413784193197932544)]} 0 1 [junit4:junit4] 2> 16792 T1326 C118 P22060 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:23059/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16793 T1308 C119 P23059 REQ /update {distrib.from=3Dht= tp://127.0.0.1:18297/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 16793 T1315 C121 P18297 /update {wt=3Djavabin&version= =3D2} {add=3D[132]} 0 8 [junit4:junit4] 2> 16811 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[133 (1413784193213661184)]} 0 15 [junit4:junit4] 2> 16820 T1326 C118 P22060 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:23059/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16821 T1308 C119 P23059 REQ /update {distrib.from=3Dht= tp://127.0.0.1:18297/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 16821 T1315 C121 P18297 /update {wt=3Djavabin&version= =3D2} {add=3D[133]} 0 8 [junit4:junit4] 2> 16827 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[134 (1413784193243021312)]} 0 3 [junit4:junit4] 2> 16835 T1340 C120 P56003 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:18297/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16836 T1318 C121 P18297 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 16837 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[134]} 0 8 [junit4:junit4] 2> 16840 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[135 (1413784193258749952)]} 0 1 [junit4:junit4] 2> 16847 T1326 C118 P22060 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16854 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[135 (1413784193262944256)]} 0 11 [junit4:junit4] 2> 16858 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[136 (1413784193277624320)]} 0 1 [junit4:junit4] 2> 16867 T1340 C120 P56003 REQ /update {distrib.from=3Dht= tp://127.0.0.1:18297/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16868 T1315 C121 P18297 /update {wt=3Djavabin&version= =3D2} {add=3D[136 (1413784193282867200)]} 0 7 [junit4:junit4] 2> 16872 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[137 (1413784193292304384)]} 0 1 [junit4:junit4] 2> 16880 T1340 C120 P56003 REQ /update {distrib.from=3Dht= tp://127.0.0.1:18297/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16881 T1315 C121 P18297 /update {wt=3Djavabin&version= =3D2} {add=3D[137 (1413784193296498688)]} 0 6 [junit4:junit4] 2> 16885 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[138 (1413784193305935872)]} 0 1 [junit4:junit4] 2> 16898 T1326 C118 P22060 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:23059/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 16899 T1308 C119 P23059 REQ /update {distrib.from=3Dht= tp://127.0.0.1:18297/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D8=20 [junit4:junit4] 2> 16899 T1315 C121 P18297 /update {wt=3Djavabin&version= =3D2} {add=3D[138]} 0 11 [junit4:junit4] 2> 16903 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[139 (1413784193324810240)]} 0 1 [junit4:junit4] 2> 16911 T1340 C120 P56003 REQ /update {distrib.from=3Dht= tp://127.0.0.1:18297/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16912 T1315 C121 P18297 /update {wt=3Djavabin&version= =3D2} {add=3D[139 (1413784193329004544)]} 0 6 [junit4:junit4] 2> 16917 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[140 (1413784193338441728)]} 0 2 [junit4:junit4] 2> 16928 T1340 C120 P56003 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:18297/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16929 T1318 C121 P18297 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 16930 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[140]} 0 9 [junit4:junit4] 2> 16933 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[141 (1413784193356267520)]} 0 1 [junit4:junit4] 2> 16940 T1340 C120 P56003 REQ /update {distrib.from=3Dht= tp://127.0.0.1:18297/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16940 T1315 C121 P18297 /update {wt=3Djavabin&version= =3D2} {add=3D[141 (1413784193359413248)]} 0 5 [junit4:junit4] 2> 16944 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[142 (1413784193367801856)]} 0 1 [junit4:junit4] 2> 16975 T1340 C120 P56003 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:18297/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D13=20 [junit4:junit4] 2> 16976 T1318 C121 P18297 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D27=20 [junit4:junit4] 2> 16976 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[142]} 0 30 [junit4:junit4] 2> 16993 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[143 (1413784193405550592)]} 0 14 [junit4:junit4] 2> 17006 T1340 C120 P56003 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:18297/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D3=20 [junit4:junit4] 2> 17006 T1318 C121 P18297 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D7=20 [junit4:junit4] 2> 17007 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[143]} 0 11 [junit4:junit4] 2> 17011 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[144 (1413784193437007872)]} 0 2 [junit4:junit4] 2> 17019 T1340 C120 P56003 REQ /update {distrib.from=3Dht= tp://127.0.0.1:18297/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17020 T1315 C121 P18297 /update {wt=3Djavabin&version= =3D2} {add=3D[144 (1413784193442250752)]} 0 6 [junit4:junit4] 2> 17023 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[145 (1413784193450639360)]} 0 1 [junit4:junit4] 2> 17031 T1340 C120 P56003 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:18297/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17032 T1318 C121 P18297 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 17032 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[145]} 0 7 [junit4:junit4] 2> 17036 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[146 (1413784193464270848)]} 0 1 [junit4:junit4] 2> 17071 T1326 C118 P22060 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D17=20 [junit4:junit4] 2> 17072 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[146 (1413784193467416576)]} 0 34 [junit4:junit4] 2> 17076 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[147 (1413784193506213888)]} 0 1 [junit4:junit4] 2> 17084 T1340 C120 P56003 REQ /update {distrib.from=3Dht= tp://127.0.0.1:18297/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17085 T1315 C121 P18297 /update {wt=3Djavabin&version= =3D2} {add=3D[147 (1413784193510408192)]} 0 6 [junit4:junit4] 2> 17090 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[148 (1413784193519845376)]} 0 2 [junit4:junit4] 2> 17103 T1326 C118 P22060 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:23059/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 17103 T1308 C119 P23059 REQ /update {distrib.from=3Dht= tp://127.0.0.1:18297/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D9=20 [junit4:junit4] 2> 17104 T1315 C121 P18297 /update {wt=3Djavabin&version= =3D2} {add=3D[148]} 0 11 [junit4:junit4] 2> 17108 T1288 C122 P47845 /update {wt=3Djavabin&version= =3D2} {add=3D[149 (1413784193539768320)]} 0 1 [junit4:junit4] 2> 17120 T1340 C120 P56003 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:18297/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17121 T1318 C121 P18297 REQ /update {distrib.from=3Dht= tp://127.0.0.1:23059/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D7=20 [junit4:junit4] 2> 17122 T1304 C119 P23059 /update {wt=3Djavabin&version= =3D2} {add=3D[149]} 0 11 [junit4:junit4] 2> 17122 T1276 oasc.AbstractDistribZkTestBase.waitForReco= veriesToFinish Wait for recoveries to finish - collection: collection1 fail= OnTimeout:true timeout (sec):3600 [junit4:junit4] 2> 17123 T1276 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 17126 T1288 C122 P47845 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 17746 T1288 C122 P47845 oasc.SolrDeletionPolicy.onComm= it SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -NightlyTests-trunk/solr/build/solr-core/test/J1/org.apache.solr.cloud.Basi= cDistributedZk2Test-1348289657217/control/data/index lockFactory=3Dorg.apac= he.lucene.store.NativeFSLockFactory@1bf55430; maxCacheMB=3D48.0 maxMergeSiz= eMB=3D4.0),segFN=3Dsegments_2,generation=3D2,filenames=3D[_0_Asserting_0.ti= m, _0_Asserting_0.tip, _0_Lucene40_0.prx, _0_Asserting_0.prx, _0_Lucene40_0= .frq, _0.si, _0_MockRandom_0.tib, _0_Lucene40_0.tim, _0.fnm, _0_nrm.cfs, _0= _Direct_0.frq, _0_MockRandom_0.skp, _0_Lucene40_0.tip, _0_MockRandom_0.frq,= _0_MockRandom_0.sd, _0_Asserting_0.frq, _0_MockRandom_0.doc, _0_MockRandom= _0.pos, _0_nrm.cfe, segments_2, _0_MockRandom_0.tii, _0_Direct_0.tip, _0.fd= x, _0_Dire [...truncated too long message...] ache.solr.cloud.BasicDistributedZk2Test-1348289657217/jetty4/index does not= exist [junit4:junit4] 2> =09at org.apache.commons.io.FileUtils.sizeOfDirectory(= FileUtils.java:2074) [junit4:junit4] 2> =09at org.apache.solr.handler.ReplicationHandler.getIn= dexSize(ReplicationHandler.java:477) [junit4:junit4] 2> =09at org.apache.solr.handler.ReplicationHandler.getSt= atistics(ReplicationHandler.java:525) [junit4:junit4] 2> =09at org.apache.solr.core.RequestHandlers$LazyRequest= HandlerWrapper.getStatistics(RequestHandlers.java:310) [junit4:junit4] 2> =09at org.apache.solr.core.JmxMonitoredMap$SolrDynamic= MBean.getMBeanInfo(JmxMonitoredMap.java:231) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.getClassName(DefaultMBeanServerInterceptor.java:1824) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.safeGetClassName(DefaultMBeanServerInterceptor.java:1661) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.checkMBeanPermission(DefaultMBeanServerInterceptor.java:1833) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.getAttribute(DefaultMBeanServerInterceptor.java:680) [junit4:junit4] 2> =09at com.sun.jmx.mbeanserver.JmxMBeanServer.getAttrib= ute(JmxMBeanServer.java:672) [junit4:junit4] 2> =09at org.apache.solr.core.JmxMonitoredMap.unregister(= JmxMonitoredMap.java:174) [junit4:junit4] 2> =09at org.apache.solr.core.JmxMonitoredMap.clear(JmxMo= nitoredMap.java:116) [junit4:junit4] 2> =09at org.apache.solr.core.SolrCore.close(SolrCore.jav= a:864) [junit4:junit4] 2> =09at org.apache.solr.cloud.ShardLeaderElectionContext= .runLeaderProcess(ElectionContext.java:213) [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> 308165 T1348 oasc.JmxMonitoredMap$SolrDynamicMBean.get= MBeanInfo WARNING Could not getStatistics on info bean Lazy[solr.Replicatio= nHandler] java.lang.IllegalArgumentException: /usr/home/hudson/hudson-slave= /workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/org.= apache.solr.cloud.BasicDistributedZk2Test-1348289657217/jetty4/index does n= ot exist [junit4:junit4] 2> =09at org.apache.commons.io.FileUtils.sizeOfDirectory(= FileUtils.java:2074) [junit4:junit4] 2> =09at org.apache.solr.handler.ReplicationHandler.getIn= dexSize(ReplicationHandler.java:477) [junit4:junit4] 2> =09at org.apache.solr.handler.ReplicationHandler.getSt= atistics(ReplicationHandler.java:525) [junit4:junit4] 2> =09at org.apache.solr.core.RequestHandlers$LazyRequest= HandlerWrapper.getStatistics(RequestHandlers.java:310) [junit4:junit4] 2> =09at org.apache.solr.core.JmxMonitoredMap$SolrDynamic= MBean.getMBeanInfo(JmxMonitoredMap.java:231) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.getClassName(DefaultMBeanServerInterceptor.java:1824) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.safeGetClassName(DefaultMBeanServerInterceptor.java:1661) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.checkMBeanPermission(DefaultMBeanServerInterceptor.java:1833) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:436) [junit4:junit4] 2> =09at com.sun.jmx.interceptor.DefaultMBeanServerInterc= eptor.unregisterMBean(DefaultMBeanServerInterceptor.java:421) [junit4:junit4] 2> =09at com.sun.jmx.mbeanserver.JmxMBeanServer.unregiste= rMBean(JmxMBeanServer.java:540) [junit4:junit4] 2> =09at org.apache.solr.core.JmxMonitoredMap.unregister(= JmxMonitoredMap.java:175) [junit4:junit4] 2> =09at org.apache.solr.core.JmxMonitoredMap.clear(JmxMo= nitoredMap.java:116) [junit4:junit4] 2> =09at org.apache.solr.core.SolrCore.close(SolrCore.jav= a:864) [junit4:junit4] 2> =09at org.apache.solr.cloud.ShardLeaderElectionContext= .runLeaderProcess(ElectionContext.java:213) [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> 308169 T1348 oasc.SolrCore.closeSearcher [collection1]= Closing main searcher on request. [junit4:junit4] 2> 308172 T1348 oasu.DirectUpdateHandler2.close closing D= irectUpdateHandler2{commits=3D5,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=3D35,cumulative_delete= sById=3D0,cumulative_deletesByQuery=3D0,cumulative_errors=3D0} [junit4:junit4] 2> 308172 T1348 oasu.DefaultSolrCoreState.decref SolrCore= State ref count has reached 0 - closing IndexWriter [junit4:junit4] 2> 308174 T1348 oasu.DefaultSolrCoreState.decref Closing = SolrCoreState - canceling any ongoing recovery [junit4:junit4] 2> 308174 T1348 oascc.ZkStateReader$3.process Updating li= ve nodes [junit4:junit4] 2> 308174 T1348 oascc.ZkStateReader$3.process WARNING Zoo= Keeper watch triggered, but Solr cannot talk to ZK [junit4:junit4] 2> 308175 T1348 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> 308175 T1348 oascc.ZkStateReader$2.process WARNING Zoo= Keeper watch triggered, but Solr cannot talk to ZK [junit4:junit4] 2> 308175 T1348 oascc.ConnectionManager.process Watcher o= rg.apache.solr.common.cloud.ConnectionManager@d016b48 name:ZooKeeperConnect= ion Watcher:127.0.0.1:21057/solr got event WatchedEvent state:Disconnected = type:None path:null path:null type:None [junit4:junit4] 2> 308175 T1348 oascc.ConnectionManager.process Client->Z= ooKeeper status change trigger but we are already closed [junit4:junit4] 2> 308175 T1348 oaz.ClientCnxn$EventThread.run EventThrea= d shut down [junit4:junit4] 2> NOTE: test params are: codec=3DLucene40: {n_f1=3DPosti= ngsFormat(name=3DDirect), foo_b=3DPostingsFormat(name=3DMockRandom), foo_d= =3DLucene40(minBlockSize=3D82 maxBlockSize=3D239), foo_f=3DPostingsFormat(n= ame=3DMockRandom), n_tl1=3DPostingsFormat(name=3DDirect), n_d1=3DPostingsFo= rmat(name=3DAsserting), rnd_b=3DLucene40(minBlockSize=3D82 maxBlockSize=3D2= 39), intDefault=3DPostingsFormat(name=3DDirect), n_td1=3DPostingsFormat(nam= e=3DDirect), id=3DLucene40(minBlockSize=3D82 maxBlockSize=3D239), timestamp= =3DPostingsFormat(name=3DAsserting), range_facet_sl=3DLucene40(minBlockSize= =3D82 maxBlockSize=3D239), range_facet_si=3DPostingsFormat(name=3DAsserting= ), oddField_s=3DLucene40(minBlockSize=3D82 maxBlockSize=3D239), multiDefaul= t=3DPostingsFormat(name=3DAsserting), n_tf1=3DPostingsFormat(name=3DAsserti= ng), n_dt1=3DPostingsFormat(name=3DDirect), n_ti1=3DLucene40(minBlockSize= =3D82 maxBlockSize=3D239), range_facet_l=3DPostingsFormat(name=3DAsserting)= , text=3DPostingsFormat(name=3DMockRandom), _version_=3DPostingsFormat(name= =3DDirect), SubjectTerms_mfacet=3DPostingsFormat(name=3DDirect), a_t=3DPost= ingsFormat(name=3DAsserting), n_tdt1=3DPostingsFormat(name=3DDirect), other= _tl1=3DPostingsFormat(name=3DDirect), n_l1=3DPostingsFormat(name=3DAssertin= g), a_si=3DPostingsFormat(name=3DDirect)}, sim=3DRandomSimilarityProvider(q= ueryNorm=3Dtrue,coord=3Dno): {}, locale=3Den_SG, timezone=3DBET [junit4:junit4] 2> NOTE: FreeBSD 9.0-RELEASE amd64/Sun Microsystems Inc. = 1.6.0_32 (64-bit)/cpus=3D16,threads=3D2,free=3D50917416,total=3D180748288 [junit4:junit4] 2> NOTE: All tests run in this JVM: [DocumentAnalysisRequ= estHandlerTest, TestSolrCoreProperties, ZkNodePropsTest, TestDFRSimilarityF= actory, LukeRequestHandlerTest, TestQueryUtils, TestReload, SolrIndexConfig= Test, DebugComponentTest, PrimUtilsTest, IndexSchemaTest, ConvertedLegacyTe= st, TestMergePolicyConfig, TestHashPartitioner, TestPropInjectDefaults, Tes= tUpdate, QueryEqualityTest, TestDistributedSearch, TestLRUCache, TestFuncti= onQuery, ClusterStateUpdateTest, TestSolrXMLSerializer, SolrInfoMBeanTest, = TestCSVLoader, FieldMutatingUpdateProcessorTest, DirectSolrSpellCheckerTest= , RAMDirectoryFactoryTest, TestReversedWildcardFilterFactory, SpellingQuery= ConverterTest, TestBM25SimilarityFactory, TestRemoteStreaming, TestCSVRespo= nseWriter, TermVectorComponentTest, TestReplicationHandler, BasicDistribute= dZk2Test] [junit4:junit4] Completed on J1 in 308.84s, 1 test, 1 failure <<< FAILURES! [...truncated 425 lines...] BUILD FAILED /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/buil= d.xml:32: The following error occurred while executing this line: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr= /build.xml:179: The following error occurred while executing this line: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/luce= ne/module-build.xml:63: The following error occurred while executing this l= ine: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/luce= ne/common-build.xml:1140: The following error occurred while executing this= line: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-NightlyTests-trunk/luce= ne/common-build.xml:813: There were test failures: 230 suites, 950 tests, 1= failure, 7 ignored (2 assumptions) Total time: 72 minutes 36 seconds Build step 'Invoke Ant' marked build as failure Recording test results Email was triggered for: Failure Sending email for trigger: Failure ------=_Part_134_1876322315.1348290359230 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_134_1876322315.1348290359230--