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 C2B5D9A2D for ; Thu, 13 Sep 2012 20:35:45 +0000 (UTC) Received: (qmail 95527 invoked by uid 500); 13 Sep 2012 20:35:44 -0000 Delivered-To: apmail-lucene-dev-archive@lucene.apache.org Received: (qmail 95432 invoked by uid 500); 13 Sep 2012 20:35:44 -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 95425 invoked by uid 99); 13 Sep 2012 20:35:44 -0000 Received: from athena.apache.org (HELO athena.apache.org) (140.211.11.136) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 13 Sep 2012 20:35:44 +0000 X-ASF-Spam-Status: No, hits=-2000.0 required=5.0 tests=ALL_TRUSTED,NORMAL_HTTP_TO_IP,WEIRD_PORT X-Spam-Check-By: apache.org Received: from [140.211.11.8] (HELO aegis.apache.org) (140.211.11.8) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 13 Sep 2012 20:35:35 +0000 Received: from aegis.apache.org (localhost [127.0.0.1]) by aegis.apache.org (Postfix) with ESMTP id 1A264C00A0 for ; Thu, 13 Sep 2012 20:35:15 +0000 (UTC) Date: Thu, 13 Sep 2012 20:34:55 +0000 (UTC) From: Apache Jenkins Server To: dev@lucene.apache.org Message-ID: <1148249115.352.1347568515105.JavaMail.hudson@aegis.apache.org> Subject: [JENKINS] Lucene-Solr-Tests-4.x-java7 - Build # 441 - Failure MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_351_1373457259.1347568495504" X-Virus-Checked: Checked by ClamAV on apache.org ------=_Part_351_1373457259.1347568495504 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: https://builds.apache.org/job/Lucene-Solr-Tests-4.x-java7/441/ 1 tests failed. REGRESSION: org.apache.solr.cloud.BasicDistributedZk2Test.testDistribSearc= h Error Message: Timeout occured while waiting response from server at: http://localhost:463= 11/solr/collection1 Stack Trace: org.apache.solr.client.solrj.SolrServerException: Timeout occured while wai= ting response from server at: http://localhost:46311/solr/collection1 =09at __randomizedtesting.SeedInfo.seed([D103DF8F26F1D954:50E5519751AEB968]= :0) =09at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServ= er.java:410) =09at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServ= er.java:182) =09at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(Ab= stractUpdateRequest.java:117) =09at org.apache.solr.cloud.AbstractFullDistribZkTestBase.index_specific(Ab= stractFullDistribZkTestBase.java:516) =09at org.apache.solr.cloud.AbstractFullDistribZkTestBase.checkQueries(Abst= ractFullDistribZkTestBase.java:688) =09at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributed= Zk2Test.java:77) =09at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseD= istributedSearchTestCase.java:691) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:57) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:601) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1559) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(Random= izedRunner.java:79) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:737) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:773) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:787) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:53) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:50) =09at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFi= eldCacheSanity.java:51) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java: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:722) Caused by: java.net.SocketTimeoutException: Read timed out =09at java.net.SocketInputStream.socketRead0(Native Method) =09at java.net.SocketInputStream.read(SocketInputStream.java:150) =09at java.net.SocketInputStream.read(SocketInputStream.java:121) =09at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(Abstrac= tSessionInputBuffer.java:149) =09at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffe= r.java:111) =09at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractS= essionInputBuffer.java:264) =09at org.apache.http.impl.conn.DefaultResponseParser.parseHead(DefaultResp= onseParser.java:98) =09at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessagePa= rser.java:252) =09at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHead= er(AbstractHttpClientConnection.java:282) =09at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHead= er(DefaultClientConnection.java:247) =09at org.apache.http.impl.conn.AbstractClientConnAdapter.receiveResponseHe= ader(AbstractClientConnAdapter.java:216) =09at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRe= questExecutor.java:298) =09at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecu= tor.java:125) =09at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(Default= RequestDirector.java:647) =09at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultReq= uestDirector.java:464) =09at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpCl= ient.java:820) =09at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpCl= ient.java:754) =09at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpCl= ient.java:732) =09at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServ= er.java:353) =09... 46 more Build Log: [...truncated 8310 lines...] [junit4:junit4] Suite: org.apache.solr.cloud.BasicDistributedZk2Test [junit4:junit4] 2> 2 T2070 oas.SolrTestCaseJ4.setUp ###Starting testDistr= ibSearch [junit4:junit4] 2> Creating dataDir: /usr/home/hudson/hudson-slave/worksp= ace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./solrtest-Bas= icDistributedZk2Test-1347568174611 [junit4:junit4] 2> 3 T2070 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4:junit4] 2> 6 T2071 oazs.ZooKeeperServer.setTickTime tickTime set = to 3000 [junit4:junit4] 2> 6 T2071 oazs.NIOServerCnxn$Factory. binding to p= ort 0.0.0.0/0.0.0.0:0 [junit4:junit4] 2> 7 T2071 oazsp.FileTxnSnapLog.save Snapshotting: 0 [junit4:junit4] 2> 105 T2070 oasc.ZkTestServer.run start zk server on por= t:47166 [junit4:junit4] 2> 105 T2070 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:47166 sessionTimeout=3D10000 watcher=3Dorg= .apache.solr.common.cloud.ConnectionManager@6745cd49 [junit4:junit4] 2> 106 T2076 oaz.ClientCnxn$SendThread.startConnect Openi= ng socket connection to server /127.0.0.1:47166 [junit4:junit4] 2> 106 T2070 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 108 T2076 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to localhost/127.0.0.1:47166, initiating sessio= n [junit4:junit4] 2> 108 T2072 oazs.NIOServerCnxn$Factory.run Accepted sock= et connection from /140.211.11.196:37959 [junit4:junit4] 2> 108 T2072 oazs.NIOServerCnxn.readConnectRequest Client= attempting to establish new session at /140.211.11.196:37959 [junit4:junit4] 2> 109 T2074 oazsp.FileTxnLog.append Creating new log fil= e: log.1 [junit4:junit4] 2> 112 T2074 oazs.NIOServerCnxn.finishSessionInit Establi= shed session 0x139c15286180000 with negotiated timeout 10000 for client /14= 0.211.11.196:37959 [junit4:junit4] 2> 112 T2076 oaz.ClientCnxn$SendThread.readConnectResult = Session establishment complete on server localhost/127.0.0.1:47166, session= id =3D 0x139c15286180000, negotiated timeout =3D 10000 [junit4:junit4] 2> 112 T2077 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@6745cd49 name:ZooKeeperConnectio= n Watcher:127.0.0.1:47166 got event WatchedEvent state:SyncConnected type:N= one path:null path:null type:None [junit4:junit4] 2> 112 T2070 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 113 T2070 oascc.SolrZkClient.makePath makePath: /solr [junit4:junit4] 2> 124 T2075 oazs.PrepRequestProcessor.pRequest Processed= session termination for sessionid: 0x139c15286180000 [junit4:junit4] 2> 125 T2077 oaz.ClientCnxn$EventThread.run EventThread s= hut down [junit4:junit4] 2> 125 T2072 oazs.NIOServerCnxn.closeSock Closed socket c= onnection for client /140.211.11.196:37959 which had sessionid 0x139c152861= 80000 [junit4:junit4] 2> 125 T2070 oaz.ZooKeeper.close Session: 0x139c152861800= 00 closed [junit4:junit4] 2> 126 T2070 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:47166/solr sessionTimeout=3D10000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@3532a7a4 [junit4:junit4] 2> 127 T2078 oaz.ClientCnxn$SendThread.startConnect Openi= ng socket connection to server /127.0.0.1:47166 [junit4:junit4] 2> 127 T2070 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 127 T2078 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to localhost/127.0.0.1:47166, initiating sessio= n [junit4:junit4] 2> 128 T2072 oazs.NIOServerCnxn$Factory.run Accepted sock= et connection from /140.211.11.196:28332 [junit4:junit4] 2> 128 T2072 oazs.NIOServerCnxn.readConnectRequest Client= attempting to establish new session at /140.211.11.196:28332 [junit4:junit4] 2> 129 T2074 oazs.NIOServerCnxn.finishSessionInit Establi= shed session 0x139c15286180001 with negotiated timeout 10000 for client /14= 0.211.11.196:28332 [junit4:junit4] 2> 129 T2078 oaz.ClientCnxn$SendThread.readConnectResult = Session establishment complete on server localhost/127.0.0.1:47166, session= id =3D 0x139c15286180001, negotiated timeout =3D 10000 [junit4:junit4] 2> 130 T2079 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@3532a7a4 name:ZooKeeperConnectio= n Watcher:127.0.0.1:47166/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 130 T2070 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 130 T2070 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1 [junit4:junit4] 2> 146 T2070 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1/shards [junit4:junit4] 2> 150 T2070 oascc.SolrZkClient.makePath makePath: /colle= ctions/control_collection [junit4:junit4] 2> 152 T2070 oascc.SolrZkClient.makePath makePath: /colle= ctions/control_collection/shards [junit4:junit4] 2> 159 T2070 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/so= lr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/s= olrconfig.xml [junit4:junit4] 2> 160 T2070 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/solrconfig.xml [junit4:junit4] 2> 171 T2070 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/so= lr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schem= a.xml [junit4:junit4] 2> 172 T2070 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/schema.xml [junit4:junit4] 2> 275 T2070 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/so= lr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/s= olrconfig.xml [junit4:junit4] 2> 276 T2070 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/solrconfig.xml [junit4:junit4] 2> 278 T2070 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/so= lr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/st= opwords.txt [junit4:junit4] 2> 279 T2070 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/stopwords.txt [junit4:junit4] 2> 290 T2070 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/so= lr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/pr= otwords.txt [junit4:junit4] 2> 290 T2070 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/protwords.txt [junit4:junit4] 2> 293 T2070 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/so= lr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/cur= rency.xml [junit4:junit4] 2> 293 T2070 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/currency.xml [junit4:junit4] 2> 295 T2070 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/so= lr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /confi= gs/conf1/open-exchange-rates.json [junit4:junit4] 2> 296 T2070 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/open-exchange-rates.json [junit4:junit4] 2> 303 T2070 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/so= lr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /co= nfigs/conf1/mapping-ISOLatin1Accent.txt [junit4:junit4] 2> 304 T2070 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/mapping-ISOLatin1Accent.txt [junit4:junit4] 2> 306 T2070 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/so= lr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1= /old_synonyms.txt [junit4:junit4] 2> 306 T2070 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/old_synonyms.txt [junit4:junit4] 2> 308 T2070 oasc.AbstractZkTestCase.putConfig put /usr/h= ome/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/so= lr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/syn= onyms.txt [junit4:junit4] 2> 309 T2070 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/synonyms.txt [junit4:junit4] 2> 315 T2075 oazs.PrepRequestProcessor.pRequest Processed= session termination for sessionid: 0x139c15286180001 [junit4:junit4] 2> 316 T2079 oaz.ClientCnxn$EventThread.run EventThread s= hut down [junit4:junit4] 2> 316 T2070 oaz.ZooKeeper.close Session: 0x139c152861800= 01 closed [junit4:junit4] 2> 316 T2072 oazs.NIOServerCnxn.closeSock Closed socket c= onnection for client /140.211.11.196:28332 which had sessionid 0x139c152861= 80001 [junit4:junit4] 2> 318 T2070 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 321 T2070 oejs.AbstractConnector.doStart Started Socke= tConnector@0.0.0.0:14282 [junit4:junit4] 2> 322 T2070 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 322 T2070 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr [junit4:junit4] 2> 323 T2070 oasc.SolrResourceLoader. new SolrResou= rceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/' [junit4:junit4] 2> 353 T2070 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() [junit4:junit4] 2> 354 T2070 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 354 T2070 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr [junit4:junit4] 2> 354 T2070 oasc.CoreContainer$Initializer.initialize lo= oking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tes= ts-4.x-java7/solr/build/solr-core/test-files/solr/solr.xml [junit4:junit4] 2> 355 T2070 oasc.CoreContainer. New CoreContainer = 1545581036 [junit4:junit4] 2> 355 T2070 oasc.CoreContainer.load Loading CoreContaine= r using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tes= ts-4.x-java7/solr/build/solr-core/test-files/solr/' [junit4:junit4] 2> 356 T2070 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-So= lr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/' [junit4:junit4] 2> 380 T2070 oasc.CoreContainer.load Registering Log List= ener [junit4:junit4] 2> 392 T2070 oasc.CoreContainer.initZooKeeper Zookeeper c= lient=3D127.0.0.1:47166/solr [junit4:junit4] 2> 392 T2070 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:47166/solr sessionTimeout=3D8000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@4acc5621 [junit4:junit4] 2> 393 T2089 oaz.ClientCnxn$SendThread.startConnect Openi= ng socket connection to server /127.0.0.1:47166 [junit4:junit4] 2> 393 T2070 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 394 T2089 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to localhost/127.0.0.1:47166, initiating sessio= n [junit4:junit4] 2> 394 T2072 oazs.NIOServerCnxn$Factory.run Accepted sock= et connection from /140.211.11.196:15262 [junit4:junit4] 2> 395 T2072 oazs.NIOServerCnxn.readConnectRequest Client= attempting to establish new session at /140.211.11.196:15262 [junit4:junit4] 2> 396 T2074 oazs.NIOServerCnxn.finishSessionInit Establi= shed session 0x139c15286180002 with negotiated timeout 8000 for client /140= .211.11.196:15262 [junit4:junit4] 2> 396 T2089 oaz.ClientCnxn$SendThread.readConnectResult = Session establishment complete on server localhost/127.0.0.1:47166, session= id =3D 0x139c15286180002, negotiated timeout =3D 8000 [junit4:junit4] 2> 396 T2090 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@4acc5621 name:ZooKeeperConnectio= n Watcher:127.0.0.1:47166/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 397 T2070 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 398 T2075 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139c15286180002 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> 407 T2070 oascc.SolrZkClient.makePath makePath: /live_= nodes [junit4:junit4] 2> 408 T2070 oasc.ZkController.createEphemeralLiveNode Re= gister node as live in ZooKeeper:/live_nodes/127.0.0.1:14282_solr [junit4:junit4] 2> 409 T2075 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139c15286180002 type:dele= te cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/live_nodes/127.0.0.1:14282_solr Error:KeeperErrorCode =3D NoNode for = /solr/live_nodes/127.0.0.1:14282_solr [junit4:junit4] 2> 414 T2070 oascc.SolrZkClient.makePath makePath: /live_= nodes/127.0.0.1:14282_solr [junit4:junit4] 2> 416 T2070 oashc.HttpShardHandlerFactory.getParameter S= etting socketTimeout to: 0 [junit4:junit4] 2> 416 T2070 oashc.HttpShardHandlerFactory.getParameter S= etting urlScheme to: http:// [junit4:junit4] 2> 417 T2070 oashc.HttpShardHandlerFactory.getParameter S= etting connTimeout to: 0 [junit4:junit4] 2> 417 T2070 oashc.HttpShardHandlerFactory.getParameter S= etting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 418 T2070 oashc.HttpShardHandlerFactory.getParameter S= etting corePoolSize to: 0 [junit4:junit4] 2> 418 T2070 oashc.HttpShardHandlerFactory.getParameter S= etting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 418 T2070 oashc.HttpShardHandlerFactory.getParameter S= etting maxThreadIdleTime to: 5 [junit4:junit4] 2> 419 T2070 oashc.HttpShardHandlerFactory.getParameter S= etting sizeOfQueue to: -1 [junit4:junit4] 2> 419 T2070 oashc.HttpShardHandlerFactory.getParameter S= etting fairnessPolicy to: false [junit4:junit4] 2> 420 T2070 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&s= ocketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 429 T2070 oascc.SolrZkClient.makePath makePath: /overs= eer_elect/election [junit4:junit4] 2> 434 T2075 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139c15286180002 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> 447 T2070 oascc.SolrZkClient.makePath makePath: /overs= eer_elect/leader [junit4:junit4] 2> 449 T2070 oasc.Overseer.start Overseer (id=3D883142278= 91634178-127.0.0.1:14282_solr-n_0000000000) starting [junit4:junit4] 2> 449 T2075 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139c15286180002 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> 455 T2075 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139c15286180002 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> 457 T2075 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139c15286180002 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> 458 T2075 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139c15286180002 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> 468 T2092 oasc.OverseerCollectionProcessor.run Process= current queue of collection creations [junit4:junit4] 2> 469 T2070 oascc.SolrZkClient.makePath makePath: /clust= erstate.json [junit4:junit4] 2> 472 T2070 oascc.ZkStateReader.createClusterStateWatche= rsAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 473 T2091 oasc.Overseer$ClusterStateUpdater.run Starti= ng to work on the main queue [junit4:junit4] 2> 478 T2070 oasc.CoreContainer.create Creating SolrCore = 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lu= cene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1 [junit4:junit4] 2> 478 T2070 oasc.ZkController.createCollectionZkNode Che= ck for collection zkNode:control_collection [junit4:junit4] 2> 479 T2070 oasc.ZkController.createCollectionZkNode Col= lection zkNode exists [junit4:junit4] 2> 479 T2070 oasc.ZkController.readConfigName Load collec= tion config from:/collections/control_collection [junit4:junit4] 2> 480 T2070 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-So= lr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/' [junit4:junit4] 2> 481 T2070 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-j= ava7/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to clas= sloader [junit4:junit4] 2> 482 T2070 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-j= ava7/solr/build/solr-core/test-files/solr/collection1/lib/README' to classl= oader [junit4:junit4] 2> 528 T2070 oasc.SolrConfig. Using Lucene MatchVer= sion: LUCENE_40 [junit4:junit4] 2> 584 T2070 oasc.SolrConfig. Loaded SolrConfig: so= lrconfig.xml [junit4:junit4] 2> 685 T2070 oass.IndexSchema.readSchema Reading Solr Sch= ema [junit4:junit4] 2> 695 T2070 oass.IndexSchema.readSchema Schema name=3Dte= st [junit4:junit4] 2> 1201 T2070 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 1214 T2070 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 1220 T2070 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 1232 T2070 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 1237 T2070 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 1241 T2070 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 1242 T2070 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 1243 T2070 oasc.SolrCore. [collection1] Opening = new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4= .x-java7/solr/build/solr-core/test-files/solr/collection1/, dataDir=3D./org= .apache.solr.cloud.BasicDistributedZk2Test-1347568174611/control/data/ [junit4:junit4] 2> 1244 T2070 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@85bce28 [junit4:junit4] 2> 1245 T2070 oasc.SolrCore.getNewIndexDir WARNING New in= dex directory detected: old=3Dnull new=3D./org.apache.solr.cloud.BasicDistr= ibutedZk2Test-1347568174611/control/data/index/ [junit4:junit4] 2> 1246 T2070 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-13= 47568174611/control/data/index' doesn't exist. Creating new index... [junit4:junit4] 2> 1247 T2070 oasc.CachingDirectoryFactory.get return new= directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.= x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistribut= edZk2Test-1347568174611/control/data/index forceNew:false [junit4:junit4] 2> 1255 T2070 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= -Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDi= stributedZk2Test-1347568174611/control/data/index lockFactory=3Dorg.apache.= lucene.store.NativeFSLockFactory@1624f38e; maxCacheMB=3D48.0 maxMergeSizeMB= =3D4.0),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 1256 T2070 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1 [junit4:junit4] 2> 1257 T2070 oasc.SolrCore.initWriters created xml: solr= .XMLResponseWriter [junit4:junit4] 2> 1258 T2070 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 1259 T2070 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe-allfields" [junit4:junit4] 2> 1259 T2070 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 1260 T2070 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "u= niq-fields" [junit4:junit4] 2> 1260 T2070 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 1261 T2070 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 1261 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 1262 T2070 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 1262 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 1263 T2070 oasc.RequestHandlers.initHandlersFromConfig= created dismax: solr.SearchHandler [junit4:junit4] 2> 1264 T2070 oasc.RequestHandlers.initHandlersFromConfig= created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 1264 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 1265 T2070 oasc.RequestHandlers.initHandlersFromConfig= created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 1265 T2070 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 1265 T2070 oasc.RequestHandlers.initHandlersFromConfig= created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 1266 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 1266 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 1267 T2070 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 1267 T2070 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 1268 T2070 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 1268 T2070 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.= SearchHandler [junit4:junit4] 2> 1269 T2070 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 1269 T2070 oasc.RequestHandlers.initHandlersFromConfig= created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 1270 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 1271 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 1273 T2070 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 1277 T2070 oasc.SolrCore.initDeprecatedSupport WARNING= solrconfig.xml uses deprecated , Please update your c= onfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 1278 T2070 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> 1281 T2070 oass.SolrIndexSearcher. Opening Searc= her@154ecd1b main [junit4:junit4] 2> 1282 T2070 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 1283 T2070 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 1283 T2070 oashc.SpellCheckComponent.inform Initializi= ng spell checkers [junit4:junit4] 2> 1315 T2070 oass.DirectSolrSpellChecker.init init: {nam= e=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLen= gth=3D3} [junit4:junit4] 2> 1371 T2093 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@154ecd1b main{StandardDirectoryReader(se= gments_1:1)} [junit4:junit4] 2> 1374 T2075 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139c15286180002 type:cre= ate cxid:0x53 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer/queue Error:KeeperErrorCode =3D NoNode for /solr/overseer/= queue [junit4:junit4] 2> 1386 T2070 oasc.CoreContainer.register registering cor= e: collection1 [junit4:junit4] 2> 1387 T2070 oasc.ZkController.register Register shard -= core:collection1 address:http://127.0.0.1:14282/solr shardId:control_shard [junit4:junit4] 2> 1387 T2070 oascc.SolrZkClient.makePath makePath: /coll= ections/control_collection/leader_elect/control_shard/election [junit4:junit4] 2> 1404 T2075 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139c15286180002 type:del= ete cxid:0x62 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> 1405 T2070 oasc.ShardLeaderElectionContext.runLeaderPr= ocess Running the leader process. afterExpiration=3Dfalse [junit4:junit4] 2> 1406 T2075 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139c15286180002 type:cre= ate cxid:0x63 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 1413 T2070 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I am the new leader: http://127.0.0.1:14282/solr/collection1/ [junit4:junit4] 2> 1414 T2070 oascc.SolrZkClient.makePath makePath: /coll= ections/control_collection/leaders/control_shard [junit4:junit4] 2> 1425 T2075 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139c15286180002 type:cre= ate cxid:0x6c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 1478 T2091 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 1479 T2075 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139c15286180002 type:cre= ate cxid:0x74 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> 1496 T2090 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 1529 T2070 oasc.ZkController.register We are http://12= 7.0.0.1:14282/solr/collection1/ and leader is http://127.0.0.1:14282/solr/c= ollection1/ [junit4:junit4] 2> 1529 T2070 oasc.ZkController.register No LogReplay nee= ded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:14282/solr [junit4:junit4] 2> 1530 T2070 oasc.ZkController.checkRecovery I am the le= ader, no recovery necessary [junit4:junit4] 2> 1532 T2070 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 1533 T2070 oass.SolrDispatchFilter.init user.dir=3D/us= r/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build= /solr-core/test/J0 [junit4:junit4] 2> 1533 T2070 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() done [junit4:junit4] 2> 1533 T2070 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fo= llowRedirects=3Dfalse [junit4:junit4] 2> 1544 T2070 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 1547 T2070 oejs.AbstractConnector.doStart Started Sock= etConnector@0.0.0.0:49212 [junit4:junit4] 2> 1547 T2070 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 1548 T2070 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr [junit4:junit4] 2> 1548 T2070 oasc.SolrResourceLoader. new SolrReso= urceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/= Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/' [junit4:junit4] 2> 1579 T2070 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() [junit4:junit4] 2> 1580 T2070 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 1581 T2070 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr [junit4:junit4] 2> 1581 T2070 oasc.CoreContainer$Initializer.initialize l= ooking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Te= sts-4.x-java7/solr/build/solr-core/test-files/solr/solr.xml [junit4:junit4] 2> 1582 T2070 oasc.CoreContainer. New CoreContainer= 1579015213 [junit4:junit4] 2> 1582 T2070 oasc.CoreContainer.load Loading CoreContain= er using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Te= sts-4.x-java7/solr/build/solr-core/test-files/solr/' [junit4:junit4] 2> 1583 T2070 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/' [junit4:junit4] 2> 1609 T2070 oasc.CoreContainer.load Registering Log Lis= tener [junit4:junit4] 2> 1621 T2070 oasc.CoreContainer.initZooKeeper Zookeeper = client=3D127.0.0.1:47166/solr [junit4:junit4] 2> 1622 T2070 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:47166/solr sessionTimeout=3D8000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@4d794132 [junit4:junit4] 2> 1623 T2070 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 1623 T2103 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:47166 [junit4:junit4] 2> 1625 T2103 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost/127.0.0.1:47166, initiating sessi= on [junit4:junit4] 2> 1625 T2072 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /140.211.11.196:28296 [junit4:junit4] 2> 1625 T2072 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /140.211.11.196:28296 [junit4:junit4] 2> 1629 T2074 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x139c15286180003 with negotiated timeout 8000 for client /14= 0.211.11.196:28296 [junit4:junit4] 2> 1629 T2103 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server localhost/127.0.0.1:47166, sessio= nid =3D 0x139c15286180003, negotiated timeout =3D 8000 [junit4:junit4] 2> 1629 T2104 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@4d794132 name:ZooKeeperConnecti= on Watcher:127.0.0.1:47166/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4:junit4] 2> 1630 T2070 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 1630 T2075 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139c15286180003 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> 1631 T2075 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139c15286180003 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> 1639 T2070 oasc.ZkController.createEphemeralLiveNode R= egister node as live in ZooKeeper:/live_nodes/127.0.0.1:49212_solr [junit4:junit4] 2> 1640 T2075 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139c15286180003 type:del= ete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/live_nodes/127.0.0.1:49212_solr Error:KeeperErrorCode =3D NoNode for= /solr/live_nodes/127.0.0.1:49212_solr [junit4:junit4] 2> 1641 T2070 oascc.SolrZkClient.makePath makePath: /live= _nodes/127.0.0.1:49212_solr [junit4:junit4] 2> 1643 T2090 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 1643 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting socketTimeout to: 0 [junit4:junit4] 2> 1644 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting urlScheme to: http:// [junit4:junit4] 2> 1644 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting connTimeout to: 0 [junit4:junit4] 2> 1645 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 1645 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting corePoolSize to: 0 [junit4:junit4] 2> 1645 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 1646 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 1646 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting sizeOfQueue to: -1 [junit4:junit4] 2> 1647 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting fairnessPolicy to: false [junit4:junit4] 2> 1647 T2070 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&= socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 1656 T2070 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 1661 T2070 oasc.CoreContainer.create Creating SolrCore= 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1 [junit4:junit4] 2> 1662 T2070 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:collection1 [junit4:junit4] 2> 1662 T2070 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 1663 T2070 oasc.ZkController.readConfigName Load colle= ction config from:/collections/collection1 [junit4:junit4] 2> 1664 T2070 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/' [junit4:junit4] 2> 1665 T2070 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-= java7/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to cla= ssloader [junit4:junit4] 2> 1665 T2070 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-= java7/solr/build/solr-core/test-files/solr/collection1/lib/README' to class= loader [junit4:junit4] 2> 1707 T2070 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_40 [junit4:junit4] 2> 1760 T2070 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 1861 T2070 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 1872 T2070 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 2029 T2091 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 2045 T2090 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 2045 T2104 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 2389 T2070 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 2398 T2070 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 2401 T2070 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 2413 T2070 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 2418 T2070 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 2422 T2070 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 2423 T2070 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 2424 T2070 oasc.SolrCore. [collection1] Opening = new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4= .x-java7/solr/build/solr-core/test-files/solr/collection1/, dataDir=3D./org= .apache.solr.cloud.BasicDistributedZk2Test-1347568174611/jetty1/ [junit4:junit4] 2> 2425 T2070 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@85bce28 [junit4:junit4] 2> 2426 T2070 oasc.SolrCore.getNewIndexDir WARNING New in= dex directory detected: old=3Dnull new=3D./org.apache.solr.cloud.BasicDistr= ibutedZk2Test-1347568174611/jetty1/index/ [junit4:junit4] 2> 2427 T2070 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-13= 47568174611/jetty1/index' doesn't exist. Creating new index... [junit4:junit4] 2> 2427 T2070 oasc.CachingDirectoryFactory.get return new= directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.= x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistribut= edZk2Test-1347568174611/jetty1/index forceNew:false [junit4:junit4] 2> 2432 T2070 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= -Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDi= stributedZk2Test-1347568174611/jetty1/index lockFactory=3Dorg.apache.lucene= .store.NativeFSLockFactory@15a8ef6; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0)= ,segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 2433 T2070 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1 [junit4:junit4] 2> 2434 T2070 oasc.SolrCore.initWriters created xml: solr= .XMLResponseWriter [junit4:junit4] 2> 2436 T2070 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 2437 T2070 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe-allfields" [junit4:junit4] 2> 2437 T2070 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 2438 T2070 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "u= niq-fields" [junit4:junit4] 2> 2438 T2070 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 2439 T2070 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 2439 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 2440 T2070 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 2440 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 2441 T2070 oasc.RequestHandlers.initHandlersFromConfig= created dismax: solr.SearchHandler [junit4:junit4] 2> 2442 T2070 oasc.RequestHandlers.initHandlersFromConfig= created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 2442 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 2443 T2070 oasc.RequestHandlers.initHandlersFromConfig= created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 2443 T2070 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 2444 T2070 oasc.RequestHandlers.initHandlersFromConfig= created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 2444 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 2445 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 2446 T2070 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 2446 T2070 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 2447 T2070 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 2447 T2070 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.= SearchHandler [junit4:junit4] 2> 2448 T2070 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 2449 T2070 oasc.RequestHandlers.initHandlersFromConfig= created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 2449 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 2450 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 2453 T2070 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 2456 T2070 oasc.SolrCore.initDeprecatedSupport WARNING= solrconfig.xml uses deprecated , Please update your c= onfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 2458 T2070 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> 2461 T2070 oass.SolrIndexSearcher. Opening Searc= her@6ce37200 main [junit4:junit4] 2> 2462 T2070 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 2462 T2070 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 2463 T2070 oashc.SpellCheckComponent.inform Initializi= ng spell checkers [junit4:junit4] 2> 2469 T2070 oass.DirectSolrSpellChecker.init init: {nam= e=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLen= gth=3D3} [junit4:junit4] 2> 2515 T2105 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@6ce37200 main{StandardDirectoryReader(se= gments_1:1)} [junit4:junit4] 2> 2549 T2091 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 2553 T2090 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 2553 T2104 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 3021 T2070 oasc.CoreContainer.register registering cor= e: collection1 [junit4:junit4] 2> 3021 T2070 oasc.ZkController.register Register shard -= core:collection1 address:http://127.0.0.1:49212/solr shardId:shard1 [junit4:junit4] 2> 3022 T2070 oascc.SolrZkClient.makePath makePath: /coll= ections/collection1/leader_elect/shard1/election [junit4:junit4] 2> 3038 T2075 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139c15286180003 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> 3039 T2070 oasc.ShardLeaderElectionContext.runLeaderPr= ocess Running the leader process. afterExpiration=3Dfalse [junit4:junit4] 2> 3040 T2075 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139c15286180003 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> 3042 T2070 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I am the new leader: http://127.0.0.1:49212/solr/collection1/ [junit4:junit4] 2> 3043 T2070 oascc.SolrZkClient.makePath makePath: /coll= ections/collection1/leaders/shard1 [junit4:junit4] 2> 3056 T2075 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139c15286180003 type:cre= ate cxid:0x4d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 3065 T2091 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 3070 T2090 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 3070 T2104 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 3109 T2070 oasc.ZkController.register We are http://12= 7.0.0.1:49212/solr/collection1/ and leader is http://127.0.0.1:49212/solr/c= ollection1/ [junit4:junit4] 2> 3109 T2070 oasc.ZkController.register No LogReplay nee= ded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:49212/solr [junit4:junit4] 2> 3110 T2070 oasc.ZkController.checkRecovery I am the le= ader, no recovery necessary [junit4:junit4] 2> 3112 T2070 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 3112 T2070 oass.SolrDispatchFilter.init user.dir=3D/us= r/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build= /solr-core/test/J0 [junit4:junit4] 2> 3113 T2070 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() done [junit4:junit4] 2> 3113 T2070 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fo= llowRedirects=3Dfalse [junit4:junit4] 2> 3123 T2070 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 3126 T2070 oejs.AbstractConnector.doStart Started Sock= etConnector@0.0.0.0:46546 [junit4:junit4] 2> 3126 T2070 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 3127 T2070 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr [junit4:junit4] 2> 3127 T2070 oasc.SolrResourceLoader. new SolrReso= urceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/= Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/' [junit4:junit4] 2> 3158 T2070 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() [junit4:junit4] 2> 3158 T2070 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 3159 T2070 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr [junit4:junit4] 2> 3159 T2070 oasc.CoreContainer$Initializer.initialize l= ooking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Te= sts-4.x-java7/solr/build/solr-core/test-files/solr/solr.xml [junit4:junit4] 2> 3160 T2070 oasc.CoreContainer. New CoreContainer= 1409532810 [junit4:junit4] 2> 3160 T2070 oasc.CoreContainer.load Loading CoreContain= er using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Te= sts-4.x-java7/solr/build/solr-core/test-files/solr/' [junit4:junit4] 2> 3161 T2070 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/' [junit4:junit4] 2> 3186 T2070 oasc.CoreContainer.load Registering Log Lis= tener [junit4:junit4] 2> 3198 T2070 oasc.CoreContainer.initZooKeeper Zookeeper = client=3D127.0.0.1:47166/solr [junit4:junit4] 2> 3199 T2070 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:47166/solr sessionTimeout=3D8000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@7c108bef [junit4:junit4] 2> 3200 T2115 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:47166 [junit4:junit4] 2> 3200 T2070 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 3201 T2115 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost/127.0.0.1:47166, initiating sessi= on [junit4:junit4] 2> 3201 T2072 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /140.211.11.196:59813 [junit4:junit4] 2> 3202 T2072 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /140.211.11.196:59813 [junit4:junit4] 2> 3204 T2074 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x139c15286180004 with negotiated timeout 8000 for client /14= 0.211.11.196:59813 [junit4:junit4] 2> 3204 T2115 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server localhost/127.0.0.1:47166, sessio= nid =3D 0x139c15286180004, negotiated timeout =3D 8000 [junit4:junit4] 2> 3204 T2116 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@7c108bef name:ZooKeeperConnecti= on Watcher:127.0.0.1:47166/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4:junit4] 2> 3204 T2070 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 3205 T2075 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139c15286180004 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> 3211 T2075 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139c15286180004 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 T2070 oasc.ZkController.createEphemeralLiveNode R= egister node as live in ZooKeeper:/live_nodes/127.0.0.1:46546_solr [junit4:junit4] 2> 3218 T2075 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139c15286180004 type:del= ete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/live_nodes/127.0.0.1:46546_solr Error:KeeperErrorCode =3D NoNode for= /solr/live_nodes/127.0.0.1:46546_solr [junit4:junit4] 2> 3219 T2070 oascc.SolrZkClient.makePath makePath: /live= _nodes/127.0.0.1:46546_solr [junit4:junit4] 2> 3220 T2090 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 3220 T2104 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 3221 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting socketTimeout to: 0 [junit4:junit4] 2> 3221 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting urlScheme to: http:// [junit4:junit4] 2> 3222 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting connTimeout to: 0 [junit4:junit4] 2> 3222 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 3223 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting corePoolSize to: 0 [junit4:junit4] 2> 3223 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 3223 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 3224 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting sizeOfQueue to: -1 [junit4:junit4] 2> 3224 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting fairnessPolicy to: false [junit4:junit4] 2> 3224 T2070 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&= socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 3233 T2070 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 3238 T2070 oasc.CoreContainer.create Creating SolrCore= 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1 [junit4:junit4] 2> 3239 T2070 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:collection1 [junit4:junit4] 2> 3239 T2070 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 3240 T2070 oasc.ZkController.readConfigName Load colle= ction config from:/collections/collection1 [junit4:junit4] 2> 3241 T2070 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/' [junit4:junit4] 2> 3242 T2070 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-= java7/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to cla= ssloader [junit4:junit4] 2> 3243 T2070 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-= java7/solr/build/solr-core/test-files/solr/collection1/lib/README' to class= loader [junit4:junit4] 2> 3282 T2070 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_40 [junit4:junit4] 2> 3333 T2070 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 3435 T2070 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 3442 T2070 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 3574 T2091 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 3588 T2116 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 3588 T2104 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 3588 T2090 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 3913 T2070 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 3921 T2070 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 3925 T2070 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 3935 T2070 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 3939 T2070 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 3943 T2070 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 3944 T2070 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 3945 T2070 oasc.SolrCore. [collection1] Opening = new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4= .x-java7/solr/build/solr-core/test-files/solr/collection1/, dataDir=3D./org= .apache.solr.cloud.BasicDistributedZk2Test-1347568174611/jetty2/ [junit4:junit4] 2> 3946 T2070 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@85bce28 [junit4:junit4] 2> 3947 T2070 oasc.SolrCore.getNewIndexDir WARNING New in= dex directory detected: old=3Dnull new=3D./org.apache.solr.cloud.BasicDistr= ibutedZk2Test-1347568174611/jetty2/index/ [junit4:junit4] 2> 3948 T2070 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-13= 47568174611/jetty2/index' doesn't exist. Creating new index... [junit4:junit4] 2> 3948 T2070 oasc.CachingDirectoryFactory.get return new= directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.= x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistribut= edZk2Test-1347568174611/jetty2/index forceNew:false [junit4:junit4] 2> 3975 T2070 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= -Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDi= stributedZk2Test-1347568174611/jetty2/index lockFactory=3Dorg.apache.lucene= .store.NativeFSLockFactory@23514650; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0= ),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 3976 T2070 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1 [junit4:junit4] 2> 3977 T2070 oasc.SolrCore.initWriters created xml: solr= .XMLResponseWriter [junit4:junit4] 2> 3979 T2070 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 3980 T2070 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe-allfields" [junit4:junit4] 2> 3980 T2070 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 3980 T2070 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "u= niq-fields" [junit4:junit4] 2> 3981 T2070 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 3981 T2070 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 3982 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 3983 T2070 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 3983 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 3984 T2070 oasc.RequestHandlers.initHandlersFromConfig= created dismax: solr.SearchHandler [junit4:junit4] 2> 3984 T2070 oasc.RequestHandlers.initHandlersFromConfig= created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 3985 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 3985 T2070 oasc.RequestHandlers.initHandlersFromConfig= created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 3986 T2070 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 3986 T2070 oasc.RequestHandlers.initHandlersFromConfig= created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 3987 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 3987 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 3988 T2070 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 3988 T2070 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 3989 T2070 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 3989 T2070 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.= SearchHandler [junit4:junit4] 2> 3990 T2070 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 3990 T2070 oasc.RequestHandlers.initHandlersFromConfig= created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 3991 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 3992 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 3994 T2070 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 3998 T2070 oasc.SolrCore.initDeprecatedSupport WARNING= solrconfig.xml uses deprecated , Please update your c= onfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 3999 T2070 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> 4002 T2070 oass.SolrIndexSearcher. Opening Searc= her@1084629 main [junit4:junit4] 2> 4003 T2070 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 4004 T2070 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 4004 T2070 oashc.SpellCheckComponent.inform Initializi= ng spell checkers [junit4:junit4] 2> 4010 T2070 oass.DirectSolrSpellChecker.init init: {nam= e=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLen= gth=3D3} [junit4:junit4] 2> 4054 T2117 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@1084629 main{StandardDirectoryReader(seg= ments_1:1)} [junit4:junit4] 2> 4093 T2091 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 4106 T2090 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 4106 T2116 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 4106 T2104 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 4559 T2070 oasc.CoreContainer.register registering cor= e: collection1 [junit4:junit4] 2> 4559 T2070 oasc.ZkController.register Register shard -= core:collection1 address:http://127.0.0.1:46546/solr shardId:shard2 [junit4:junit4] 2> 4560 T2070 oascc.SolrZkClient.makePath makePath: /coll= ections/collection1/leader_elect/shard2/election [junit4:junit4] 2> 4576 T2075 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139c15286180004 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> 4577 T2070 oasc.ShardLeaderElectionContext.runLeaderPr= ocess Running the leader process. afterExpiration=3Dfalse [junit4:junit4] 2> 4578 T2075 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139c15286180004 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> 4580 T2070 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I am the new leader: http://127.0.0.1:46546/solr/collection1/ [junit4:junit4] 2> 4580 T2070 oascc.SolrZkClient.makePath makePath: /coll= ections/collection1/leaders/shard2 [junit4:junit4] 2> 4583 T2075 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139c15286180004 type:cre= ate cxid:0x4b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 4609 T2091 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 4627 T2116 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 4627 T2104 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 4627 T2090 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 4645 T2070 oasc.ZkController.register We are http://12= 7.0.0.1:46546/solr/collection1/ and leader is http://127.0.0.1:46546/solr/c= ollection1/ [junit4:junit4] 2> 4645 T2070 oasc.ZkController.register No LogReplay nee= ded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:46546/solr [junit4:junit4] 2> 4646 T2070 oasc.ZkController.checkRecovery I am the le= ader, no recovery necessary [junit4:junit4] 2> 4648 T2070 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 4649 T2070 oass.SolrDispatchFilter.init user.dir=3D/us= r/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build= /solr-core/test/J0 [junit4:junit4] 2> 4650 T2070 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() done [junit4:junit4] 2> 4650 T2070 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fo= llowRedirects=3Dfalse [junit4:junit4] 2> 4661 T2070 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 4664 T2070 oejs.AbstractConnector.doStart Started Sock= etConnector@0.0.0.0:23066 [junit4:junit4] 2> 4664 T2070 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 4665 T2070 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr [junit4:junit4] 2> 4665 T2070 oasc.SolrResourceLoader. new SolrReso= urceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/= Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/' [junit4:junit4] 2> 4697 T2070 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() [junit4:junit4] 2> 4698 T2070 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 4698 T2070 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr [junit4:junit4] 2> 4699 T2070 oasc.CoreContainer$Initializer.initialize l= ooking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Te= sts-4.x-java7/solr/build/solr-core/test-files/solr/solr.xml [junit4:junit4] 2> 4699 T2070 oasc.CoreContainer. New CoreContainer= 141250441 [junit4:junit4] 2> 4699 T2070 oasc.CoreContainer.load Loading CoreContain= er using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Te= sts-4.x-java7/solr/build/solr-core/test-files/solr/' [junit4:junit4] 2> 4700 T2070 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/' [junit4:junit4] 2> 4748 T2070 oasc.CoreContainer.load Registering Log Lis= tener [junit4:junit4] 2> 4761 T2070 oasc.CoreContainer.initZooKeeper Zookeeper = client=3D127.0.0.1:47166/solr [junit4:junit4] 2> 4762 T2070 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:47166/solr sessionTimeout=3D8000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@7ccbab10 [junit4:junit4] 2> 4763 T2127 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:47166 [junit4:junit4] 2> 4763 T2070 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 4786 T2127 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost/127.0.0.1:47166, initiating sessi= on [junit4:junit4] 2> 4787 T2072 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /140.211.11.196:25786 [junit4:junit4] 2> 4787 T2072 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /140.211.11.196:25786 [junit4:junit4] 2> 4807 T2074 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x139c15286180005 with negotiated timeout 8000 for client /14= 0.211.11.196:25786 [junit4:junit4] 2> 4807 T2127 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server localhost/127.0.0.1:47166, sessio= nid =3D 0x139c15286180005, negotiated timeout =3D 8000 [junit4:junit4] 2> 4807 T2128 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@7ccbab10 name:ZooKeeperConnecti= on Watcher:127.0.0.1:47166/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4:junit4] 2> 4808 T2070 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 4809 T2075 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139c15286180005 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> 4815 T2075 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139c15286180005 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> 4816 T2070 oasc.ZkController.createEphemeralLiveNode R= egister node as live in ZooKeeper:/live_nodes/127.0.0.1:23066_solr [junit4:junit4] 2> 4817 T2075 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139c15286180005 type:del= ete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/live_nodes/127.0.0.1:23066_solr Error:KeeperErrorCode =3D NoNode for= /solr/live_nodes/127.0.0.1:23066_solr [junit4:junit4] 2> 4818 T2070 oascc.SolrZkClient.makePath makePath: /live= _nodes/127.0.0.1:23066_solr [junit4:junit4] 2> 4827 T2116 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 4827 T2104 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 4827 T2090 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 4828 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting socketTimeout to: 0 [junit4:junit4] 2> 4829 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting urlScheme to: http:// [junit4:junit4] 2> 4829 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting connTimeout to: 0 [junit4:junit4] 2> 4829 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 4830 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting corePoolSize to: 0 [junit4:junit4] 2> 4830 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 4831 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 4831 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting sizeOfQueue to: -1 [junit4:junit4] 2> 4831 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting fairnessPolicy to: false [junit4:junit4] 2> 4832 T2070 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&= socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 4841 T2070 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 4847 T2070 oasc.CoreContainer.create Creating SolrCore= 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1 [junit4:junit4] 2> 4847 T2070 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:collection1 [junit4:junit4] 2> 4848 T2070 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 4848 T2070 oasc.ZkController.readConfigName Load colle= ction config from:/collections/collection1 [junit4:junit4] 2> 4849 T2070 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/' [junit4:junit4] 2> 4850 T2070 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-= java7/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to cla= ssloader [junit4:junit4] 2> 4851 T2070 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-= java7/solr/build/solr-core/test-files/solr/collection1/lib/README' to class= loader [junit4:junit4] 2> 4890 T2070 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_40 [junit4:junit4] 2> 4942 T2070 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 5044 T2070 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 5051 T2070 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 5132 T2091 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 5146 T2116 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 5146 T2090 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 5146 T2128 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 5146 T2104 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 5528 T2070 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 5536 T2070 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 5539 T2070 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 5570 T2070 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 5575 T2070 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 5579 T2070 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 5580 T2070 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 5581 T2070 oasc.SolrCore. [collection1] Opening = new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4= .x-java7/solr/build/solr-core/test-files/solr/collection1/, dataDir=3D./org= .apache.solr.cloud.BasicDistributedZk2Test-1347568174611/jetty3/ [junit4:junit4] 2> 5581 T2070 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@85bce28 [junit4:junit4] 2> 5582 T2070 oasc.SolrCore.getNewIndexDir WARNING New in= dex directory detected: old=3Dnull new=3D./org.apache.solr.cloud.BasicDistr= ibutedZk2Test-1347568174611/jetty3/index/ [junit4:junit4] 2> 5583 T2070 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-13= 47568174611/jetty3/index' doesn't exist. Creating new index... [junit4:junit4] 2> 5584 T2070 oasc.CachingDirectoryFactory.get return new= directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.= x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistribut= edZk2Test-1347568174611/jetty3/index forceNew:false [junit4:junit4] 2> 5589 T2070 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= -Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDi= stributedZk2Test-1347568174611/jetty3/index lockFactory=3Dorg.apache.lucene= .store.NativeFSLockFactory@5ed1d1e9; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0= ),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 5590 T2070 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1 [junit4:junit4] 2> 5590 T2070 oasc.SolrCore.initWriters created xml: solr= .XMLResponseWriter [junit4:junit4] 2> 5592 T2070 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 5593 T2070 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe-allfields" [junit4:junit4] 2> 5593 T2070 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 5594 T2070 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "u= niq-fields" [junit4:junit4] 2> 5594 T2070 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 5595 T2070 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 5595 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 5596 T2070 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 5596 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 5597 T2070 oasc.RequestHandlers.initHandlersFromConfig= created dismax: solr.SearchHandler [junit4:junit4] 2> 5598 T2070 oasc.RequestHandlers.initHandlersFromConfig= created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 5598 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 5599 T2070 oasc.RequestHandlers.initHandlersFromConfig= created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 5599 T2070 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 5599 T2070 oasc.RequestHandlers.initHandlersFromConfig= created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 5600 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 5601 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 5601 T2070 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 5602 T2070 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 5602 T2070 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 5603 T2070 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.= SearchHandler [junit4:junit4] 2> 5603 T2070 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 5604 T2070 oasc.RequestHandlers.initHandlersFromConfig= created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 5604 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 5605 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 5608 T2070 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 5611 T2070 oasc.SolrCore.initDeprecatedSupport WARNING= solrconfig.xml uses deprecated , Please update your c= onfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 5613 T2070 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> 5616 T2070 oass.SolrIndexSearcher. Opening Searc= her@7f294d00 main [junit4:junit4] 2> 5617 T2070 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 5617 T2070 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 5618 T2070 oashc.SpellCheckComponent.inform Initializi= ng spell checkers [junit4:junit4] 2> 5624 T2070 oass.DirectSolrSpellChecker.init init: {nam= e=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLen= gth=3D3} [junit4:junit4] 2> 5669 T2129 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@7f294d00 main{StandardDirectoryReader(se= gments_1:1)} [junit4:junit4] 2> 6154 T2091 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 6189 T2128 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 6189 T2104 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 6189 T2090 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 6189 T2116 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 6677 T2070 oasc.CoreContainer.register registering cor= e: collection1 [junit4:junit4] 2> 6678 T2070 oasc.ZkController.register Register shard -= core:collection1 address:http://127.0.0.1:23066/solr shardId:shard1 [junit4:junit4] 2> 6681 T2070 oasc.ZkController.register We are http://12= 7.0.0.1:23066/solr/collection1/ and leader is http://127.0.0.1:49212/solr/c= ollection1/ [junit4:junit4] 2> 6681 T2070 oasc.ZkController.register No LogReplay nee= ded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:23066/solr [junit4:junit4] 2> 6681 T2070 oasc.ZkController.checkRecovery Core needs = to recover:collection1 [junit4:junit4] 2> 6682 T2070 oasu.DefaultSolrCoreState.doRecovery Runnin= g recovery - first canceling any ongoing recovery [junit4:junit4] 2> ASYNC NEW_CORE C214 name=3Dcollection1 org.apache.sol= r.core.SolrCore@9205b85 url=3Dhttp://127.0.0.1:23066/solr/collection1 node= =3D127.0.0.1:23066_solr [junit4:junit4] 2> 6683 T2130 C214 P23066 oasc.RecoveryStrategy.run Start= ing recovery process. core=3Dcollection1 recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 6683 T2070 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 6684 T2130 C214 P23066 oasc.RecoveryStrategy.doRecover= y ###### startupVersions=3D[] [junit4:junit4] 2> 6685 T2070 oass.SolrDispatchFilter.init user.dir=3D/us= r/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build= /solr-core/test/J0 [junit4:junit4] 2> 6685 T2070 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() done [junit4:junit4] 2> 6685 T2070 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fo= llowRedirects=3Dfalse [junit4:junit4] 2> 6696 T2070 oejs.Server.doStart jetty-8.1.2.v20120308 [junit4:junit4] 2> 6697 T2130 C214 P23066 oascsi.HttpClientUtil.createCli= ent Creating new http client, config:maxConnections=3D128&maxConnectionsPer= Host=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 6698 T2091 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 6699 T2070 oejs.AbstractConnector.doStart Started Sock= etConnector@0.0.0.0:46311 [junit4:junit4] 2> 6699 T2070 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 6700 T2070 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr [junit4:junit4] 2> 6700 T2070 oasc.SolrResourceLoader. new SolrReso= urceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/= Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/' [junit4:junit4] 2> 6713 T2128 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 6713 T2104 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 6713 T2090 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 6713 T2116 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 6731 T2070 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() [junit4:junit4] 2> 6732 T2070 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 6732 T2070 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr [junit4:junit4] 2> 6732 T2070 oasc.CoreContainer$Initializer.initialize l= ooking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Te= sts-4.x-java7/solr/build/solr-core/test-files/solr/solr.xml [junit4:junit4] 2> 6733 T2070 oasc.CoreContainer. New CoreContainer= 1127898839 [junit4:junit4] 2> 6733 T2070 oasc.CoreContainer.load Loading CoreContain= er using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Te= sts-4.x-java7/solr/build/solr-core/test-files/solr/' [junit4:junit4] 2> 6734 T2070 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/' [junit4:junit4] 2> 6759 T2070 oasc.CoreContainer.load Registering Log Lis= tener [junit4:junit4] 2> 6771 T2070 oasc.CoreContainer.initZooKeeper Zookeeper = client=3D127.0.0.1:47166/solr [junit4:junit4] 2> 6772 T2070 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:47166/solr sessionTimeout=3D8000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@a57d4ca [junit4:junit4] 2> 6772 T2140 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:47166 [junit4:junit4] 2> 6772 T2070 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 6773 T2140 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost/127.0.0.1:47166, initiating sessi= on [junit4:junit4] 2> 6774 T2072 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /140.211.11.196:38892 [junit4:junit4] 2> 6774 T2072 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /140.211.11.196:38892 [junit4:junit4] 2> 6775 T2074 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x139c15286180006 with negotiated timeout 8000 for client /14= 0.211.11.196:38892 [junit4:junit4] 2> 6775 T2140 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server localhost/127.0.0.1:47166, sessio= nid =3D 0x139c15286180006, negotiated timeout =3D 8000 [junit4:junit4] 2> 6775 T2141 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@a57d4ca name:ZooKeeperConnectio= n Watcher:127.0.0.1:47166/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 6776 T2070 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 6776 T2075 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139c15286180006 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> 6777 T2075 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139c15286180006 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> 6778 T2070 oasc.ZkController.createEphemeralLiveNode R= egister node as live in ZooKeeper:/live_nodes/127.0.0.1:46311_solr [junit4:junit4] 2> 6779 T2075 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139c15286180006 type:del= ete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/live_nodes/127.0.0.1:46311_solr Error:KeeperErrorCode =3D NoNode for= /solr/live_nodes/127.0.0.1:46311_solr [junit4:junit4] 2> 6780 T2070 oascc.SolrZkClient.makePath makePath: /live= _nodes/127.0.0.1:46311_solr [junit4:junit4] 2> 6781 T2128 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 6781 T2116 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 6781 T2104 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 6781 T2090 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 6782 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting socketTimeout to: 0 [junit4:junit4] 2> 6782 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting urlScheme to: http:// [junit4:junit4] 2> 6783 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting connTimeout to: 0 [junit4:junit4] 2> 6783 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 6783 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting corePoolSize to: 0 [junit4:junit4] 2> 6784 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 6784 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 6785 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting sizeOfQueue to: -1 [junit4:junit4] 2> 6785 T2070 oashc.HttpShardHandlerFactory.getParameter = Setting fairnessPolicy to: false [junit4:junit4] 2> 6785 T2070 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&= socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 6794 T2070 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 6799 T2070 oasc.CoreContainer.create Creating SolrCore= 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/L= ucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1 [junit4:junit4] 2> 6800 T2070 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:collection1 [junit4:junit4] 2> 6800 T2070 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 6801 T2070 oasc.ZkController.readConfigName Load colle= ction config from:/collections/collection1 [junit4:junit4] 2> 6802 T2070 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-S= olr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/' [junit4:junit4] 2> 6803 T2070 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-= java7/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to cla= ssloader [junit4:junit4] 2> 6804 T2070 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-= java7/solr/build/solr-core/test-files/solr/collection1/lib/README' to class= loader [junit4:junit4] 2> 6842 T2070 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_40 [junit4:junit4] 2> 6895 T2070 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 6996 T2070 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 7001 T2070 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 7474 T2070 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 7484 T2070 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 7487 T2070 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 7498 T2070 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 7503 T2070 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 7508 T2070 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 7512 T2070 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 7513 T2070 oasc.SolrCore. [collection1] Opening = new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4= .x-java7/solr/build/solr-core/test-files/solr/collection1/, dataDir=3D./org= .apache.solr.cloud.BasicDistributedZk2Test-1347568174611/jetty4/ [junit4:junit4] 2> 7514 T2070 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@85bce28 [junit4:junit4] 2> 7515 T2070 oasc.SolrCore.getNewIndexDir WARNING New in= dex directory detected: old=3Dnull new=3D./org.apache.solr.cloud.BasicDistr= ibutedZk2Test-1347568174611/jetty4/index/ [junit4:junit4] 2> 7516 T2070 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-13= 47568174611/jetty4/index' doesn't exist. Creating new index... [junit4:junit4] 2> 7516 T2070 oasc.CachingDirectoryFactory.get return new= directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.= x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistribut= edZk2Test-1347568174611/jetty4/index forceNew:false [junit4:junit4] 2> 7548 T2070 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= -Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDi= stributedZk2Test-1347568174611/jetty4/index lockFactory=3Dorg.apache.lucene= .store.NativeFSLockFactory@2d6a492d; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0= ),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 7549 T2070 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1 [junit4:junit4] 2> 7550 T2070 oasc.SolrCore.initWriters created xml: solr= .XMLResponseWriter [junit4:junit4] 2> 7552 T2070 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 7553 T2070 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe-allfields" [junit4:junit4] 2> 7553 T2070 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 7554 T2070 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "u= niq-fields" [junit4:junit4] 2> 7554 T2070 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 7555 T2070 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 7555 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 7556 T2070 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 7556 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 7557 T2070 oasc.RequestHandlers.initHandlersFromConfig= created dismax: solr.SearchHandler [junit4:junit4] 2> 7558 T2070 oasc.RequestHandlers.initHandlersFromConfig= created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 7558 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 7559 T2070 oasc.RequestHandlers.initHandlersFromConfig= created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 7559 T2070 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 7559 T2070 oasc.RequestHandlers.initHandlersFromConfig= created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 7560 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 7561 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 7561 T2070 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 7562 T2070 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 7562 T2070 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 7563 T2070 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.= SearchHandler [junit4:junit4] 2> 7563 T2070 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 7564 T2070 oasc.RequestHandlers.initHandlersFromConfig= created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 7564 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 7565 T2070 oasc.RequestHandlers.initHandlersFromConfig= created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 7568 T2070 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 7572 T2070 oasc.SolrCore.initDeprecatedSupport WARNING= solrconfig.xml uses deprecated , Please update your c= onfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 7573 T2070 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> 7577 T2070 oass.SolrIndexSearcher. Opening Searc= her@44d82f8f main [junit4:junit4] 2> 7578 T2070 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 7579 T2070 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 7579 T2070 oashc.SpellCheckComponent.inform Initializi= ng spell checkers [junit4:junit4] 2> 7595 T2070 oass.DirectSolrSpellChecker.init init: {nam= e=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLen= gth=3D3} [junit4:junit4] 2> 7649 T2142 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@44d82f8f main{StandardDirectoryReader(se= gments_1:1)} [junit4:junit4] 2> 7718 T2091 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 7722 T2141 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 7722 T2090 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 7722 T2104 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 7722 T2116 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 7722 T2128 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 8155 T2070 oasc.CoreContainer.register registering cor= e: collection1 [junit4:junit4] 2> 8156 T2070 oasc.ZkController.register Register shard -= core:collection1 address:http://127.0.0.1:46311/solr shardId:shard2 [junit4:junit4] 2> 8160 T2070 oasc.ZkController.register We are http://12= 7.0.0.1:46311/solr/collection1/ and leader is http://127.0.0.1:46546/solr/c= ollection1/ [junit4:junit4] 2> 8161 T2070 oasc.ZkController.register No LogReplay nee= ded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:46311/solr [junit4:junit4] 2> 8161 T2070 oasc.ZkController.checkRecovery Core needs = to recover:collection1 [junit4:junit4] 2> 8161 T2070 oasu.DefaultSolrCoreState.doRecovery Runnin= g recovery - first canceling any ongoing recovery [junit4:junit4] 2> ASYNC NEW_CORE C215 name=3Dcollection1 org.apache.sol= r.core.SolrCore@57fbc0d8 url=3Dhttp://127.0.0.1:46311/solr/collection1 node= =3D127.0.0.1:46311_solr [junit4:junit4] 2> 8162 T2143 C215 P46311 oasc.RecoveryStrategy.run Start= ing recovery process. core=3Dcollection1 recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 8162 T2070 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 8163 T2143 C215 P46311 oasc.RecoveryStrategy.doRecover= y ###### startupVersions=3D[] [junit4:junit4] 2> 8163 T2070 oass.SolrDispatchFilter.init user.dir=3D/us= r/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build= /solr-core/test/J0 [junit4:junit4] 2> 8164 T2070 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() done [junit4:junit4] 2> 8164 T2143 C215 P46311 oascsi.HttpClientUtil.createCli= ent Creating new http client, config:maxConnections=3D128&maxConnectionsPer= Host=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 8164 T2070 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fo= llowRedirects=3Dfalse [junit4:junit4] 2> 8181 T2070 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:47166/solr sessionTimeout=3D10000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@2a822be6 [junit4:junit4] 2> 8182 T2144 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:47166 [junit4:junit4] 2> 8182 T2070 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 8183 T2144 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost/127.0.0.1:47166, initiating sessi= on [junit4:junit4] 2> 8183 T2072 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /140.211.11.196:34322 [junit4:junit4] 2> 8184 T2072 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /140.211.11.196:34322 [junit4:junit4] 2> 8185 T2074 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x139c15286180007 with negotiated timeout 10000 for client /1= 40.211.11.196:34322 [junit4:junit4] 2> 8185 T2144 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server localhost/127.0.0.1:47166, sessio= nid =3D 0x139c15286180007, negotiated timeout =3D 10000 [junit4:junit4] 2> 8186 T2145 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@2a822be6 name:ZooKeeperConnecti= on Watcher:127.0.0.1:47166/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4:junit4] 2> 8186 T2070 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 8187 T2070 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 8188 T2070 oasc.ChaosMonkey.monkeyLog monkey: init - e= xpire sessions:true cause connection loss:false [junit4:junit4] 2> 8189 T2070 oascsi.HttpClientUtil.createClient Creating= new http client, config: [junit4:junit4] 2> 8196 T2070 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> ASYNC NEW_CORE C216 name=3Dcollection1 org.apache.sol= r.core.SolrCore@2374475e url=3Dhttp://127.0.0.1:14282/solr/collection1 node= =3D127.0.0.1:14282_solr [junit4:junit4] 2> 8208 T2082 C216 P14282 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= -Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDi= stributedZk2Test-1347568174611/control/data/index lockFactory=3Dorg.apache.= lucene.store.NativeFSLockFactory@1624f38e; maxCacheMB=3D48.0 maxMergeSizeMB= =3D4.0),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 8209 T2082 C216 P14282 oasc.SolrDeletionPolicy.updateC= ommits newest commit =3D 1 [junit4:junit4] 2> 8214 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[1 (1413027654861324288)]} 0 9 [junit4:junit4] 2> 8215 T2070 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:47166/solr sessionTimeout=3D10000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@60e3c838 [junit4:junit4] 2> 8216 T2146 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:47166 [junit4:junit4] 2> 8216 T2070 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 8217 T2146 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost/127.0.0.1:47166, initiating sessi= on [junit4:junit4] 2> 8217 T2072 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /140.211.11.196:43872 [junit4:junit4] 2> 8218 T2072 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /140.211.11.196:43872 [junit4:junit4] 2> 8219 T2074 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x139c15286180008 with negotiated timeout 10000 for client /1= 40.211.11.196:43872 [junit4:junit4] 2> 8219 T2146 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server localhost/127.0.0.1:47166, sessio= nid =3D 0x139c15286180008, negotiated timeout =3D 10000 [junit4:junit4] 2> 8220 T2147 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@60e3c838 name:ZooKeeperConnecti= on Watcher:127.0.0.1:47166/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4:junit4] 2> 8220 T2070 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 8221 T2070 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 8234 T2091 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> ASYNC NEW_CORE C217 name=3Dcollection1 org.apache.sol= r.core.SolrCore@34203071 url=3Dhttp://127.0.0.1:49212/solr/collection1 node= =3D127.0.0.1:49212_solr [junit4:junit4] 2> 8240 T2097 C217 P49212 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= -Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDi= stributedZk2Test-1347568174611/jetty1/index lockFactory=3Dorg.apache.lucene= .store.NativeFSLockFactory@15a8ef6; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0)= ,segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 8240 T2097 C217 P49212 oasc.SolrDeletionPolicy.updateC= ommits newest commit =3D 1 [junit4:junit4] 2> 8247 T2141 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 8247 T2128 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 8247 T2104 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 8247 T2090 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 8247 T2147 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 8247 T2145 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 8247 T2116 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> C214_STATE=3Dcoll:collection1 core:collection1 props:= {shard=3Dshard1, roles=3Dnull, state=3Drecovering, core=3Dcollection1, coll= ection=3Dcollection1, node_name=3D127.0.0.1:23066_solr, base_url=3Dhttp://1= 27.0.0.1:23066/solr} [junit4:junit4] 2> 8257 T2120 C214 P23066 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= -Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDi= stributedZk2Test-1347568174611/jetty3/index lockFactory=3Dorg.apache.lucene= .store.NativeFSLockFactory@5ed1d1e9; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0= ),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 8258 T2120 C214 P23066 oasc.SolrDeletionPolicy.updateC= ommits newest commit =3D 1 [junit4:junit4] 2> 8262 T2120 C214 P23066 REQ /update {update.distrib=3DF= ROMLEADER&distrib.from=3Dhttp://127.0.0.1:49212/solr/collection1/&wt=3Djava= bin&version=3D2} status=3D0 QTime=3D9=20 [junit4:junit4] 2> 8262 T2097 C217 P49212 REQ /update {distrib.from=3Dhtt= p://127.0.0.1:46546/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavabi= n&version=3D2} status=3D0 QTime=3D26=20 [junit4:junit4] 2> ASYNC NEW_CORE C218 name=3Dcollection1 org.apache.sol= r.core.SolrCore@39044efa url=3Dhttp://127.0.0.1:46546/solr/collection1 node= =3D127.0.0.1:46546_solr [junit4:junit4] 2> 8263 T2109 C218 P46546 /update {wt=3Djavabin&version= =3D2} {add=3D[1]} 0 33 [junit4:junit4] 2> 8264 T2070 oasc.AbstractDistribZkTestBase.waitForRecov= eriesToFinish Wait for recoveries to finish - collection: collection1 failO= nTimeout:true timeout (sec):1440 [junit4:junit4] 2> 8265 T2070 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 9267 T2070 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 10269 T2070 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 11271 T2070 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 12273 T2070 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 13275 T2070 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 13708 T2130 C214 P23066 oasc.RecoveryStrategy.doRecove= ry Attempting to PeerSync from http://127.0.0.1:49212/solr/collection1/ cor= e=3Dcollection1 - recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 13708 T2130 C214 P23066 oasu.PeerSync.sync PeerSync: c= ore=3Dcollection1 url=3Dhttp://127.0.0.1:23066/solr START replicas=3D[http:= //127.0.0.1:49212/solr/collection1/] nUpdates=3D100 [junit4:junit4] 2> 13709 T2130 C214 P23066 oasc.RecoveryStrategy.doRecove= ry PeerSync Recovery was not successful - trying replication. core=3Dcollec= tion1 [junit4:junit4] 2> 13709 T2130 C214 P23066 oasc.RecoveryStrategy.doRecove= ry Starting Replication Recovery. core=3Dcollection1 [junit4:junit4] 2> 13709 T2130 C214 P23066 oasc.RecoveryStrategy.doRecove= ry Begin buffering updates. core=3Dcollection1 [junit4:junit4] 2> 13710 T2130 C214 P23066 oasu.UpdateLog.bufferUpdates S= tarting to buffer updates. FSUpdateLog{state=3DACTIVE, tlog=3Dtlog{file=3D.= /org.apache.solr.cloud.BasicDistributedZk2Test-1347568174611/jetty3/tlog/tl= og.0000000000000000000 refcount=3D1}} [junit4:junit4] 2> 13710 T2130 C214 P23066 oasc.RecoveryStrategy.replicat= e Attempting to replicate from http://127.0.0.1:49212/solr/collection1/. co= re=3Dcollection1 [junit4:junit4] 2> 13710 T2130 C214 P23066 oascsi.HttpClientUtil.createCl= ient Creating new http client, config:maxConnections=3D128&maxConnectionsPe= rHost=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 13711 T2098 C217 P49212 REQ /get {distrib=3Dfalse&qt= =3D/get&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 13721 T2099 C217 P49212 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 13848 T2099 C217 P49212 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= -Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDi= stributedZk2Test-1347568174611/jetty1/index lockFactory=3Dorg.apache.lucene= .store.NativeFSLockFactory@15a8ef6; maxCacheMB=3D48.0 maxMergeSizeMB=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= -Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDi= stributedZk2Test-1347568174611/jetty1/index lockFactory=3Dorg.apache.lucene= .store.NativeFSLockFactory@15a8ef6; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0)= ,segFN=3Dsegments_2,generation=3D2,filenames=3D[_0_MockSep_0.pos, _0_TestBl= oomFilteredLucene40Postings_0.prx, _0_Pulsing40_0.prx, _0_Pulsing40_0.frq, = _0_MockSep_0.skp, _0_Pulsing40_0.tip, _0_TestBloomFilteredLucene40Postings_= 0.frq, _0.si, _0_MockSep_0.doc, _0_TestBloomFilteredLucene40Postings_0.tim,= _0_TestBloomFilteredLucene40Postings_0.blm, _0_TestBloomFilteredLucene40Po= stings_0.tip, _0.fnm, _0_Pulsing40_0.tim, _0_nrm.cfs, _0_MockRandom_0.skp, = _0_MockSep_0.tib, _0_MockRandom_0.tip, _0_MockRandom_0.frq, _0_MockRandom_0= .sd, _0_MockRandom_0.doc, _0_nrm.cfe, _0_MockRandom_0.pos, _0_MockSep_0.frq= , segments_2, _0.fdx, _0_MockSep_0.tii, _0_MockSep_0.pyl, _0_MockRandom_0.p= yl, _0_MockRandom_0.tim, _0.fdt] [junit4:junit4] 2> 13849 T2099 C217 P49212 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 2 [junit4:junit4] 2> 13862 T2099 C217 P49212 oass.SolrIndexSearcher. = Opening Searcher@59a5b2b7 main [junit4:junit4] 2> 13863 T2099 C217 P49212 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 13864 T2105 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@59a5b2b7 main{StandardDirectoryReader(s= egments_2:3 _0(4.0.0.2):C1)} [junit4:junit4] 2> 13865 T2099 C217 P49212 /update {waitSearcher=3Dtrue&c= ommit=3Dtrue&commit_end_point=3Dtrue&wt=3Djavabin&softCommit=3Dfalse&versio= n=3D2} {commit=3D} 0 144 [junit4:junit4] 2> 13866 T2130 C214 P23066 oash.ReplicationHandler.inform= Commits will be reserved for 10000 [junit4:junit4] 2> 13866 T2130 C214 P23066 oash.SnapPuller. No val= ue set for 'pollInterval'. Timer Task not started. [junit4:junit4] 2> 13868 T2100 C217 P49212 oash.ReplicationHandler.inform= Commits will be reserved for 10000 [junit4:junit4] 2> 13868 T2100 C217 P49212 REQ /replication {command=3Din= dexversion&wt=3Djavabin&qt=3D/replication&version=3D2} status=3D0 QTime=3D1= =20 [junit4:junit4] 2> 13869 T2130 C214 P23066 oash.SnapPuller.fetchLatestInd= ex Master's generation: 2 [junit4:junit4] 2> 13869 T2130 C214 P23066 oash.SnapPuller.fetchLatestInd= ex Slave's generation: 1 [junit4:junit4] 2> 13869 T2130 C214 P23066 oash.SnapPuller.fetchLatestInd= ex Starting replication process [junit4:junit4] 2> 13873 T2100 C217 P49212 REQ /replication {command=3Dfi= lelist&generation=3D2&wt=3Djavabin&qt=3D/replication&version=3D2} status=3D= 0 QTime=3D2=20 [junit4:junit4] 2> 13874 T2130 C214 P23066 oash.SnapPuller.fetchLatestInd= ex Number of files in latest index in master: 31 [junit4:junit4] 2> 13880 T2130 C214 P23066 oash.SnapPuller.fetchLatestInd= ex Starting download to ./org.apache.solr.cloud.BasicDistributedZk2Test-134= 7568174611/jetty3/index.20120913202948482 fullCopy=3Dtrue [junit4:junit4] 2> 13883 T2100 C217 P49212 REQ /replication {file=3D_0_Mo= ckSep_0.pos&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/repl= ication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 13888 T2100 C217 P49212 REQ /replication {file=3D_0_Te= stBloomFilteredLucene40Postings_0.prx&command=3Dfilecontent&checksum=3Dtrue= &generation=3D2&qt=3D/replication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 13892 T2100 C217 P49212 REQ /replication {file=3D_0_Pu= lsing40_0.prx&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/re= plication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 13896 T2100 C217 P49212 REQ /replication {file=3D_0_Pu= lsing40_0.frq&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/re= plication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 13900 T2100 C217 P49212 REQ /replication {file=3D_0_Mo= ckSep_0.skp&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/repl= ication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 13901 T2130 C214 P23066 oash.SnapPuller$FileFetcher.fe= tchPackets WARNING No content recieved for file: {name=3D_0_MockSep_0.skp, = lastmodified=3D1347568188000, size=3D0} [junit4:junit4] 2> 13905 T2100 C217 P49212 REQ /replication {file=3D_0_Pu= lsing40_0.tip&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/re= plication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 13909 T2100 C217 P49212 REQ /replication {file=3D_0_Te= stBloomFilteredLucene40Postings_0.frq&command=3Dfilecontent&checksum=3Dtrue= &generation=3D2&qt=3D/replication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 13913 T2100 C217 P49212 REQ /replication {file=3D_0_Mo= ckSep_0.doc&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/repl= ication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 13917 T2100 C217 P49212 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> 13921 T2100 C217 P49212 REQ /replication {file=3D_0_Te= stBloomFilteredLucene40Postings_0.tim&command=3Dfilecontent&checksum=3Dtrue= &generation=3D2&qt=3D/replication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 13925 T2100 C217 P49212 REQ /replication {file=3D_0_Te= stBloomFilteredLucene40Postings_0.tip&command=3Dfilecontent&checksum=3Dtrue= &generation=3D2&qt=3D/replication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 13929 T2100 C217 P49212 REQ /replication {file=3D_0_Te= stBloomFilteredLucene40Postings_0.blm&command=3Dfilecontent&checksum=3Dtrue= &generation=3D2&qt=3D/replication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 13933 T2100 C217 P49212 REQ /replication {file=3D_0_Pu= lsing40_0.tim&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/re= plication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 13937 T2100 C217 P49212 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> 13941 T2100 C217 P49212 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> 13945 T2100 C217 P49212 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> 13946 T2130 C214 P23066 oash.SnapPuller$FileFetcher.fe= tchPackets WARNING No content recieved for file: {name=3D_0_MockRandom_0.sk= p, lastmodified=3D1347568188000, size=3D0} [junit4:junit4] 2> 13949 T2100 C217 P49212 REQ /replication {file=3D_0_Mo= ckSep_0.tib&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/repl= ication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 13953 T2100 C217 P49212 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> 13957 T2100 C217 P49212 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> 13961 T2100 C217 P49212 REQ /replication {file=3D_0_Mo= ckRandom_0.tip&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/r= eplication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 13965 T2100 C217 P49212 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> 13993 T2100 C217 P49212 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> 13998 T2100 C217 P49212 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> 14003 T2100 C217 P49212 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> 14007 T2100 C217 P49212 REQ /replication {file=3D_0_Mo= ckSep_0.frq&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/repl= ication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 14011 T2100 C217 P49212 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> 14015 T2100 C217 P49212 REQ /replication {file=3D_0_Mo= ckSep_0.tii&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/repl= ication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 14019 T2100 C217 P49212 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> 14020 T2130 C214 P23066 oash.SnapPuller$FileFetcher.fe= tchPackets WARNING No content recieved for file: {name=3D_0_MockRandom_0.py= l, lastmodified=3D1347568188000, size=3D0} [junit4:junit4] 2> 14023 T2100 C217 P49212 REQ /replication {file=3D_0_Mo= ckSep_0.pyl&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/repl= ication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 14024 T2130 C214 P23066 oash.SnapPuller$FileFetcher.fe= tchPackets WARNING No content recieved for file: {name=3D_0_MockSep_0.pyl, = lastmodified=3D1347568188000, size=3D0} [junit4:junit4] 2> 14027 T2100 C217 P49212 REQ /replication {file=3D_0_Mo= ckRandom_0.tim&command=3Dfilecontent&checksum=3Dtrue&generation=3D2&qt=3D/r= eplication&wt=3Dfilestream} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 14031 T2100 C217 P49212 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> 14032 T2130 C214 P23066 oash.SnapPuller.fetchLatestInd= ex Total time taken for download : 0 secs [junit4:junit4] 2> 14033 T2130 C214 P23066 oash.SnapPuller.modifyIndexPro= ps New index installed. Updating index properties... index=3Dindex.20120913= 202948482 [junit4:junit4] 2> 14034 T2130 C214 P23066 oasu.DefaultSolrCoreState.newI= ndexWriter Creating new IndexWriter... [junit4:junit4] 2> 14034 T2130 C214 P23066 oasu.DefaultSolrCoreState.newI= ndexWriter Waiting until IndexWriter is unused... core=3Dcollection1 [junit4:junit4] 2> 14034 T2130 C214 P23066 oasu.DefaultSolrCoreState.newI= ndexWriter Rollback old IndexWriter... core=3Dcollection1 [junit4:junit4] 2> 14038 T2130 C214 P23066 oasc.SolrCore.getNewIndexDir W= ARNING New index directory detected: old=3D./org.apache.solr.cloud.BasicDis= tributedZk2Test-1347568174611/jetty3/index/ new=3D./org.apache.solr.cloud.B= asicDistributedZk2Test-1347568174611/jetty3/index.20120913202948482 [junit4:junit4] 2> 14039 T2130 C214 P23066 oasc.CachingDirectoryFactory.g= et return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-= Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.B= asicDistributedZk2Test-1347568174611/jetty3/index.20120913202948482 forceNe= w:true [junit4:junit4] 2> 14048 T2130 C214 P23066 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= -Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDi= stributedZk2Test-1347568174611/jetty3/index.20120913202948482 lockFactory= =3Dorg.apache.lucene.store.NativeFSLockFactory@41560af5; maxCacheMB=3D48.0 = maxMergeSizeMB=3D4.0),segFN=3Dsegments_2,generation=3D2,filenames=3D[_0_Moc= kSep_0.pos, _0_TestBloomFilteredLucene40Postings_0.prx, _0_Pulsing40_0.prx,= _0_Pulsing40_0.frq, _0_MockSep_0.skp, _0_Pulsing40_0.tip, _0_TestBloomFilt= eredLucene40Postings_0.frq, _0_MockSep_0.doc, _0.si, _0_TestBloomFilteredLu= cene40Postings_0.tim, _0_TestBloomFilteredLucene40Postings_0.tip, _0_TestBl= oomFilteredLucene40Postings_0.blm, _0.fnm, _0_Pulsing40_0.tim, _0_nrm.cfs, = _0_MockRandom_0.skp, _0_MockSep_0.tib, _0_MockRandom_0.tip, _0_MockRandom_0= .frq, _0_MockRandom_0.sd, _0_MockRandom_0.doc, _0_nrm.cfe, _0_MockRandom_0.= pos, _0_MockSep_0.frq, segments_2, _0.fdx, _0_MockSep_0.tii, _0_MockRandom_= 0.pyl, _0_MockSep_0.pyl, _0_MockRandom_0.tim, _0.fdt] [junit4:junit4] 2> 14049 T2130 C214 P23066 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 2 [junit4:junit4] 2> 14049 T2130 C214 P23066 oasu.DefaultSolrCoreState.newI= ndexWriter New IndexWriter is ready to be used. [junit4:junit4] 2> 14052 T2130 C214 P23066 oass.SolrIndexSearcher. = Opening Searcher@2145347c main [junit4:junit4] 2> 14054 T2129 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@2145347c main{StandardDirectoryReader(s= egments_2:3:nrt _0(4.0.0.2):C1)} [junit4:junit4] 2> 14054 T2129 oasc.CachingDirectoryFactory.close Closing= directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-ja= va7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk= 2Test-1347568174611/jetty3/index [junit4:junit4] 2> 14054 T2129 oash.SnapPuller$2.onClose removing old ind= ex directory ./org.apache.solr.cloud.BasicDistributedZk2Test-1347568174611/= jetty3/index [junit4:junit4] 2> 14056 T2130 C214 P23066 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 14057 T2130 C214 P23066 oass.SolrIndexSearcher. = Opening Searcher@2412d651 main [junit4:junit4] 2> 14057 T2130 C214 P23066 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 14058 T2129 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@2412d651 main{StandardDirectoryReader(s= egments_2:3:nrt _0(4.0.0.2):C1)} [junit4:junit4] 2> 14059 T2130 C214 P23066 oasc.RecoveryStrategy.replay R= eplaying buffered documents. core=3Dcollection1 [junit4:junit4] 2> 14059 T2152 C214 P23066 oasu.UpdateLog$LogReplayer.doR= eplay WARNING Starting log replay tlog{file=3D./org.apache.solr.cloud.Basic= DistributedZk2Test-1347568174611/jetty3/tlog/tlog.0000000000000000000 refco= unt=3D2} active=3Dtrue starting pos=3D430 [junit4:junit4] 2> 14059 T2152 C214 P23066 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D2,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 14061 T2152 C214 P23066 oass.SolrIndexSearcher. = Opening Searcher@399f76f5 main [junit4:junit4] 2> 14061 T2152 C214 P23066 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 14062 T2129 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@399f76f5 main{StandardDirectoryReader(s= egments_2:3:nrt _0(4.0.0.2):C1)} [junit4:junit4] 2> 14062 T2152 C214 P23066 oasu.UpdateLog$LogReplayer.run= WARNING Log replay finished. recoveryInfo=3DRecoveryInfo{adds=3D0 deletes= =3D0 deleteByQuery=3D0 errors=3D0 positionOfStart=3D430} [junit4:junit4] 2> 14063 T2130 C214 P23066 oasc.RecoveryStrategy.doRecove= ry Replication Recovery was successful - registering as Active. core=3Dcoll= ection1 [junit4:junit4] 2> 14064 T2130 C214 P23066 oasc.RecoveryStrategy.doRecove= ry Finished recovery process. core=3Dcollection1 [junit4:junit4] 2> 14274 T2091 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 14277 T2070 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 14287 T2141 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 14287 T2116 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 14287 T2145 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 14287 T2147 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 14287 T2104 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 14287 T2128 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 14287 T2090 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> C215_STATE=3Dcoll:collection1 core:collection1 props:= {shard=3Dshard2, roles=3Dnull, state=3Drecovering, core=3Dcollection1, coll= ection=3Dcollection1, node_name=3D127.0.0.1:46311_solr, base_url=3Dhttp://1= 27.0.0.1:46311/solr} [junit4:junit4] 2> 15184 T2143 C215 P46311 oasc.RecoveryStrategy.doRecove= ry Attempting to PeerSync from http://127.0.0.1:46546/solr/collection1/ cor= e=3Dcollection1 - recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 15185 T2143 C215 P46311 oasu.PeerSync.sync PeerSync: c= ore=3Dcollection1 url=3Dhttp://127.0.0.1:46311/solr START replicas=3D[http:= //127.0.0.1:46546/solr/collection1/] nUpdates=3D100 [junit4:junit4] 2> 15185 T2143 C215 P46311 oasc.RecoveryStrategy.doRecove= ry PeerSync Recovery was not successful - trying replication. core=3Dcollec= tion1 [junit4:junit4] 2> 15185 T2143 C215 P46311 oasc.RecoveryStrategy.doRecove= ry Starting Replication Recovery. core=3Dcollection1 [junit4:junit4] 2> 15185 T2143 C215 P46311 oasc.RecoveryStrategy.doRecove= ry Begin buffering updates. core=3Dcollection1 [junit4:junit4] 2> 15185 T2143 C215 P46311 oasu.UpdateLog.bufferUpdates S= tarting to buffer updates. FSUpdateLog{state=3DACTIVE, tlog=3Dnull} [junit4:junit4] 2> 15186 T2143 C215 P46311 oasc.RecoveryStrategy.replicat= e Attempting to replicate from http://127.0.0.1:46546/solr/collection1/. co= re=3Dcollection1 [junit4:junit4] 2> 15186 T2143 C215 P46311 oascsi.HttpClientUtil.createCl= ient Creating new http client, config:maxConnections=3D128&maxConnectionsPe= rHost=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 15186 T2110 C218 P46546 REQ /get {distrib=3Dfalse&qt= =3D/get&wt=3Djavabin&version=3D2&getVersions=3D100} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 15196 T2111 C218 P46546 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 15201 T2111 C218 P46546 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= -Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDi= stributedZk2Test-1347568174611/jetty2/index lockFactory=3Dorg.apache.lucene= .store.NativeFSLockFactory@23514650; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0= ),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 15203 T2111 C218 P46546 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 1 [junit4:junit4] 2> 15204 T2111 C218 P46546 oass.SolrIndexSearcher. = Opening Searcher@179d57a9 main [junit4:junit4] 2> 15205 T2111 C218 P46546 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 15206 T2117 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@179d57a9 main{StandardDirectoryReader(s= egments_1:1)} [junit4:junit4] 2> 15206 T2111 C218 P46546 /update {waitSearcher=3Dtrue&c= ommit=3Dtrue&commit_end_point=3Dtrue&wt=3Djavabin&softCommit=3Dfalse&versio= n=3D2} {commit=3D} 0 10 [junit4:junit4] 2> 15207 T2143 C215 P46311 oash.ReplicationHandler.inform= Commits will be reserved for 10000 [junit4:junit4] 2> 15207 T2143 C215 P46311 oash.SnapPuller. No val= ue set for 'pollInterval'. Timer Task not started. [junit4:junit4] 2> 15209 T2112 C218 P46546 oash.ReplicationHandler.inform= Commits will be reserved for 10000 [junit4:junit4] 2> 15209 T2112 C218 P46546 REQ /replication {command=3Din= dexversion&wt=3Djavabin&qt=3D/replication&version=3D2} status=3D0 QTime=3D1= =20 [junit4:junit4] 2> 15212 T2143 C215 P46311 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= -Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDi= stributedZk2Test-1347568174611/jetty4/index lockFactory=3Dorg.apache.lucene= .store.NativeFSLockFactory@2d6a492d; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0= ),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 15213 T2143 C215 P46311 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 1 [junit4:junit4] 2> 15214 T2143 C215 P46311 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 15217 T2143 C215 P46311 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= -Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDi= stributedZk2Test-1347568174611/jetty4/index lockFactory=3Dorg.apache.lucene= .store.NativeFSLockFactory@2d6a492d; maxCacheMB=3D48.0 maxMergeSizeMB=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= -Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDi= stributedZk2Test-1347568174611/jetty4/index lockFactory=3Dorg.apache.lucene= .store.NativeFSLockFactory@2d6a492d; maxCacheMB=3D48.0 maxMergeSizeMB=3D4.0= ),segFN=3Dsegments_2,generation=3D2,filenames=3D[segments_2] [junit4:junit4] 2> 15217 T2143 C215 P46311 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 2 [junit4:junit4] 2> 15219 T2143 C215 P46311 oass.SolrIndexSearcher. = Opening Searcher@7eb414f1 main [junit4:junit4] 2> 15219 T2143 C215 P46311 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 15220 T2142 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@7eb414f1 main{StandardDirectoryReader(s= egments_2:2)} [junit4:junit4] 2> 15221 T2143 C215 P46311 oasc.RecoveryStrategy.replay N= o replay needed. core=3Dcollection1 [junit4:junit4] 2> 15221 T2143 C215 P46311 oasc.RecoveryStrategy.doRecove= ry Replication Recovery was successful - registering as Active. core=3Dcoll= ection1 [junit4:junit4] 2> 15235 T2143 C215 P46311 oasc.RecoveryStrategy.doRecove= ry Finished recovery process. core=3Dcollection1 [junit4:junit4] 2> 15279 T2070 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 15292 T2091 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 15304 T2128 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 15304 T2141 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 15304 T2116 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 15304 T2145 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 15304 T2147 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 15304 T2104 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 15304 T2090 oascc.ZkStateReader$2.process A cluster st= ate change has occurred - updating... [junit4:junit4] 2> 16281 T2070 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 16284 T2082 C216 P14282 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 16344 T2082 C216 P14282 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= -Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDi= stributedZk2Test-1347568174611/control/data/index lockFactory=3Dorg.apache.= lucene.store.NativeFSLockFactory@1624f38e; maxCacheMB=3D48.0 maxMergeSizeMB= =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= -Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDi= stributedZk2Test-1347568174611/control/data/index lockFactory=3Dorg.apache.= lucene.store.NativeFSLockFactory@1624f38e; maxCacheMB=3D48.0 maxMergeSizeMB= =3D4.0),segFN=3Dsegments_2,generation=3D2,filenames=3D[_0_MockSep_0.pos, _0= _MockRandom_0.prx, _0_TestBloomFilteredLucene40Postings_0.prx, _0_Pulsing40= _0.prx, _0_Pulsing40_0.frq, _0_MockSep_0.skp, _0_Pulsing40_0.tip, _0_TestBl= oomFilteredLucene40Postings_0.frq, _0.si, _0_MockSep_0.doc, _0_TestBloomFil= teredLucene40Postings_0.tim, _0_MockRandom_0.tib, _0_TestBloomFilteredLucen= e40Postings_0.tip, _0_TestBloomFilteredLucene40Postings_0.blm, _0.fnm, _0_P= ulsing40_0.tim, _0_nrm.cfs, _0_MockSep_0.tib, _0_MockRandom_0.frq, _0_MockR= andom_0.sd, _0_nrm.cfe, segments_2, _0_MockRandom_0.tii, _0_MockSep_0.frq, = _0.fdx, _0_MockSep_0.tii, _0_MockSep_0.pyl, _0.fdt] [junit4:junit4] 2> 16345 T2082 C216 P14282 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 2 [junit4:junit4] 2> 16357 T2082 C216 P14282 oass.SolrIndexSearcher. = Opening Searcher@1da868d6 main [junit4:junit4] 2> 16357 T2082 C216 P14282 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 16358 T2093 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@1da868d6 main{StandardDirectoryReader(s= egments_2:3 _0(4.0.0.2):C1)} [junit4:junit4] 2> 16359 T2082 C216 P14282 /update {waitSearcher=3Dtrue&w= t=3Djavabin&commit=3Dtrue&softCommit=3Dfalse&version=3D2} {commit=3D} 0 75 [junit4:junit4] 2> 16361 T2109 C218 P46546 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 16365 T2109 C218 P46546 oass.SolrIndexSearcher. = Opening Searcher@1857c260 main [junit4:junit4] 2> 16366 T2109 C218 P46546 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 16366 T2117 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@1857c260 main{StandardDirectoryReader(s= egments_1:1)} [junit4:junit4] 2> 16367 T2109 C218 P46546 oasu.SolrCmdDistributor.distri= bCommit Distrib commit to:[StdNode: http://127.0.0.1:49212/solr/collection1= /, StdNode: http://127.0.0.1:23066/solr/collection1/, StdNode: http://127.0= .0.1:46311/solr/collection1/] [junit4:junit4] 2> 16369 T2097 C217 P49212 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> C214_STATE=3Dcoll:collection1 core:collection1 props:= {shard=3Dshard1, roles=3Dnull, state=3Dactive, core=3Dcollection1, collecti= on=3Dcollection1, node_name=3D127.0.0.1:23066_solr, base_url=3Dhttp://127.0= .0.1:23066/solr} [junit4:junit4] 2> 16370 T2120 C214 P23066 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 16372 T2120 C214 P23066 oass.SolrIndexSearcher. = Opening Searcher@125aacbe main [junit4:junit4] 2> 16372 T2120 C214 P23066 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 16373 T2129 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@125aacbe main{StandardDirectoryReader(s= egments_2:3:nrt _0(4.0.0.2):C1)} [junit4:junit4] 2> 16373 T2120 C214 P23066 /update {waitSearcher=3Dtrue&c= ommit=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&softCommit=3Dfalse&versio= n=3D2} {commit=3D} 0 3 [junit4:junit4] 2> C215_STATE=3Dcoll:collection1 core:collection1 props:= {shard=3Dshard2, roles=3Dnull, state=3Dactive, core=3Dcollection1, collecti= on=3Dcollection1, node_name=3D127.0.0.1:46311_solr, base_url=3Dhttp://127.0= .0.1:46311/solr} [junit4:junit4] 2> 16375 T2133 C215 P46311 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 16380 T2133 C215 P46311 oass.SolrIndexSearcher. = Opening Searcher@51d5651 main [junit4:junit4] 2> 16380 T2133 C215 P46311 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 16381 T2097 C217 P49212 oass.SolrIndexSearcher. = Opening Searcher@2e8ed672 main [junit4:junit4] 2> 16381 T2142 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@51d5651 main{StandardDirectoryReader(se= gments_2:2)} [junit4:junit4] 2> 16381 T2097 C217 P49212 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 16381 T2133 C215 P46311 /update {waitSearcher=3Dtrue&c= ommit=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&softCommit=3Dfalse&versio= n=3D2} {commit=3D} 0 6 [junit4:junit4] 2> 16382 T2105 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@2e8ed672 main{StandardDirectoryReader(s= egments_2:3 _0(4.0.0.2):C1)} [junit4:junit4] 2> 16382 T2097 C217 P49212 /update {waitSearcher=3Dtrue&c= ommit=3Dtrue&wt=3Djavabin&commit_end_point=3Dtrue&softCommit=3Dfalse&versio= n=3D2} {commit=3D} 0 13 [junit4:junit4] 2> 16383 T2109 C218 P46546 /update {waitSearcher=3Dtrue&w= t=3Djavabin&commit=3Dtrue&softCommit=3Dfalse&version=3D2} {commit=3D} 0 22 [junit4:junit4] 2> 16387 T2083 C216 P14282 REQ /select {fl=3Did,score&sha= rd.url=3D127.0.0.1:14282/solr/collection1/&NOW=3D1347568190993&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> 16390 T2083 C216 P14282 REQ /select {shard.url=3D127.0= .0.1:14282/solr/collection1/&NOW=3D1347568190993&q=3D*:*&ids=3D1&distrib=3D= false&isShard=3Dtrue&wt=3Djavabin&version=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 16391 T2082 C216 P14282 REQ /select {wt=3Djavabin&q=3D= *:*&version=3D2} hits=3D1 status=3D0 QTime=3D6=20 [junit4:junit4] 2> 16391 T2070 oaz.ZooKeeper. Initiating client con= nection, connectString=3D127.0.0.1:47166/solr sessionTimeout=3D10000 watche= r=3Dorg.apache.solr.common.cloud.ConnectionManager@45c9e9d5 [junit4:junit4] 2> 16392 T2158 oaz.ClientCnxn$SendThread.startConnect Ope= ning socket connection to server /127.0.0.1:47166 [junit4:junit4] 2> 16392 T2070 oascc.ConnectionManager.waitForConnected W= aiting for client to connect to ZooKeeper [junit4:junit4] 2> 16393 T2158 oaz.ClientCnxn$SendThread.primeConnection = Socket connection established to localhost/127.0.0.1:47166, initiating sess= ion [junit4:junit4] 2> 16393 T2072 oazs.NIOServerCnxn$Factory.run Accepted so= cket connection from /140.211.11.196:37381 [junit4:junit4] 2> 16393 T2072 oazs.NIOServerCnxn.readConnectRequest Clie= nt attempting to establish new session at /140.211.11.196:37381 [junit4:junit4] 2> 16396 T2074 oazs.NIOServerCnxn.finishSessionInit Estab= lished session 0x139c15286180009 with negotiated timeout 10000 for client /= 140.211.11.196:37381 [junit4:junit4] 2> 16396 T2158 oaz.ClientCnxn$SendThread.readConnectResul= t Session establishment complete on server localhost/127.0.0.1:47166, sessi= onid =3D 0x139c15286180009, negotiated timeout =3D 10000 [junit4:junit4] 2> 16396 T2159 oascc.ConnectionManager.process Watcher or= g.apache.solr.common.cloud.ConnectionManager@45c9e9d5 name:ZooKeeperConnect= ion Watcher:127.0.0.1:47166/solr got event WatchedEvent state:SyncConnected= type:None path:null path:null type:None [junit4:junit4] 2> 16396 T2070 oascc.ConnectionManager.waitForConnected C= lient is connected to ZooKeeper [junit4:junit4] 2> 16397 T2070 oascc.ZkStateReader.createClusterStateWatc= hersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 16398 T2075 oazs.PrepRequestProcessor.pRequest Process= ed session termination for sessionid: 0x139c15286180009 [junit4:junit4] 2> 16399 T2159 oaz.ClientCnxn$EventThread.run EventThread= shut down [junit4:junit4] 2> 16399 T2072 oazs.NIOServerCnxn.closeSock Closed socket= connection for client /140.211.11.196:37381 which had sessionid 0x139c1528= 6180009 [junit4:junit4] 2> 16399 T2070 oaz.ZooKeeper.close Session: 0x139c1528618= 0009 closed [junit4:junit4] 2> 16400 T2101 C217 P49212 REQ /select {distrib=3Dfalse&w= t=3Djavabin&q=3D*:*&version=3D2} hits=3D1 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 16402 T2113 C218 P46546 REQ /select {distrib=3Dfalse&w= t=3Djavabin&q=3D*:*&version=3D2} hits=3D0 status=3D0 QTime=3D0=20 [junit4:junit4] 2> 16405 T2114 C218 P46546 REQ /select {fl=3Did,score&sha= rd.url=3D127.0.0.1:46546/solr/collection1/|127.0.0.1:46311/solr/collection1= /&NOW=3D1347568191011&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> 16405 T2121 C214 P23066 REQ /select {fl=3Did,score&sha= rd.url=3D127.0.0.1:49212/solr/collection1/|127.0.0.1:23066/solr/collection1= /&NOW=3D1347568191011&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> 16408 T2121 C214 P23066 REQ /select {shard.url=3D127.0= .0.1:49212/solr/collection1/|127.0.0.1:23066/solr/collection1/&NOW=3D134756= 8191011&q=3D*:*&ids=3D1&distrib=3Dfalse&isShard=3Dtrue&wt=3Djavabin&version= =3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 16409 T2102 C217 P49212 REQ /select {wt=3Djavabin&q=3D= *:*&version=3D2} hits=3D1 status=3D0 QTime=3D6=20 [junit4:junit4] 2> 16414 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[2 (1413027663465938944)]} 0 3 [junit4:junit4] 2> 16425 T2133 C215 P46311 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:46546/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D3=20 [junit4:junit4] 2> 16425 T2108 C218 P46546 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D8=20 [junit4:junit4] 2> 16426 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[2]} 0 10 [junit4:junit4] 2> 16429 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[3 (1413027663483764736)]} 0 1 [junit4:junit4] 2> 16438 T2133 C215 P46311 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:46546/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16439 T2108 C218 P46546 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 16439 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[3]} 0 7 [junit4:junit4] 2> 16443 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[4 (1413027663498444800)]} 0 1 [junit4:junit4] 2> 16454 T2120 C214 P23066 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D3=20 [junit4:junit4] 2> 16454 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[4 (1413027663502639104)]} 0 8 [junit4:junit4] 2> 16458 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[5 (1413027663514173440)]} 0 1 [junit4:junit4] 2> 16467 T2133 C215 P46311 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:46546/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16468 T2108 C218 P46546 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 16468 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[5]} 0 7 [junit4:junit4] 2> 16472 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[6 (1413027663528853504)]} 0 1 [junit4:junit4] 2> 16481 T2133 C215 P46311 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:46546/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16482 T2108 C218 P46546 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 16482 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[6]} 0 7 [junit4:junit4] 2> 16487 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[7 (1413027663543533568)]} 0 2 [junit4:junit4] 2> 16497 T2133 C215 P46311 REQ /update {distrib.from=3Dht= tp://127.0.0.1:46546/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 16497 T2109 C218 P46546 /update {wt=3Djavabin&version= =3D2} {add=3D[7 (1413027663548776448)]} 0 7 [junit4:junit4] 2> 16502 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[8 (1413027663559262208)]} 0 2 [junit4:junit4] 2> 16510 T2120 C214 P23066 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 16511 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[8 (1413027663563456512)]} 0 7 [junit4:junit4] 2> 16532 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[9 (1413027663573942272)]} 0 18 [junit4:junit4] 2> 16542 T2133 C215 P46311 REQ /update {distrib.from=3Dht= tp://127.0.0.1:46546/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 16542 T2109 C218 P46546 /update {wt=3Djavabin&version= =3D2} {add=3D[9 (1413027663597010944)]} 0 7 [junit4:junit4] 2> 16547 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[10 (1413027663606448128)]} 0 2 [junit4:junit4] 2> 16560 T2120 C214 P23066 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:49212/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D3=20 [junit4:junit4] 2> 16560 T2097 C217 P49212 REQ /update {distrib.from=3Dht= tp://127.0.0.1:46546/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D7=20 [junit4:junit4] 2> 16561 T2109 C218 P46546 /update {wt=3Djavabin&version= =3D2} {add=3D[10]} 0 11 [junit4:junit4] 2> 16576 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[11 (1413027663626371072)]} 0 12 [junit4:junit4] 2> 16587 T2120 C214 P23066 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:49212/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 16588 T2097 C217 P49212 REQ /update {distrib.from=3Dht= tp://127.0.0.1:46546/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D6=20 [junit4:junit4] 2> 16588 T2109 C218 P46546 /update {wt=3Djavabin&version= =3D2} {add=3D[11]} 0 9 [junit4:junit4] 2> 16593 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[12 (1413027663654682624)]} 0 2 [junit4:junit4] 2> 16605 T2120 C214 P23066 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:49212/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16605 T2097 C217 P49212 REQ /update {distrib.from=3Dht= tp://127.0.0.1:46546/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D7=20 [junit4:junit4] 2> 16606 T2109 C218 P46546 /update {wt=3Djavabin&version= =3D2} {add=3D[12]} 0 10 [junit4:junit4] 2> 16611 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[13 (1413027663673556992)]} 0 2 [junit4:junit4] 2> 16621 T2120 C214 P23066 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:49212/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 16622 T2097 C217 P49212 REQ /update {distrib.from=3Dht= tp://127.0.0.1:46546/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D7=20 [junit4:junit4] 2> 16623 T2109 C218 P46546 /update {wt=3Djavabin&version= =3D2} {add=3D[13]} 0 10 [junit4:junit4] 2> 16627 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[14 (1413027663691382784)]} 0 1 [junit4:junit4] 2> 16635 T2120 C214 P23066 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 16636 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[14 (1413027663695577088)]} 0 6 [junit4:junit4] 2> 16639 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[15 (1413027663703965696)]} 0 1 [junit4:junit4] 2> 16647 T2120 C214 P23066 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 16648 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[15 (1413027663708160000)]} 0 6 [junit4:junit4] 2> 16653 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[16 (1413027663717597184)]} 0 2 [junit4:junit4] 2> 16663 T2120 C214 P23066 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:49212/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 16664 T2097 C217 P49212 REQ /update {distrib.from=3Dht= tp://127.0.0.1:46546/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D6=20 [junit4:junit4] 2> 16665 T2109 C218 P46546 /update {wt=3Djavabin&version= =3D2} {add=3D[16]} 0 10 [junit4:junit4] 2> 16672 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[17 (1413027663736471552)]} 0 4 [junit4:junit4] 2> 16688 T2133 C215 P46311 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:46546/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 16689 T2108 C218 P46546 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D11=20 [junit4:junit4] 2> 16690 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[17]} 0 15 [junit4:junit4] 2> 16693 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[100 (1413027663760588800)]} 0 1 [junit4:junit4] 2> 16724 T2120 C214 P23066 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D16=20 [junit4:junit4] 2> 16725 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[100 (1413027663764783104)]} 0 29 [junit4:junit4] 2> 16730 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[101 (1413027663798337536)]} 0 2 [junit4:junit4] 2> 16739 T2120 C214 P23066 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D3=20 [junit4:junit4] 2> 16740 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[101 (1413027663802531840)]} 0 8 [junit4:junit4] 2> 16744 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[102 (1413027663814066176)]} 0 1 [junit4:junit4] 2> 16756 T2120 C214 P23066 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:49212/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 16757 T2097 C217 P49212 REQ /update {distrib.from=3Dht= tp://127.0.0.1:46546/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D7=20 [junit4:junit4] 2> 16758 T2109 C218 P46546 /update {wt=3Djavabin&version= =3D2} {add=3D[102]} 0 11 [junit4:junit4] 2> 16772 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[103 (1413027663832940544)]} 0 11 [junit4:junit4] 2> 16781 T2133 C215 P46311 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:46546/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 16781 T2108 C218 P46546 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 16782 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[103]} 0 8 [junit4:junit4] 2> 16786 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[104 (1413027663857057792)]} 0 2 [junit4:junit4] 2> 16794 T2133 C215 P46311 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:46546/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16795 T2108 C218 P46546 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 16795 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[104]} 0 7 [junit4:junit4] 2> 16799 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[105 (1413027663871737856)]} 0 1 [junit4:junit4] 2> 16833 T2133 C215 P46311 REQ /update {distrib.from=3Dht= tp://127.0.0.1:46546/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D11=20 [junit4:junit4] 2> 16834 T2109 C218 P46546 /update {wt=3Djavabin&version= =3D2} {add=3D[105 (1413027663875932160)]} 0 32 [junit4:junit4] 2> 16839 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[106 (1413027663913680896)]} 0 2 [junit4:junit4] 2> 16851 T2133 C215 P46311 REQ /update {distrib.from=3Dht= tp://127.0.0.1:46546/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 16852 T2109 C218 P46546 /update {wt=3Djavabin&version= =3D2} {add=3D[106 (1413027663917875200)]} 0 10 [junit4:junit4] 2> 16857 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[107 (1413027663931506688)]} 0 2 [junit4:junit4] 2> 16863 T2120 C214 P23066 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16864 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[107 (1413027663935700992)]} 0 5 [junit4:junit4] 2> 16867 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[108 (1413027663943041024)]} 0 1 [junit4:junit4] 2> 16874 T2120 C214 P23066 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16875 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[108 (1413027663947235328)]} 0 5 [junit4:junit4] 2> 16878 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[109 (1413027663954575360)]} 0 1 [junit4:junit4] 2> 16886 T2120 C214 P23066 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 16887 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[109 (1413027663958769664)]} 0 6 [junit4:junit4] 2> 16890 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[110 (1413027663967158272)]} 0 1 [junit4:junit4] 2> 16899 T2120 C214 P23066 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:49212/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16900 T2097 C217 P49212 REQ /update {distrib.from=3Dht= tp://127.0.0.1:46546/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 16900 T2109 C218 P46546 /update {wt=3Djavabin&version= =3D2} {add=3D[110]} 0 7 [junit4:junit4] 2> 16904 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[111 (1413027663981838336)]} 0 1 [junit4:junit4] 2> 16911 T2120 C214 P23066 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16912 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[111 (1413027663986032640)]} 0 5 [junit4:junit4] 2> 16916 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[112 (1413027663994421248)]} 0 1 [junit4:junit4] 2> 16924 T2133 C215 P46311 REQ /update {distrib.from=3Dht= tp://127.0.0.1:46546/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16925 T2109 C218 P46546 /update {wt=3Djavabin&version= =3D2} {add=3D[112 (1413027663998615552)]} 0 6 [junit4:junit4] 2> 16938 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[113 (1413027664008052736)]} 0 10 [junit4:junit4] 2> 16947 T2120 C214 P23066 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 16948 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[113 (1413027664021684224)]} 0 7 [junit4:junit4] 2> 16953 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[114 (1413027664032169984)]} 0 2 [junit4:junit4] 2> 16962 T2120 C214 P23066 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:49212/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16963 T2097 C217 P49212 REQ /update {distrib.from=3Dht= tp://127.0.0.1:46546/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 16964 T2109 C218 P46546 /update {wt=3Djavabin&version= =3D2} {add=3D[114]} 0 8 [junit4:junit4] 2> 16967 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[115 (1413027664047898624)]} 0 1 [junit4:junit4] 2> 16974 T2133 C215 P46311 REQ /update {distrib.from=3Dht= tp://127.0.0.1:46546/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16974 T2109 C218 P46546 /update {wt=3Djavabin&version= =3D2} {add=3D[115 (1413027664052092928)]} 0 4 [junit4:junit4] 2> 16978 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[116 (1413027664059432960)]} 0 1 [junit4:junit4] 2> 16986 T2133 C215 P46311 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:46546/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16986 T2108 C218 P46546 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 16987 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[116]} 0 7 [junit4:junit4] 2> 16990 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[117 (1413027664072015872)]} 0 1 [junit4:junit4] 2> 16996 T2133 C215 P46311 REQ /update {distrib.from=3Dht= tp://127.0.0.1:46546/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 16997 T2109 C218 P46546 /update {wt=3Djavabin&version= =3D2} {add=3D[117 (1413027664075161600)]} 0 5 [junit4:junit4] 2> 17000 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[118 (1413027664082501632)]} 0 1 [junit4:junit4] 2> 17024 T2120 C214 P23066 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D10=20 [junit4:junit4] 2> 17025 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[118 (1413027664086695936)]} 0 22 [junit4:junit4] 2> 17029 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[119 (1413027664112910336)]} 0 1 [junit4:junit4] 2> 17037 T2133 C215 P46311 REQ /update {distrib.from=3Dht= tp://127.0.0.1:46546/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17038 T2109 C218 P46546 /update {wt=3Djavabin&version= =3D2} {add=3D[119 (1413027664117104640)]} 0 6 [junit4:junit4] 2> 17042 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[120 (1413027664126541824)]} 0 1 [junit4:junit4] 2> 17049 T2133 C215 P46311 REQ /update {distrib.from=3Dht= tp://127.0.0.1:46546/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17049 T2109 C218 P46546 /update {wt=3Djavabin&version= =3D2} {add=3D[120 (1413027664129687552)]} 0 5 [junit4:junit4] 2> 17053 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[121 (1413027664138076160)]} 0 1 [junit4:junit4] 2> 17061 T2133 C215 P46311 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:46546/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17061 T2108 C218 P46546 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 17062 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[121]} 0 7 [junit4:junit4] 2> 17065 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[122 (1413027664150659072)]} 0 1 [junit4:junit4] 2> 17074 T2120 C214 P23066 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:49212/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17075 T2097 C217 P49212 REQ /update {distrib.from=3Dht= tp://127.0.0.1:46546/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D6=20 [junit4:junit4] 2> 17075 T2109 C218 P46546 /update {wt=3Djavabin&version= =3D2} {add=3D[122]} 0 8 [junit4:junit4] 2> 17088 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[123 (1413027664165339136)]} 0 10 [junit4:junit4] 2> 17095 T2120 C214 P23066 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17095 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[123 (1413027664178970624)]} 0 4 [junit4:junit4] 2> 17099 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[124 (1413027664186310656)]} 0 1 [junit4:junit4] 2> 17108 T2120 C214 P23066 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:49212/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17108 T2097 C217 P49212 REQ /update {distrib.from=3Dht= tp://127.0.0.1:46546/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 17108 T2109 C218 P46546 /update {wt=3Djavabin&version= =3D2} {add=3D[124]} 0 6 [junit4:junit4] 2> 17112 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[125 (1413027664199942144)]} 0 1 [junit4:junit4] 2> 17118 T2120 C214 P23066 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17118 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[125 (1413027664203087872)]} 0 4 [junit4:junit4] 2> 17122 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[126 (1413027664210427904)]} 0 1 [junit4:junit4] 2> 17130 T2133 C215 P46311 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:46546/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17130 T2108 C218 P46546 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 17130 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[126]} 0 6 [junit4:junit4] 2> 17134 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[127 (1413027664223010816)]} 0 1 [junit4:junit4] 2> 17159 T2133 C215 P46311 REQ /update {distrib.from=3Dht= tp://127.0.0.1:46546/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D8=20 [junit4:junit4] 2> 17160 T2109 C218 P46546 /update {wt=3Djavabin&version= =3D2} {add=3D[127 (1413027664226156544)]} 0 24 [junit4:junit4] 2> 17164 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[128 (1413027664254468096)]} 0 1 [junit4:junit4] 2> 17172 T2133 C215 P46311 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:46546/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17173 T2108 C218 P46546 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 17173 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[128]} 0 7 [junit4:junit4] 2> 17177 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[129 (1413027664268099584)]} 0 1 [junit4:junit4] 2> 17185 T2120 C214 P23066 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:49212/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17185 T2097 C217 P49212 REQ /update {distrib.from=3Dht= tp://127.0.0.1:46546/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 17185 T2109 C218 P46546 /update {wt=3Djavabin&version= =3D2} {add=3D[129]} 0 6 [junit4:junit4] 2> 17189 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[130 (1413027664280682496)]} 0 1 [junit4:junit4] 2> 17197 T2133 C215 P46311 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:46546/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17197 T2108 C218 P46546 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 17197 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[130]} 0 6 [junit4:junit4] 2> 17201 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[131 (1413027664293265408)]} 0 1 [junit4:junit4] 2> 17209 T2133 C215 P46311 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:46546/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17209 T2108 C218 P46546 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 17209 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[131]} 0 6 [junit4:junit4] 2> 17213 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[132 (1413027664305848320)]} 0 1 [junit4:junit4] 2> 17221 T2120 C214 P23066 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:49212/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17221 T2097 C217 P49212 REQ /update {distrib.from=3Dht= tp://127.0.0.1:46546/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 17222 T2109 C218 P46546 /update {wt=3Djavabin&version= =3D2} {add=3D[132]} 0 7 [junit4:junit4] 2> 17234 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[133 (1413027664318431232)]} 0 10 [junit4:junit4] 2> 17241 T2120 C214 P23066 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17241 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[133 (1413027664332062720)]} 0 4 [junit4:junit4] 2> 17245 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[134 (1413027664339402752)]} 0 1 [junit4:junit4] 2> 17254 T2133 C215 P46311 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:46546/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17254 T2108 C218 P46546 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 17255 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[134]} 0 7 [junit4:junit4] 2> 17258 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[135 (1413027664353034240)]} 0 1 [junit4:junit4] 2> 17264 T2120 C214 P23066 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17264 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[135 (1413027664356179968)]} 0 4 [junit4:junit4] 2> 17268 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[136 (1413027664363520000)]} 0 1 [junit4:junit4] 2> 17276 T2133 C215 P46311 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:46546/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17276 T2108 C218 P46546 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 17277 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[136]} 0 7 [junit4:junit4] 2> 17281 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[137 (1413027664377151488)]} 0 1 [junit4:junit4] 2> 17289 T2133 C215 P46311 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:46546/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17289 T2108 C218 P46546 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 17290 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[137]} 0 7 [junit4:junit4] 2> 17293 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[138 (1413027664389734400)]} 0 1 [junit4:junit4] 2> 17299 T2120 C214 P23066 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17299 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[138 (1413027664392880128)]} 0 4 [junit4:junit4] 2> 17303 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[139 (1413027664400220160)]} 0 1 [junit4:junit4] 2> 17311 T2133 C215 P46311 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:46546/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17311 T2108 C218 P46546 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 17312 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[139]} 0 7 [junit4:junit4] 2> 17315 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[140 (1413027664412803072)]} 0 1 [junit4:junit4] 2> 17322 T2133 C215 P46311 REQ /update {distrib.from=3Dht= tp://127.0.0.1:46546/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17322 T2109 C218 P46546 /update {wt=3Djavabin&version= =3D2} {add=3D[140 (1413027664415948800)]} 0 5 [junit4:junit4] 2> 17326 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[141 (1413027664424337408)]} 0 1 [junit4:junit4] 2> 17334 T2133 C215 P46311 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:46546/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17334 T2108 C218 P46546 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 17335 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[141]} 0 7 [junit4:junit4] 2> 17338 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[142 (1413027664436920320)]} 0 1 [junit4:junit4] 2> 17366 T2133 C215 P46311 REQ /update {distrib.from=3Dht= tp://127.0.0.1:46546/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D10=20 [junit4:junit4] 2> 17366 T2109 C218 P46546 /update {wt=3Djavabin&version= =3D2} {add=3D[142 (1413027664440066048)]} 0 26 [junit4:junit4] 2> 17378 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[143 (1413027664470474752)]} 0 9 [junit4:junit4] 2> 17387 T2133 C215 P46311 REQ /update {distrib.from=3Dht= tp://127.0.0.1:46546/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D2=20 [junit4:junit4] 2> 17387 T2109 C218 P46546 /update {wt=3Djavabin&version= =3D2} {add=3D[143 (1413027664483057664)]} 0 6 [junit4:junit4] 2> 17391 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[144 (1413027664492494848)]} 0 1 [junit4:junit4] 2> 17398 T2133 C215 P46311 REQ /update {distrib.from=3Dht= tp://127.0.0.1:46546/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17398 T2109 C218 P46546 /update {wt=3Djavabin&version= =3D2} {add=3D[144 (1413027664496689152)]} 0 4 [junit4:junit4] 2> 17402 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[145 (1413027664504029184)]} 0 1 [junit4:junit4] 2> 17410 T2133 C215 P46311 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:46546/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17410 T2108 C218 P46546 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D4=20 [junit4:junit4] 2> 17411 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[145]} 0 7 [junit4:junit4] 2> 17414 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[146 (1413027664516612096)]} 0 1 [junit4:junit4] 2> 17436 T2120 C214 P23066 REQ /update {distrib.from=3Dht= tp://127.0.0.1:49212/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D9=20 [junit4:junit4] 2> 17437 T2102 C217 P49212 /update {wt=3Djavabin&version= =3D2} {add=3D[146 (1413027664519757824)]} 0 21 [junit4:junit4] 2> 17440 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[147 (1413027664543875072)]} 0 1 [junit4:junit4] 2> 17446 T2133 C215 P46311 REQ /update {distrib.from=3Dht= tp://127.0.0.1:46546/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17446 T2109 C218 P46546 /update {wt=3Djavabin&version= =3D2} {add=3D[147 (1413027664547020800)]} 0 4 [junit4:junit4] 2> 17450 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[148 (1413027664554360832)]} 0 1 [junit4:junit4] 2> 17458 T2120 C214 P23066 REQ /update {update.distrib=3D= FROMLEADER&distrib.from=3Dhttp://127.0.0.1:49212/solr/collection1/&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17459 T2097 C217 P49212 REQ /update {distrib.from=3Dht= tp://127.0.0.1:46546/solr/collection1/&update.distrib=3DTOLEADER&wt=3Djavab= in&version=3D2} status=3D0 QTime=3D5=20 [junit4:junit4] 2> 17459 T2109 C218 P46546 /update {wt=3Djavabin&version= =3D2} {add=3D[148]} 0 7 [junit4:junit4] 2> 17463 T2082 C216 P14282 /update {wt=3Djavabin&version= =3D2} {add=3D[149 (1413027664567992320)]} 0 1 [junit4:junit4] 2> 17469 T2133 C215 P46311 REQ /update {distrib.from=3Dht= tp://127.0.0.1:46546/solr/collection1/&update.distrib=3DFROMLEADER&wt=3Djav= abin&version=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 17469 T2109 C218 P46546 /update {wt=3Djavabin&version= =3D2} {add=3D[149 (1413027664571138048)]} 0 4 [junit4:junit4] 2> 17470 T2070 oasc.AbstractDistribZkTestBase.waitForReco= veriesToFinish Wait for recoveries to finish - collection: collection1 fail= OnTimeout:true timeout (sec):1440 [junit4:junit4] 2> 17471 T2070 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 17473 T2082 C216 P14282 oasu.DirectUpdateHandler2.comm= it start commit{flags=3D0,_version_=3D0,optimize=3Dfalse,openSearcher=3Dtru= e,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,softCommit=3Dfalse} [junit4:junit4] 2> 17892 T2082 C216 P14282 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= -Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDi= stributedZk2Test-1347568174611/control/data/index lockFactory=3Dorg.apache.= lucene.store.NativeFSLockFactory@1624f38e; maxCacheMB=3D48.0 maxMergeSizeMB= =3D4.0),segFN=3Dsegments_2,generation=3D2,filenames=3D[_0_MockSep_0.pos, _0= _MockRandom_0.prx, _0_TestBloomFilteredLucene40Postings_0.prx, _0_Pulsing40= _0.prx, _0_Pulsing40_0.frq, _0_MockSep_0.skp, _0_Pulsing40_0.tip, _0_TestBl= oomFilteredLucene40Postings_0.frq, _0.si, _0_MockSep_0.doc, _0_TestBloomFil= teredLucene40Postings_0.tim, _0_MockRandom_0.tib, _0_TestBloomFilteredLucen= e40Postings_0.tip, _0_TestBloomFilteredLucene40Postings_0.blm, _0.fnm, _0_P= ulsing40_0.tim, _0_nrm.cfs, _0_MockSep_0.tib, _0_MockRandom_0.frq, _0_MockR= andom_0.sd, _0_nrm.cfe, segments_2, _0_MockRandom_0.tii, _0_MockSep_0.frq, = _0.fdx, _0_MockSep_0.tii, _0_MockSep_0.pyl, _0.fdt] [junit4:junit4] 2> =09=09commit{dir=3DNRTCachingDirectory(org.apache.luce= ne.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr= -Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDi= stributedZk2Test-1347568174611/control/data/index lockFactory=3Dorg.apache.= lucene.store.NativeFSLockFactory@1624f38e; maxCacheMB=3D48.0 maxMergeSizeMB= =3D4.0),segFN=3Dsegments_3,generation=3D3,filenames=3D[_5_nrm.cfe, _3_MockR= andom_0.prx, _0_MockRandom_0.prx, _4_MockSep_0.skp, _3.fdt, _2_MockSep_0.do= c, _1_MockRandom_0.pos, _7.fnm, _6_MockSep_0.doc, _5_MockSep_0.skp, _4_Mock= Random_0.pyl, _5_MockRandom_0.pyl, _5_Pulsing40_0.tip, _3_TestBloomFiltered= Lucene40Postings_0.frq, _6_MockSep_0.frq, _7_MockSep_0.tib, _5_Pulsing40_0.= tim, _1_MockSep_0.pyl, _3_TestBloomFilteredLucene40Postings_0.tim, _0_TestB= loomFilteredLucene40Postings_0.blm, _0_Pulsing40_0.tim, _7_MockSep_0.tii, _= 1_MockSep_0.tib, _1_MockRandom_0.sd, _3_TestBloomFilteredLucene40Postings_0= .tip, _5_MockSep_0.doc, _1_MockSep_0.tii, _5_TestBloomFilteredLucene40Posti= ngs_0.frq, _1.fdx, _0_MockRandom_0.frq, _1.fdt, _3_MockSep_0.pos, _5_nrm.cf= s, _4_MockRandom_0.sd, _7_MockRandom_0.sd, _1_MockRandom_0.doc, _6.fdt, _3_= Pulsing40_0.frq, _2.si, _2_Pulsing40_0.tim, _6_MockRandom_0.tip, _6_MockSep= _0.pyl, _2_MockSep_0.skp, _2_Pulsing40_0.tip, _6_MockRandom_0.tim, _6.fdx, = _5_MockSep_0.tii, _2_nrm.cfe, _5_Pulsing40_0.frq, _0_Pulsing40_0.tip, _2_nr= m.cfs, _4_MockRandom_0.skp, _2_TestBloomFilteredLucene40Postings_0.frq, _2_= MockRandom_0.prx, _4_TestBloomFilteredLucene40Postings_0.blm, _2.fnm, _7_Pu= lsing40_0.tim, _3.fdx, _2_MockRandom_0.sd, _4_Pulsing40_0.tip, _2_MockSep_0= .pyl, _4_Pulsing40_0.tim, _1.si, _5_MockSep_0.tib, _4_TestBloomFilteredLuce= ne40Postings_0.tim, _3_MockSep_0.skp, _4_TestBloomFilteredLucene40Postings_= 0.tip, _7_Pulsing40_0.tip, _3_MockSep_0.doc, _3_nrm.cfe, _4_MockRandom_0.ti= i, _4_nrm.cfe, _4_MockRandom_0.tib, _0_Pulsing40_0.prx, _4.fdx, _7_MockRand= om_0.frq, _1_Pulsing40_0.prx, _5_Mo [...truncated too long message...] luate(StatementAdapter.java:36) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.SystemP= ropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) [junit4:junit4] > =09at org.apache.lucene.util.AbstractBeforeAfterRule$1= .evaluate(AbstractBeforeAfterRule.java:45) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleStoreClassName$1.= evaluate(TestRuleStoreClassName.java:42) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.SystemP= ropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.NoShado= wingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule= .java:39) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.NoShado= wingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule= .java:39) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.Stateme= ntAdapter.evaluate(StatementAdapter.java:36) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleAssertionsRequire= d$1.evaluate(TestRuleAssertionsRequired.java:43) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleMarkFailure$1.eva= luate(TestRuleMarkFailure.java:48) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFai= lures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$= 1.evaluate(TestRuleIgnoreTestSuites.java:55) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.Stateme= ntAdapter.evaluate(StatementAdapter.java:36) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.ThreadLeakCon= trol$StatementRunner.run(ThreadLeakControl.java:358) [junit4:junit4] > =09at java.lang.Thread.run(Thread.java:722) [junit4:junit4] > Caused by: java.net.SocketTimeoutException: Read timed= out [junit4:junit4] > =09at java.net.SocketInputStream.socketRead0(Native Me= thod) [junit4:junit4] > =09at java.net.SocketInputStream.read(SocketInputStrea= m.java:150) [junit4:junit4] > =09at java.net.SocketInputStream.read(SocketInputStrea= m.java:121) [junit4:junit4] > =09at org.apache.http.impl.io.AbstractSessionInputBuff= er.fillBuffer(AbstractSessionInputBuffer.java:149) [junit4:junit4] > =09at org.apache.http.impl.io.SocketInputBuffer.fillBu= ffer(SocketInputBuffer.java:111) [junit4:junit4] > =09at org.apache.http.impl.io.AbstractSessionInputBuff= er.readLine(AbstractSessionInputBuffer.java:264) [junit4:junit4] > =09at org.apache.http.impl.conn.DefaultResponseParser.= parseHead(DefaultResponseParser.java:98) [junit4:junit4] > =09at org.apache.http.impl.io.AbstractMessageParser.pa= rse(AbstractMessageParser.java:252) [junit4:junit4] > =09at org.apache.http.impl.AbstractHttpClientConnectio= n.receiveResponseHeader(AbstractHttpClientConnection.java:282) [junit4:junit4] > =09at org.apache.http.impl.conn.DefaultClientConnectio= n.receiveResponseHeader(DefaultClientConnection.java:247) [junit4:junit4] > =09at org.apache.http.impl.conn.AbstractClientConnAdap= ter.receiveResponseHeader(AbstractClientConnAdapter.java:216) [junit4:junit4] > =09at org.apache.http.protocol.HttpRequestExecutor.doR= eceiveResponse(HttpRequestExecutor.java:298) [junit4:junit4] > =09at org.apache.http.protocol.HttpRequestExecutor.exe= cute(HttpRequestExecutor.java:125) [junit4:junit4] > =09at org.apache.http.impl.client.DefaultRequestDirect= or.tryExecute(DefaultRequestDirector.java:647) [junit4:junit4] > =09at org.apache.http.impl.client.DefaultRequestDirect= or.execute(DefaultRequestDirector.java:464) [junit4:junit4] > =09at org.apache.http.impl.client.AbstractHttpClient.e= xecute(AbstractHttpClient.java:820) [junit4:junit4] > =09at org.apache.http.impl.client.AbstractHttpClient.e= xecute(AbstractHttpClient.java:754) [junit4:junit4] > =09at org.apache.http.impl.client.AbstractHttpClient.e= xecute(AbstractHttpClient.java:732) [junit4:junit4] > =09at org.apache.solr.client.solrj.impl.HttpSolrServer= .request(HttpSolrServer.java:353) [junit4:junit4] > =09... 46 more [junit4:junit4] 2> 110234 T2070 oas.SolrTestCaseJ4.deleteCore ###deleteCo= re [junit4:junit4] 2> NOTE: test params are: codec=3DLucene40: {n_f1=3DPosti= ngsFormat(name=3DTestBloomFilteredLucene40Postings), foo_b=3DPulsing40(freq= Cutoff=3D16 minBlockSize=3D62 maxBlockSize=3D122), foo_d=3DPostingsFormat(n= ame=3DMockSep), foo_f=3DPulsing40(freqCutoff=3D16 minBlockSize=3D62 maxBloc= kSize=3D122), n_tl1=3DPostingsFormat(name=3DTestBloomFilteredLucene40Postin= gs), n_d1=3DPostingsFormat(name=3DMockRandom), rnd_b=3DPostingsFormat(name= =3DMockSep), intDefault=3DPostingsFormat(name=3DMockRandom), n_td1=3DPostin= gsFormat(name=3DTestBloomFilteredLucene40Postings), timestamp=3DPostingsFor= mat(name=3DMockRandom), id=3DPostingsFormat(name=3DMockSep), range_facet_sl= =3DPulsing40(freqCutoff=3D16 minBlockSize=3D62 maxBlockSize=3D122), range_f= acet_si=3DPostingsFormat(name=3DTestBloomFilteredLucene40Postings), oddFiel= d_s=3DPulsing40(freqCutoff=3D16 minBlockSize=3D62 maxBlockSize=3D122), mult= iDefault=3DPostingsFormat(name=3DTestBloomFilteredLucene40Postings), n_tf1= =3DPostingsFormat(name=3DMockRandom), n_dt1=3DPostingsFormat(name=3DTestBlo= omFilteredLucene40Postings), n_ti1=3DPostingsFormat(name=3DMockSep), range_= facet_l=3DPostingsFormat(name=3DMockRandom), text=3DPulsing40(freqCutoff=3D= 16 minBlockSize=3D62 maxBlockSize=3D122), _version_=3DPostingsFormat(name= =3DMockRandom), SubjectTerms_mfacet=3DPostingsFormat(name=3DMockRandom), a_= t=3DPostingsFormat(name=3DMockRandom), n_tdt1=3DPostingsFormat(name=3DMockR= andom), other_tl1=3DPostingsFormat(name=3DMockRandom), n_l1=3DPostingsForma= t(name=3DMockRandom), a_si=3DPostingsFormat(name=3DTestBloomFilteredLucene4= 0Postings)}, sim=3DDefaultSimilarity, locale=3Dar_JO, timezone=3DAfrica/Acc= ra [junit4:junit4] 2> NOTE: FreeBSD 9.0-RELEASE amd64/Oracle Corporation 1.7= .0_06 (64-bit)/cpus=3D16,threads=3D3,free=3D150599008,total=3D328204288 [junit4:junit4] 2> NOTE: All tests run in this JVM: [TestBadConfig, TestN= umberUtils, TestCharFilters, SearchHandlerTest, SuggesterFSTTest, SolrReque= stParserTest, HighlighterConfigTest, DateMathParserTest, CircularListTest, = DefaultValueUpdateProcessorTest, TestLFUCache, TestLMJelinekMercerSimilarit= yFactory, RequestHandlersTest, TestFastOutputStream, TestQueryTypes, TestRe= load, TestElisionMultitermQuery, DistributedTermsComponentTest, AnalysisAft= erCoreReloadTest, TestSolrJ, DistanceFunctionTest, BadComponentTest, JSONWr= iterTest, TestGroupingSearch, SimpleFacetsTest, TestStressReorder, UpdateRe= questProcessorFactoryTest, NumericFieldsTest, FieldAnalysisRequestHandlerTe= st, SortByFunctionTest, WordBreakSolrSpellCheckerTest, TestFastLRUCache, Sp= ellCheckComponentTest, ZkControllerTest, TestSystemIdResolver, DateFieldTes= t, TestWordDelimiterFilterFactory, SolrCmdDistributorTest, SpatialFilterTes= t, TestSearchPerf, CoreContainerCoreInitFailuresTest, FastVectorHighlighter= Test, TestPerFieldSimilarity, TestLuceneMatchVersion, BasicDistributedZkTes= t, BinaryUpdateRequestHandlerTest, TestExtendedDismaxParser, FileBasedSpell= CheckerTest, TestIBSimilarityFactory, SuggesterWFSTTest, PreAnalyzedFieldTe= st, TestPseudoReturnFields, ClusterStateTest, RecoveryZkTest, FullSolrCloud= DistribCmdsTest, LeaderElectionIntegrationTest, TestRandomFaceting, TestJoi= n, PeerSyncTest, QueryElevationComponentTest, SignatureUpdateProcessorFacto= ryTest, TestBinaryResponseWriter, TestJmxIntegration, SampleTest, Highlight= erTest, TestIndexSearcher, CoreAdminHandlerTest, DirectSolrConnectionTest, = TestRemoteStreaming, CacheHeaderTest, TestCSVResponseWriter, MoreLikeThisHa= ndlerTest, MBeansHandlerTest, CurrencyFieldTest, DisMaxRequestHandlerTest, = IndexSchemaTest, QueryParsingTest, TermsComponentTest, SolrInfoMBeanTest, X= sltUpdateRequestHandlerTest, MinimalSchemaTest, TestPHPSerializedResponseWr= iter, XmlUpdateRequestHandlerTest, JsonLoaderTest, TestSurroundQueryParser,= DocumentAnalysisRequestHandlerTest, DirectUpdateHandlerOptimizeTest, Index= SchemaRuntimeFieldTest, TestSolrQueryParser, SolrPluginUtilsTest, TestRever= sedWildcardFilterFactory, RequiredFieldsTest, TestOmitPositions, CopyFieldT= est, TestArbitraryIndexDir, LoggingHandlerTest, UniqFieldsUpdateProcessorFa= ctoryTest, CSVRequestHandlerTest, ShowFileRequestHandlerTest, NotRequiredUn= iqueKeyTest, TestFoldingMultitermQuery, SolrCoreCheckLockOnStartupTest, Tes= tIndexingPerformance, NoCacheHeaderTest, PrimitiveFieldTypeTest, AlternateD= irectoryTest, FieldMutatingUpdateProcessorTest, TestUpdate, TestBinaryField= , TestSolrDeletionPolicy2, SOLR749Test, TestPropInjectDefaults, TestXInclud= eConfig, TestCollationField, SolrIndexConfigTest, TestQuerySenderNoQuery, I= ndexReaderFactoryTest, TestSolrCoreProperties, TestJmxMonitoredMap, TestCon= fig, SpellPossibilityIteratorTest, TestBM25SimilarityFactory, TestPhraseSug= gestions, TestLMDirichletSimilarityFactory, LegacyHTMLStripCharFilterTest, = TestDFRSimilarityFactory, TestDefaultSimilarityFactory, URLClassifyProcesso= rTest, PluginInfoTest, PrimUtilsTest, ResourceLoaderTest, FileUtilsTest, Te= stSuggestSpellingConverter, SystemInfoHandlerTest, TestDocumentBuilder, Pat= hHierarchyTokenizerFactoryTest, BasicDistributedZk2Test] [junit4:junit4] Completed on J0 in 110.26s, 1 test, 1 error <<< FAILURES! [...truncated 41 lines...] BUILD FAILED /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/build.x= ml:29: The following error occurred while executing this line: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/bu= ild.xml:146: The following error occurred while executing this line: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/= module-build.xml:63: The following error occurred while executing this line= : /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/= common-build.xml:1133: The following error occurred while executing this li= ne: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/= common-build.xml:810: There were test failures: 229 suites, 927 tests, 1 er= ror, 6 ignored (1 assumption) Total time: 37 minutes 4 seconds Build step 'Invoke Ant' marked build as failure Recording test results Email was triggered for: Failure Sending email for trigger: Failure ------=_Part_351_1373457259.1347568495504 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_351_1373457259.1347568495504--