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 856FDD572 for ; Tue, 11 Sep 2012 00:12:36 +0000 (UTC) Received: (qmail 54264 invoked by uid 500); 11 Sep 2012 00:12:32 -0000 Delivered-To: apmail-lucene-dev-archive@lucene.apache.org Received: (qmail 54218 invoked by uid 500); 11 Sep 2012 00:12:31 -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 54209 invoked by uid 99); 11 Sep 2012 00:12:31 -0000 Received: from nike.apache.org (HELO nike.apache.org) (192.87.106.230) by apache.org (qpsmtpd/0.29) with ESMTP; Tue, 11 Sep 2012 00:12:31 +0000 X-ASF-Spam-Status: No, hits=0.0 required=5.0 tests=NORMAL_HTTP_TO_IP,SPF_PASS,WEIRD_PORT X-Spam-Check-By: apache.org Received-SPF: pass (nike.apache.org: local policy) Received: from [188.138.97.18] (HELO mail.sd-datasolutions.de) (188.138.97.18) by apache.org (qpsmtpd/0.29) with ESMTP; Tue, 11 Sep 2012 00:12:11 +0000 Received: from serv1 (localhost.localdomain [127.0.0.1]) by mail.sd-datasolutions.de (Postfix) with ESMTP id A2A8A14AA06C for ; Tue, 11 Sep 2012 00:11:50 +0000 (UTC) Date: Tue, 11 Sep 2012 00:11:42 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org Message-ID: <24957717.17.1347322310633.JavaMail.jenkins@serv1.sd-datasolutions.de> Subject: [JENKINS] Lucene-Solr-4.x-Windows (32bit/jdk1.6.0_35) - Build # 712 - Failure! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_16_1603519094.1347322302032" ------=_Part_16_1603519094.1347322302032 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-4.x-Windows/712/ Java: 32bit/jdk1.6.0_35 -server -XX:+UseConcMarkSweepGC 1 tests failed. FAILED: junit.framework.TestSuite.org.apache.solr.cloud.LeaderElectionInte= grationTest Error Message: 1 thread leaked from SUITE scope at org.apache.solr.cloud.LeaderElectionInt= egrationTest: 1) Thread[id=3D2512, name=3DSessionTracker, state=3DTIMED= _WAITING, group=3DTGRP-LeaderElectionIntegrationTest] at java.lang.= Object.wait(Native Method) at org.apache.zookeeper.server.SessionTr= ackerImpl.run(SessionTrackerImpl.java:145) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SU= ITE scope at org.apache.solr.cloud.LeaderElectionIntegrationTest:=20 1) Thread[id=3D2512, name=3DSessionTracker, state=3DTIMED_WAITING, group= =3DTGRP-LeaderElectionIntegrationTest] at java.lang.Object.wait(Native Method) at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTracke= rImpl.java:145) =09at __randomizedtesting.SeedInfo.seed([4AC9E8D0F70D13C6]:0) Build Log: [...truncated 7217 lines...] [junit4:junit4] Suite: org.apache.solr.cloud.LeaderElectionIntegrationTest [junit4:junit4] 2> 4 T2509 oas.SolrTestCaseJ4.setUp ###Starting testSimpl= eSliceLeaderElection [junit4:junit4] 2> Creating dataDir: C:\Jenkins\workspace\Lucene-Solr-4.x= -Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationT= est-1347321837212 [junit4:junit4] 2> 5 T2509 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4:junit4] 2> 11 T2510 oazs.ZooKeeperServer.setTickTime tickTime set= to 3000 [junit4:junit4] 2> 11 T2510 oazs.NIOServerCnxn$Factory. binding to = port 0.0.0.0/0.0.0.0:0 [junit4:junit4] 2> 12 T2510 oazsp.FileTxnSnapLog.save Snapshotting: 0 [junit4:junit4] 2> 107 T2509 oasc.ZkTestServer.run start zk server on por= t:52160 [junit4:junit4] 2> 107 T2509 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:52160 sessionTimeout=3D10000 watcher=3Dorg= .apache.solr.common.cloud.ConnectionManager@1a5b5cb [junit4:junit4] 2> 108 T2515 oaz.ClientCnxn$SendThread.startConnect Openi= ng socket connection to server /127.0.0.1:52160 [junit4:junit4] 2> 108 T2509 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 109 T2515 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to 127.0.0.1/127.0.0.1:52160, initiating sessio= n [junit4:junit4] 2> 110 T2511 oazs.NIOServerCnxn$Factory.run Accepted sock= et connection from /127.0.0.1:52163 [junit4:junit4] 2> 110 T2511 oazs.NIOServerCnxn.readConnectRequest Client= attempting to establish new session at /127.0.0.1:52163 [junit4:junit4] 2> 110 T2513 oazsp.FileTxnLog.append Creating new log fil= e: log.1 [junit4:junit4] 2> 116 T2513 oazs.NIOServerCnxn.finishSessionInit Establi= shed session 0x139b2a3b6a70000 with negotiated timeout 10000 for client /12= 7.0.0.1:52163 [junit4:junit4] 2> 116 T2515 oaz.ClientCnxn$SendThread.readConnectResult = Session establishment complete on server 127.0.0.1/127.0.0.1:52160, session= id =3D 0x139b2a3b6a70000, negotiated timeout =3D 10000 [junit4:junit4] 2> 117 T2516 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@1a5b5cb name:ZooKeeperConnection= Watcher:127.0.0.1:52160 got event WatchedEvent state:SyncConnected type:No= ne path:null path:null type:None [junit4:junit4] 2> 117 T2509 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 117 T2509 oascc.SolrZkClient.makePath makePath: /solr [junit4:junit4] 2> 129 T2514 oazs.PrepRequestProcessor.pRequest Processed= session termination for sessionid: 0x139b2a3b6a70000 [junit4:junit4] 2> 131 T2516 oaz.ClientCnxn$EventThread.run EventThread s= hut down [junit4:junit4] 2> 131 T2509 oaz.ZooKeeper.close Session: 0x139b2a3b6a700= 00 closed [junit4:junit4] 2> 131 T2509 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:52160/solr sessionTimeout=3D10000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@9dd4b1 [junit4:junit4] 2> 132 T2511 oazs.NIOServerCnxn.doIO WARNING EndOfStreamE= xception: Unable to read additional data from client sessionid 0x139b2a3b6a= 70000, likely client has closed socket [junit4:junit4] 2> 133 T2511 oazs.NIOServerCnxn.closeSock Closed socket c= onnection for client /127.0.0.1:52163 which had sessionid 0x139b2a3b6a70000 [junit4:junit4] 2> 133 T2517 oaz.ClientCnxn$SendThread.startConnect Openi= ng socket connection to server /127.0.0.1:52160 [junit4:junit4] 2> 133 T2509 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 135 T2517 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to 127.0.0.1/127.0.0.1:52160, initiating sessio= n [junit4:junit4] 2> 135 T2511 oazs.NIOServerCnxn$Factory.run Accepted sock= et connection from /127.0.0.1:52166 [junit4:junit4] 2> 135 T2511 oazs.NIOServerCnxn.readConnectRequest Client= attempting to establish new session at /127.0.0.1:52166 [junit4:junit4] 2> 149 T2513 oazs.NIOServerCnxn.finishSessionInit Establi= shed session 0x139b2a3b6a70001 with negotiated timeout 10000 for client /12= 7.0.0.1:52166 [junit4:junit4] 2> 150 T2517 oaz.ClientCnxn$SendThread.readConnectResult = Session establishment complete on server 127.0.0.1/127.0.0.1:52160, session= id =3D 0x139b2a3b6a70001, negotiated timeout =3D 10000 [junit4:junit4] 2> 150 T2518 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@9dd4b1 name:ZooKeeperConnection = Watcher:127.0.0.1:52160/solr got event WatchedEvent state:SyncConnected typ= e:None path:null path:null type:None [junit4:junit4] 2> 150 T2509 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 150 T2509 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1 [junit4:junit4] 2> 157 T2509 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1/shards [junit4:junit4] 2> 163 T2509 oascc.SolrZkClient.makePath makePath: /colle= ctions/control_collection [junit4:junit4] 2> 167 T2509 oascc.SolrZkClient.makePath makePath: /colle= ctions/control_collection/shards [junit4:junit4] 2> 172 T2509 oasc.AbstractZkTestCase.putConfig put C:\Jen= kins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr= \collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml [junit4:junit4] 2> 173 T2509 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/solrconfig.xml [junit4:junit4] 2> 184 T2509 oasc.AbstractZkTestCase.putConfig put C:\Jen= kins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr= \collection1\conf\schema.xml to /configs/conf1/schema.xml [junit4:junit4] 2> 186 T2509 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/schema.xml [junit4:junit4] 2> 194 T2509 oasc.AbstractZkTestCase.putConfig put C:\Jen= kins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr= \collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml [junit4:junit4] 2> 196 T2509 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/solrconfig.xml [junit4:junit4] 2> 202 T2509 oasc.AbstractZkTestCase.putConfig put C:\Jen= kins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr= \collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt [junit4:junit4] 2> 203 T2509 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/stopwords.txt [junit4:junit4] 2> 210 T2509 oasc.AbstractZkTestCase.putConfig put C:\Jen= kins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr= \collection1\conf\protwords.txt to /configs/conf1/protwords.txt [junit4:junit4] 2> 212 T2509 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/protwords.txt [junit4:junit4] 2> 219 T2509 oasc.AbstractZkTestCase.putConfig put C:\Jen= kins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr= \collection1\conf\currency.xml to /configs/conf1/currency.xml [junit4:junit4] 2> 220 T2509 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/currency.xml [junit4:junit4] 2> 231 T2509 oasc.AbstractZkTestCase.putConfig put C:\Jen= kins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr= \collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-= rates.json [junit4:junit4] 2> 232 T2509 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/open-exchange-rates.json [junit4:junit4] 2> 237 T2509 oasc.AbstractZkTestCase.putConfig put C:\Jen= kins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr= \collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISO= Latin1Accent.txt [junit4:junit4] 2> 239 T2509 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/mapping-ISOLatin1Accent.txt [junit4:junit4] 2> 244 T2509 oasc.AbstractZkTestCase.putConfig put C:\Jen= kins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr= \collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4:junit4] 2> 245 T2509 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/old_synonyms.txt [junit4:junit4] 2> 249 T2509 oasc.AbstractZkTestCase.putConfig put C:\Jen= kins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr= \collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt [junit4:junit4] 2> 250 T2509 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/synonyms.txt [junit4:junit4] 2> 254 T2514 oazs.PrepRequestProcessor.pRequest Processed= session termination for sessionid: 0x139b2a3b6a70001 [junit4:junit4] 2> 257 T2518 oaz.ClientCnxn$EventThread.run EventThread s= hut down [junit4:junit4] 2> 257 T2511 oazs.NIOServerCnxn.doIO WARNING EndOfStreamE= xception: Unable to read additional data from client sessionid 0x139b2a3b6a= 70001, likely client has closed socket [junit4:junit4] 2> 256 T2509 oaz.ZooKeeper.close Session: 0x139b2a3b6a700= 01 closed [junit4:junit4] 2> 258 T2511 oazs.NIOServerCnxn.closeSock Closed socket c= onnection for client /127.0.0.1:52166 which had sessionid 0x139b2a3b6a70001 [junit4:junit4] 2> 258 T2509 oasc.LeaderElectionIntegrationTest.setUp ###= #SETUP_START testSimpleSliceLeaderElection [junit4:junit4] 2> 262 T2509 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 264 T2509 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-Windo= ws\solr\build\solr-core\test-files\solr [junit4:junit4] 2> 264 T2509 oasc.CoreContainer$Initializer.initialize lo= oking for solr.xml: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build= \solr-core\test-files\solr\solr.xml [junit4:junit4] 2> 265 T2509 oasc.CoreContainer. New CoreContainer = 9261682 [junit4:junit4] 2> 266 T2509 oasc.CoreContainer.load Loading CoreContaine= r using Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build= \solr-core\test-files\solr\' [junit4:junit4] 2> 267 T2509 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr= \build\solr-core\test-files\solr\' [junit4:junit4] 2> 312 T2509 oasc.CoreContainer.load Registering Log List= ener [junit4:junit4] 2> 341 T2509 oasc.CoreContainer.initZooKeeper Zookeeper c= lient=3D127.0.0.1:52160/solr [junit4:junit4] 2> 342 T2509 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:52160/solr sessionTimeout=3D3000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@8ba538 [junit4:junit4] 2> 344 T2519 oaz.ClientCnxn$SendThread.startConnect Openi= ng socket connection to server /127.0.0.1:52160 [junit4:junit4] 2> 344 T2509 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 346 T2519 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to 127.0.0.1/127.0.0.1:52160, initiating sessio= n [junit4:junit4] 2> 347 T2511 oazs.NIOServerCnxn$Factory.run Accepted sock= et connection from /127.0.0.1:52169 [junit4:junit4] 2> 347 T2511 oazs.NIOServerCnxn.readConnectRequest Client= attempting to establish new session at /127.0.0.1:52169 [junit4:junit4] 2> 350 T2513 oazs.NIOServerCnxn.finishSessionInit Establi= shed session 0x139b2a3b6a70002 with negotiated timeout 6000 for client /127= .0.0.1:52169 [junit4:junit4] 2> 350 T2519 oaz.ClientCnxn$SendThread.readConnectResult = Session establishment complete on server 127.0.0.1/127.0.0.1:52160, session= id =3D 0x139b2a3b6a70002, negotiated timeout =3D 6000 [junit4:junit4] 2> 351 T2520 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@8ba538 name:ZooKeeperConnection = Watcher:127.0.0.1:52160/solr got event WatchedEvent state:SyncConnected typ= e:None path:null path:null type:None [junit4:junit4] 2> 351 T2509 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 355 T2514 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139b2a3b6a70002 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> 357 T2509 oascc.SolrZkClient.makePath makePath: /live_= nodes [junit4:junit4] 2> 362 T2509 oasc.ZkController.createEphemeralLiveNode Re= gister node as live in ZooKeeper:/live_nodes/127.0.0.1:7000_solr [junit4:junit4] 2> 362 T2514 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139b2a3b6a70002 type:dele= te cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:= /solr/live_nodes/127.0.0.1:7000_solr Error:KeeperErrorCode =3D NoNode for /= solr/live_nodes/127.0.0.1:7000_solr [junit4:junit4] 2> 364 T2509 oascc.SolrZkClient.makePath makePath: /live_= nodes/127.0.0.1:7000_solr [junit4:junit4] 2> 375 T2509 oashc.HttpShardHandlerFactory.getParameter S= etting socketTimeout to: 0 [junit4:junit4] 2> 376 T2509 oashc.HttpShardHandlerFactory.getParameter S= etting urlScheme to: http:// [junit4:junit4] 2> 376 T2509 oashc.HttpShardHandlerFactory.getParameter S= etting connTimeout to: 0 [junit4:junit4] 2> 377 T2509 oashc.HttpShardHandlerFactory.getParameter S= etting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 377 T2509 oashc.HttpShardHandlerFactory.getParameter S= etting corePoolSize to: 0 [junit4:junit4] 2> 378 T2509 oashc.HttpShardHandlerFactory.getParameter S= etting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 378 T2509 oashc.HttpShardHandlerFactory.getParameter S= etting maxThreadIdleTime to: 5 [junit4:junit4] 2> 378 T2509 oashc.HttpShardHandlerFactory.getParameter S= etting sizeOfQueue to: -1 [junit4:junit4] 2> 379 T2509 oashc.HttpShardHandlerFactory.getParameter S= etting fairnessPolicy to: false [junit4:junit4] 2> 379 T2509 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&s= ocketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 389 T2509 oascc.SolrZkClient.makePath makePath: /overs= eer_elect/election [junit4:junit4] 2> 398 T2514 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139b2a3b6a70002 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> 400 T2509 oascc.SolrZkClient.makePath makePath: /overs= eer_elect/leader [junit4:junit4] 2> 405 T2509 oasc.Overseer.start Overseer (id=3D882980839= 24246530-127.0.0.1:7000_solr-n_0000000000) starting [junit4:junit4] 2> 406 T2514 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139b2a3b6a70002 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> 409 T2514 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139b2a3b6a70002 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> 411 T2514 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139b2a3b6a70002 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> 414 T2514 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x139b2a3b6a70002 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> 417 T2522 oasc.OverseerCollectionProcessor.run Process= current queue of collection creations [junit4:junit4] 2> 417 T2509 oascc.SolrZkClient.makePath makePath: /clust= erstate.json [junit4:junit4] 2> 420 T2509 oascc.ZkStateReader.createClusterStateWatche= rsAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 423 T2521 oasc.Overseer$ClusterStateUpdater.run Starti= ng to work on the main queue [junit4:junit4] 2> 428 T2509 oasc.CoreContainer.create Creating SolrCore = 'collection1' using instanceDir: C:\Jenkins\workspace\Lucene-Solr-4.x-Windo= ws\solr\build\solr-core\test-files\solr\collection1 [junit4:junit4] 2> 428 T2509 oasc.ZkController.createCollectionZkNode Che= ck for collection zkNode:collection1 [junit4:junit4] 2> 430 T2509 oasc.ZkController.createCollectionZkNode Col= lection zkNode exists [junit4:junit4] 2> 430 T2509 oasc.ZkController.readConfigName Load collec= tion config from:/collections/collection1 [junit4:junit4] 2> 432 T2509 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr= \build\solr-core\test-files\solr\collection1\' [junit4:junit4] 2> 434 T2509 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-c= ore/test-files/solr/collection1/lib/classes/' to classloader [junit4:junit4] 2> 435 T2509 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-c= ore/test-files/solr/collection1/lib/README' to classloader [junit4:junit4] 2> 501 T2509 oasc.SolrConfig. Using Lucene MatchVer= sion: LUCENE_40 [junit4:junit4] 2> 601 T2509 oasc.SolrConfig. Loaded SolrConfig: so= lrconfig.xml [junit4:junit4] 2> 603 T2509 oass.IndexSchema.readSchema Reading Solr Sch= ema [junit4:junit4] 2> 611 T2509 oass.IndexSchema.readSchema Schema name=3Dte= st [junit4:junit4] 2> 1500 T2509 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 1512 T2509 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 1518 T2509 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 1536 T2509 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 1542 T2509 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 1548 T2509 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 1550 T2509 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 1551 T2509 oasc.SolrCore. [collection1] Opening = new SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\sol= r-core\test-files\solr\collection1\, dataDir=3DC:\Jenkins\workspace\Lucene-= Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionInte= grationTest-1347321837212\data_7000\ [junit4:junit4] 2> 1552 T2509 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@ed9f47 [junit4:junit4] 2> 1553 T2509 oasc.SolrCore.getNewIndexDir WARNING New in= dex directory detected: old=3Dnull new=3DC:\Jenkins\workspace\Lucene-Solr-4= .x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegratio= nTest-1347321837212\data_7000\index/ [junit4:junit4] 2> 1555 T2509 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\= build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321837= 212\data_7000\index' doesn't exist. Creating new index... [junit4:junit4] 2> 1555 T2509 oasc.CachingDirectoryFactory.get return new= directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr= -core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321837212\data_7= 000\index forceNew:false [junit4:junit4] 2> 1558 T2509 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@1c9f7fc lockFactory=3Dorg.apache.lucene.store.NativeFSLock= Factory@aeb161),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 1559 T2509 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1 [junit4:junit4] 2> 1560 T2509 oasc.SolrCore.initWriters created xml: solr= .XMLResponseWriter [junit4:junit4] 2> 1562 T2509 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 1563 T2509 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe-allfields" [junit4:junit4] 2> 1563 T2509 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 1564 T2509 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "u= niq-fields" [junit4:junit4] 2> 1564 T2509 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 1564 T2509 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 1565 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 1565 T2509 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 1566 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 1567 T2509 oasc.RequestHandlers.initHandlersFromConfig= created dismax: solr.SearchHandler [junit4:junit4] 2> 1567 T2509 oasc.RequestHandlers.initHandlersFromConfig= created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 1568 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 1568 T2509 oasc.RequestHandlers.initHandlersFromConfig= created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 1569 T2509 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 1569 T2509 oasc.RequestHandlers.initHandlersFromConfig= created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 1570 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 1570 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 1570 T2509 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 1571 T2509 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 1571 T2509 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 1572 T2509 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.= SearchHandler [junit4:junit4] 2> 1573 T2509 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 1573 T2509 oasc.RequestHandlers.initHandlersFromConfig= created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 1574 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 1574 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 1578 T2509 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 1583 T2509 oasc.SolrCore.initDeprecatedSupport WARNING= solrconfig.xml uses deprecated , Please update your c= onfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 1585 T2509 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> 1587 T2509 oass.SolrIndexSearcher. Opening Searc= her@113fa54 main [junit4:junit4] 2> 1587 T2509 oass.SolrIndexSearcher.getIndexDir WARNING = WARNING: Directory impl does not support setting indexDir: org.apache.lucen= e.store.MockDirectoryWrapper [junit4:junit4] 2> 1587 T2509 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 1587 T2509 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 1588 T2509 oashc.SpellCheckComponent.inform Initializi= ng spell checkers [junit4:junit4] 2> 1600 T2509 oass.DirectSolrSpellChecker.init init: {nam= e=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLen= gth=3D3} [junit4:junit4] 2> 1666 T2523 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@113fa54 main{StandardDirectoryReader(seg= ments_1:1)} [junit4:junit4] 2> 1671 T2514 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139b2a3b6a70002 type:cre= ate cxid:0x56 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer/queue Error:KeeperErrorCode =3D NoNode for /solr/overseer/= queue [junit4:junit4] 2> 1679 T2509 oasc.CoreContainer.register registering cor= e: collection1 [junit4:junit4] 2> 1679 T2509 oasc.ZkController.register Register shard -= core:collection1 address:http://127.0.0.1:7000/solr shardId:shard1 [junit4:junit4] 2> 1680 T2509 oascc.SolrZkClient.makePath makePath: /coll= ections/collection1/leader_elect/shard1/election [junit4:junit4] 2> 1692 T2514 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139b2a3b6a70002 type:del= ete cxid:0x65 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> 1694 T2509 oasc.ShardLeaderElectionContext.runLeaderPr= ocess Running the leader process. afterExpiration=3Dfalse [junit4:junit4] 2> 1695 T2514 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139b2a3b6a70002 type:cre= ate cxid:0x66 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 1698 T2509 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I am the new leader: http://127.0.0.1:7000/solr/collection1/ [junit4:junit4] 2> 1698 T2509 oascc.SolrZkClient.makePath makePath: /coll= ections/collection1/leaders/shard1 [junit4:junit4] 2> 1705 T2514 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139b2a3b6a70002 type:cre= ate cxid:0x6f zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pat= h:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 1935 T2521 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 1936 T2514 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139b2a3b6a70002 type:cre= ate cxid:0x77 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> 1959 T2520 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 2015 T2509 oasc.ZkController.register We are http://12= 7.0.0.1:7000/solr/collection1/ and leader is http://127.0.0.1:7000/solr/col= lection1/ [junit4:junit4] 2> 2015 T2509 oasc.ZkController.checkRecovery WARNING Ski= pping recovery according to sys prop solrcloud.skip.autorecovery [junit4:junit4] 2> 2019 T2509 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 2024 T2509 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 2024 T2509 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-Wind= ows\solr\build\solr-core\test-files\solr [junit4:junit4] 2> 2025 T2509 oasc.CoreContainer$Initializer.initialize l= ooking for solr.xml: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\buil= d\solr-core\test-files\solr\solr.xml [junit4:junit4] 2> 2025 T2509 oasc.CoreContainer. New CoreContainer= 13898930 [junit4:junit4] 2> 2026 T2509 oasc.CoreContainer.load Loading CoreContain= er using Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\buil= d\solr-core\test-files\solr\' [junit4:junit4] 2> 2026 T2509 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\sol= r\build\solr-core\test-files\solr\' [junit4:junit4] 2> 2075 T2509 oasc.CoreContainer.load Registering Log Lis= tener [junit4:junit4] 2> 2092 T2509 oasc.CoreContainer.initZooKeeper Zookeeper = client=3D127.0.0.1:52160/solr [junit4:junit4] 2> 2093 T2509 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:52160/solr sessionTimeout=3D3000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@18b62bb [junit4:junit4] 2> 2095 T2524 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:52160 [junit4:junit4] 2> 2095 T2509 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 2097 T2524 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to 127.0.0.1/127.0.0.1:52160, initiating sessi= on [junit4:junit4] 2> 2097 T2511 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /127.0.0.1:52172 [junit4:junit4] 2> 2098 T2511 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /127.0.0.1:52172 [junit4:junit4] 2> 2100 T2513 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x139b2a3b6a70003 with negotiated timeout 6000 for client /12= 7.0.0.1:52172 [junit4:junit4] 2> 2100 T2524 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server 127.0.0.1/127.0.0.1:52160, sessio= nid =3D 0x139b2a3b6a70003, negotiated timeout =3D 6000 [junit4:junit4] 2> 2100 T2525 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@18b62bb name:ZooKeeperConnectio= n Watcher:127.0.0.1:52160/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 2101 T2509 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 2102 T2514 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139b2a3b6a70003 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> 2104 T2514 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139b2a3b6a70003 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> 2106 T2509 oasc.ZkController.createEphemeralLiveNode R= egister node as live in ZooKeeper:/live_nodes/127.0.0.1:7001_solr [junit4:junit4] 2> 2107 T2514 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139b2a3b6a70003 type:del= ete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/live_nodes/127.0.0.1:7001_solr Error:KeeperErrorCode =3D NoNode for = /solr/live_nodes/127.0.0.1:7001_solr [junit4:junit4] 2> 2108 T2509 oascc.SolrZkClient.makePath makePath: /live= _nodes/127.0.0.1:7001_solr [junit4:junit4] 2> 2112 T2520 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 2113 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting socketTimeout to: 0 [junit4:junit4] 2> 2114 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting urlScheme to: http:// [junit4:junit4] 2> 2114 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting connTimeout to: 0 [junit4:junit4] 2> 2114 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 2114 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting corePoolSize to: 0 [junit4:junit4] 2> 2115 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 2115 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 2115 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting sizeOfQueue to: -1 [junit4:junit4] 2> 2116 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting fairnessPolicy to: false [junit4:junit4] 2> 2116 T2509 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&= socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 2128 T2509 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 2136 T2509 oasc.CoreContainer.create Creating SolrCore= 'collection1' using instanceDir: C:\Jenkins\workspace\Lucene-Solr-4.x-Wind= ows\solr\build\solr-core\test-files\solr\collection1 [junit4:junit4] 2> 2136 T2509 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:collection1 [junit4:junit4] 2> 2138 T2509 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 2138 T2509 oasc.ZkController.readConfigName Load colle= ction config from:/collections/collection1 [junit4:junit4] 2> 2140 T2509 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\sol= r\build\solr-core\test-files\solr\collection1\' [junit4:junit4] 2> 2143 T2509 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-= core/test-files/solr/collection1/lib/classes/' to classloader [junit4:junit4] 2> 2146 T2509 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-= core/test-files/solr/collection1/lib/README' to classloader [junit4:junit4] 2> 2202 T2509 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_40 [junit4:junit4] 2> 2289 T2509 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 2290 T2509 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 2297 T2509 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 2475 T2521 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 2486 T2520 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 2486 T2525 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 3114 T2509 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 3126 T2509 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 3130 T2509 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 3151 T2509 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 3157 T2509 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 3163 T2509 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 3165 T2509 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 3167 T2509 oasc.SolrCore. [collection1] Opening = new SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\sol= r-core\test-files\solr\collection1\, dataDir=3DC:\Jenkins\workspace\Lucene-= Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionInte= grationTest-1347321837212\data_7001\ [junit4:junit4] 2> 3167 T2509 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@ed9f47 [junit4:junit4] 2> 3169 T2509 oasc.SolrCore.getNewIndexDir WARNING New in= dex directory detected: old=3Dnull new=3DC:\Jenkins\workspace\Lucene-Solr-4= .x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegratio= nTest-1347321837212\data_7001\index/ [junit4:junit4] 2> 3170 T2509 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\= build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321837= 212\data_7001\index' doesn't exist. Creating new index... [junit4:junit4] 2> 3171 T2509 oasc.CachingDirectoryFactory.get return new= directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr= -core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321837212\data_7= 001\index forceNew:false [junit4:junit4] 2> 3173 T2509 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@9fe69a lockFactory=3Dorg.apache.lucene.store.NativeFSLockF= actory@2bb272),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 3173 T2509 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1 [junit4:junit4] 2> 3174 T2509 oasc.SolrCore.initWriters created xml: solr= .XMLResponseWriter [junit4:junit4] 2> 3176 T2509 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 3177 T2509 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe-allfields" [junit4:junit4] 2> 3177 T2509 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 3178 T2509 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "u= niq-fields" [junit4:junit4] 2> 3179 T2509 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 3179 T2509 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 3179 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 3184 T2509 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 3185 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 3185 T2509 oasc.RequestHandlers.initHandlersFromConfig= created dismax: solr.SearchHandler [junit4:junit4] 2> 3186 T2509 oasc.RequestHandlers.initHandlersFromConfig= created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 3187 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 3187 T2509 oasc.RequestHandlers.initHandlersFromConfig= created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 3188 T2509 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 3188 T2509 oasc.RequestHandlers.initHandlersFromConfig= created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 3189 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 3189 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 3190 T2509 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 3190 T2509 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 3191 T2509 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 3191 T2509 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.= SearchHandler [junit4:junit4] 2> 3192 T2509 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 3192 T2509 oasc.RequestHandlers.initHandlersFromConfig= created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 3193 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 3193 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 3198 T2509 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 3204 T2509 oasc.SolrCore.initDeprecatedSupport WARNING= solrconfig.xml uses deprecated , Please update your c= onfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 3205 T2509 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> 3209 T2509 oass.SolrIndexSearcher. Opening Searc= her@1f1cea3 main [junit4:junit4] 2> 3209 T2509 oass.SolrIndexSearcher.getIndexDir WARNING = WARNING: Directory impl does not support setting indexDir: org.apache.lucen= e.store.MockDirectoryWrapper [junit4:junit4] 2> 3210 T2509 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 3210 T2509 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 3211 T2509 oashc.SpellCheckComponent.inform Initializi= ng spell checkers [junit4:junit4] 2> 3223 T2509 oass.DirectSolrSpellChecker.init init: {nam= e=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLen= gth=3D3} [junit4:junit4] 2> 3284 T2526 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@1f1cea3 main{StandardDirectoryReader(seg= ments_1:1)} [junit4:junit4] 2> 3291 T2509 oasc.CoreContainer.register registering cor= e: collection1 [junit4:junit4] 2> 3291 T2509 oasc.ZkController.register Register shard -= core:collection1 address:http://127.0.0.1:7001/solr shardId:shard1 [junit4:junit4] 2> 3296 T2509 oasc.ZkController.register We are http://12= 7.0.0.1:7001/solr/collection1/ and leader is http://127.0.0.1:7000/solr/col= lection1/ [junit4:junit4] 2> 3296 T2509 oasc.ZkController.checkRecovery WARNING Ski= pping recovery according to sys prop solrcloud.skip.autorecovery [junit4:junit4] 2> 3298 T2509 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 3303 T2509 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 3303 T2509 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-Wind= ows\solr\build\solr-core\test-files\solr [junit4:junit4] 2> 3303 T2509 oasc.CoreContainer$Initializer.initialize l= ooking for solr.xml: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\buil= d\solr-core\test-files\solr\solr.xml [junit4:junit4] 2> 3304 T2509 oasc.CoreContainer. New CoreContainer= 24126967 [junit4:junit4] 2> 3305 T2509 oasc.CoreContainer.load Loading CoreContain= er using Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\buil= d\solr-core\test-files\solr\' [junit4:junit4] 2> 3305 T2509 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\sol= r\build\solr-core\test-files\solr\' [junit4:junit4] 2> 3348 T2509 oasc.CoreContainer.load Registering Log Lis= tener [junit4:junit4] 2> 3373 T2509 oasc.CoreContainer.initZooKeeper Zookeeper = client=3D127.0.0.1:52160/solr [junit4:junit4] 2> 3373 T2509 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:52160/solr sessionTimeout=3D3000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@5ce511 [junit4:junit4] 2> 3375 T2527 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:52160 [junit4:junit4] 2> 3375 T2509 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 3377 T2527 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to 127.0.0.1/127.0.0.1:52160, initiating sessi= on [junit4:junit4] 2> 3377 T2511 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /127.0.0.1:52175 [junit4:junit4] 2> 3377 T2511 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /127.0.0.1:52175 [junit4:junit4] 2> 3379 T2513 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x139b2a3b6a70004 with negotiated timeout 6000 for client /12= 7.0.0.1:52175 [junit4:junit4] 2> 3379 T2527 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server 127.0.0.1/127.0.0.1:52160, sessio= nid =3D 0x139b2a3b6a70004, negotiated timeout =3D 6000 [junit4:junit4] 2> 3379 T2528 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@5ce511 name:ZooKeeperConnection= Watcher:127.0.0.1:52160/solr got event WatchedEvent state:SyncConnected ty= pe:None path:null path:null type:None [junit4:junit4] 2> 3379 T2509 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 3380 T2514 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139b2a3b6a70004 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> 3382 T2514 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139b2a3b6a70004 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> 3384 T2509 oasc.ZkController.createEphemeralLiveNode R= egister node as live in ZooKeeper:/live_nodes/127.0.0.1:7002_solr [junit4:junit4] 2> 3385 T2514 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139b2a3b6a70004 type:del= ete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/live_nodes/127.0.0.1:7002_solr Error:KeeperErrorCode =3D NoNode for = /solr/live_nodes/127.0.0.1:7002_solr [junit4:junit4] 2> 3387 T2509 oascc.SolrZkClient.makePath makePath: /live= _nodes/127.0.0.1:7002_solr [junit4:junit4] 2> 3392 T2525 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 3392 T2520 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 3394 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting socketTimeout to: 0 [junit4:junit4] 2> 3394 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting urlScheme to: http:// [junit4:junit4] 2> 3394 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting connTimeout to: 0 [junit4:junit4] 2> 3394 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 3395 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting corePoolSize to: 0 [junit4:junit4] 2> 3395 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 3395 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 3396 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting sizeOfQueue to: -1 [junit4:junit4] 2> 3396 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting fairnessPolicy to: false [junit4:junit4] 2> 3396 T2509 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&= socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 3410 T2509 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 3418 T2509 oasc.CoreContainer.create Creating SolrCore= 'collection1' using instanceDir: C:\Jenkins\workspace\Lucene-Solr-4.x-Wind= ows\solr\build\solr-core\test-files\solr\collection1 [junit4:junit4] 2> 3418 T2509 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:collection1 [junit4:junit4] 2> 3419 T2509 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 3420 T2509 oasc.ZkController.readConfigName Load colle= ction config from:/collections/collection1 [junit4:junit4] 2> 3421 T2509 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\sol= r\build\solr-core\test-files\solr\collection1\' [junit4:junit4] 2> 3423 T2509 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-= core/test-files/solr/collection1/lib/classes/' to classloader [junit4:junit4] 2> 3425 T2509 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-= core/test-files/solr/collection1/lib/README' to classloader [junit4:junit4] 2> 3487 T2509 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_40 [junit4:junit4] 2> 3494 T2521 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 3510 T2525 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 3511 T2520 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 3510 T2528 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 3596 T2509 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 3597 T2509 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 3609 T2509 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 4424 T2509 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 4443 T2509 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 4447 T2509 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 4465 T2509 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 4470 T2509 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 4476 T2509 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 4478 T2509 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 4480 T2509 oasc.SolrCore. [collection1] Opening = new SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\sol= r-core\test-files\solr\collection1\, dataDir=3DC:\Jenkins\workspace\Lucene-= Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionInte= grationTest-1347321837212\data_7002\ [junit4:junit4] 2> 4480 T2509 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@ed9f47 [junit4:junit4] 2> 4482 T2509 oasc.SolrCore.getNewIndexDir WARNING New in= dex directory detected: old=3Dnull new=3DC:\Jenkins\workspace\Lucene-Solr-4= .x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegratio= nTest-1347321837212\data_7002\index/ [junit4:junit4] 2> 4483 T2509 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\= build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321837= 212\data_7002\index' doesn't exist. Creating new index... [junit4:junit4] 2> 4484 T2509 oasc.CachingDirectoryFactory.get return new= directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr= -core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321837212\data_7= 002\index forceNew:false [junit4:junit4] 2> 4486 T2509 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@f7319f lockFactory=3Dorg.apache.lucene.store.NativeFSLockF= actory@efa96b),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 4487 T2509 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1 [junit4:junit4] 2> 4488 T2509 oasc.SolrCore.initWriters created xml: solr= .XMLResponseWriter [junit4:junit4] 2> 4490 T2509 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 4490 T2509 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe-allfields" [junit4:junit4] 2> 4490 T2509 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 4491 T2509 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "u= niq-fields" [junit4:junit4] 2> 4491 T2509 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 4492 T2509 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 4492 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 4493 T2509 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 4493 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 4493 T2509 oasc.RequestHandlers.initHandlersFromConfig= created dismax: solr.SearchHandler [junit4:junit4] 2> 4494 T2509 oasc.RequestHandlers.initHandlersFromConfig= created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 4494 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 4495 T2509 oasc.RequestHandlers.initHandlersFromConfig= created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 4495 T2509 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 4496 T2509 oasc.RequestHandlers.initHandlersFromConfig= created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 4496 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 4497 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 4497 T2509 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 4497 T2509 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 4498 T2509 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 4498 T2509 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.= SearchHandler [junit4:junit4] 2> 4499 T2509 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 4499 T2509 oasc.RequestHandlers.initHandlersFromConfig= created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 4500 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 4500 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 4504 T2509 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 4510 T2509 oasc.SolrCore.initDeprecatedSupport WARNING= solrconfig.xml uses deprecated , Please update your c= onfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 4512 T2509 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> 4517 T2509 oass.SolrIndexSearcher. Opening Searc= her@1941ea main [junit4:junit4] 2> 4518 T2509 oass.SolrIndexSearcher.getIndexDir WARNING = WARNING: Directory impl does not support setting indexDir: org.apache.lucen= e.store.MockDirectoryWrapper [junit4:junit4] 2> 4518 T2509 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 4519 T2509 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 4519 T2509 oashc.SpellCheckComponent.inform Initializi= ng spell checkers [junit4:junit4] 2> 4532 T2509 oass.DirectSolrSpellChecker.init init: {nam= e=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLen= gth=3D3} [junit4:junit4] 2> 4605 T2529 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@1941ea main{StandardDirectoryReader(segm= ents_1:1)} [junit4:junit4] 2> 4608 T2509 oasc.CoreContainer.register registering cor= e: collection1 [junit4:junit4] 2> 4609 T2509 oasc.ZkController.register Register shard -= core:collection1 address:http://127.0.0.1:7002/solr shardId:shard1 [junit4:junit4] 2> 4614 T2509 oasc.ZkController.register We are http://12= 7.0.0.1:7002/solr/collection1/ and leader is http://127.0.0.1:7000/solr/col= lection1/ [junit4:junit4] 2> 4614 T2509 oasc.ZkController.checkRecovery WARNING Ski= pping recovery according to sys prop solrcloud.skip.autorecovery [junit4:junit4] 2> 4618 T2509 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 4623 T2509 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 4624 T2509 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-Wind= ows\solr\build\solr-core\test-files\solr [junit4:junit4] 2> 4624 T2509 oasc.CoreContainer$Initializer.initialize l= ooking for solr.xml: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\buil= d\solr-core\test-files\solr\solr.xml [junit4:junit4] 2> 4624 T2509 oasc.CoreContainer. New CoreContainer= 25353308 [junit4:junit4] 2> 4625 T2509 oasc.CoreContainer.load Loading CoreContain= er using Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\buil= d\solr-core\test-files\solr\' [junit4:junit4] 2> 4625 T2509 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\sol= r\build\solr-core\test-files\solr\' [junit4:junit4] 2> 4687 T2509 oasc.CoreContainer.load Registering Log Lis= tener [junit4:junit4] 2> 4712 T2509 oasc.CoreContainer.initZooKeeper Zookeeper = client=3D127.0.0.1:52160/solr [junit4:junit4] 2> 4713 T2509 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:52160/solr sessionTimeout=3D3000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@cd1a50 [junit4:junit4] 2> 4715 T2509 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 4717 T2530 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:52160 [junit4:junit4] 2> 4725 T2530 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to 127.0.0.1/127.0.0.1:52160, initiating sessi= on [junit4:junit4] 2> 4725 T2511 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /127.0.0.1:52178 [junit4:junit4] 2> 4725 T2511 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /127.0.0.1:52178 [junit4:junit4] 2> 4727 T2513 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x139b2a3b6a70005 with negotiated timeout 6000 for client /12= 7.0.0.1:52178 [junit4:junit4] 2> 4728 T2530 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server 127.0.0.1/127.0.0.1:52160, sessio= nid =3D 0x139b2a3b6a70005, negotiated timeout =3D 6000 [junit4:junit4] 2> 4729 T2531 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@cd1a50 name:ZooKeeperConnection= Watcher:127.0.0.1:52160/solr got event WatchedEvent state:SyncConnected ty= pe:None path:null path:null type:None [junit4:junit4] 2> 4729 T2509 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 4730 T2514 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139b2a3b6a70005 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> 4733 T2514 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139b2a3b6a70005 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> 4735 T2509 oasc.ZkController.createEphemeralLiveNode R= egister node as live in ZooKeeper:/live_nodes/127.0.0.1:7003_solr [junit4:junit4] 2> 4736 T2514 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139b2a3b6a70005 type:del= ete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/live_nodes/127.0.0.1:7003_solr Error:KeeperErrorCode =3D NoNode for = /solr/live_nodes/127.0.0.1:7003_solr [junit4:junit4] 2> 4737 T2509 oascc.SolrZkClient.makePath makePath: /live= _nodes/127.0.0.1:7003_solr [junit4:junit4] 2> 4741 T2528 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 4741 T2525 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 4741 T2520 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 4743 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting socketTimeout to: 0 [junit4:junit4] 2> 4744 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting urlScheme to: http:// [junit4:junit4] 2> 4744 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting connTimeout to: 0 [junit4:junit4] 2> 4744 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 4744 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting corePoolSize to: 0 [junit4:junit4] 2> 4744 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 4746 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 4746 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting sizeOfQueue to: -1 [junit4:junit4] 2> 4746 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting fairnessPolicy to: false [junit4:junit4] 2> 4746 T2509 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&= socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 4762 T2509 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 4775 T2509 oasc.CoreContainer.create Creating SolrCore= 'collection1' using instanceDir: C:\Jenkins\workspace\Lucene-Solr-4.x-Wind= ows\solr\build\solr-core\test-files\solr\collection1 [junit4:junit4] 2> 4776 T2509 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:collection1 [junit4:junit4] 2> 4778 T2509 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 4778 T2509 oasc.ZkController.readConfigName Load colle= ction config from:/collections/collection1 [junit4:junit4] 2> 4780 T2509 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\sol= r\build\solr-core\test-files\solr\collection1\' [junit4:junit4] 2> 4784 T2509 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-= core/test-files/solr/collection1/lib/classes/' to classloader [junit4:junit4] 2> 4786 T2509 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-= core/test-files/solr/collection1/lib/README' to classloader [junit4:junit4] 2> 4860 T2509 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_40 [junit4:junit4] 2> 4957 T2509 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 4959 T2509 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 4964 T2509 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 5027 T2521 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 5050 T2531 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 5050 T2525 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 5051 T2528 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 5052 T2520 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 5786 T2509 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 5798 T2509 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 5802 T2509 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 5820 T2509 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 5825 T2509 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 5831 T2509 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 5832 T2509 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 5834 T2509 oasc.SolrCore. [collection1] Opening = new SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\sol= r-core\test-files\solr\collection1\, dataDir=3DC:\Jenkins\workspace\Lucene-= Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionInte= grationTest-1347321837212\data_7003\ [junit4:junit4] 2> 5834 T2509 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@ed9f47 [junit4:junit4] 2> 5836 T2509 oasc.SolrCore.getNewIndexDir WARNING New in= dex directory detected: old=3Dnull new=3DC:\Jenkins\workspace\Lucene-Solr-4= .x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegratio= nTest-1347321837212\data_7003\index/ [junit4:junit4] 2> 5837 T2509 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\= build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321837= 212\data_7003\index' doesn't exist. Creating new index... [junit4:junit4] 2> 5838 T2509 oasc.CachingDirectoryFactory.get return new= directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr= -core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321837212\data_7= 003\index forceNew:false [junit4:junit4] 2> 5842 T2509 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@804170 lockFactory=3Dorg.apache.lucene.store.NativeFSLockF= actory@6a0453),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 5843 T2509 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1 [junit4:junit4] 2> 5844 T2509 oasc.SolrCore.initWriters created xml: solr= .XMLResponseWriter [junit4:junit4] 2> 5847 T2509 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 5847 T2509 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe-allfields" [junit4:junit4] 2> 5847 T2509 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 5848 T2509 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "u= niq-fields" [junit4:junit4] 2> 5848 T2509 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 5848 T2509 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 5850 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 5850 T2509 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 5850 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 5851 T2509 oasc.RequestHandlers.initHandlersFromConfig= created dismax: solr.SearchHandler [junit4:junit4] 2> 5852 T2509 oasc.RequestHandlers.initHandlersFromConfig= created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 5852 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 5852 T2509 oasc.RequestHandlers.initHandlersFromConfig= created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 5853 T2509 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 5853 T2509 oasc.RequestHandlers.initHandlersFromConfig= created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 5854 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 5855 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 5855 T2509 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 5855 T2509 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 5856 T2509 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 5856 T2509 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.= SearchHandler [junit4:junit4] 2> 5857 T2509 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 5857 T2509 oasc.RequestHandlers.initHandlersFromConfig= created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 5858 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 5859 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 5871 T2509 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 5877 T2509 oasc.SolrCore.initDeprecatedSupport WARNING= solrconfig.xml uses deprecated , Please update your c= onfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 5879 T2509 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> 5882 T2509 oass.SolrIndexSearcher. Opening Searc= her@d62399 main [junit4:junit4] 2> 5883 T2509 oass.SolrIndexSearcher.getIndexDir WARNING = WARNING: Directory impl does not support setting indexDir: org.apache.lucen= e.store.MockDirectoryWrapper [junit4:junit4] 2> 5884 T2509 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 5884 T2509 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 5885 T2509 oashc.SpellCheckComponent.inform Initializi= ng spell checkers [junit4:junit4] 2> 5896 T2509 oass.DirectSolrSpellChecker.init init: {nam= e=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLen= gth=3D3} [junit4:junit4] 2> 5959 T2532 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@d62399 main{StandardDirectoryReader(segm= ents_1:1)} [junit4:junit4] 2> 5964 T2509 oasc.CoreContainer.register registering cor= e: collection1 [junit4:junit4] 2> 5965 T2509 oasc.ZkController.register Register shard -= core:collection1 address:http://127.0.0.1:7003/solr shardId:shard1 [junit4:junit4] 2> 5970 T2509 oasc.ZkController.register We are http://12= 7.0.0.1:7003/solr/collection1/ and leader is http://127.0.0.1:7000/solr/col= lection1/ [junit4:junit4] 2> 5970 T2509 oasc.ZkController.checkRecovery WARNING Ski= pping recovery according to sys prop solrcloud.skip.autorecovery [junit4:junit4] 2> 5973 T2509 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 5978 T2509 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 5978 T2509 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-Wind= ows\solr\build\solr-core\test-files\solr [junit4:junit4] 2> 5978 T2509 oasc.CoreContainer$Initializer.initialize l= ooking for solr.xml: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\buil= d\solr-core\test-files\solr\solr.xml [junit4:junit4] 2> 5978 T2509 oasc.CoreContainer. New CoreContainer= 7816233 [junit4:junit4] 2> 5979 T2509 oasc.CoreContainer.load Loading CoreContain= er using Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\buil= d\solr-core\test-files\solr\' [junit4:junit4] 2> 5980 T2509 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\sol= r\build\solr-core\test-files\solr\' [junit4:junit4] 2> 6021 T2509 oasc.CoreContainer.load Registering Log Lis= tener [junit4:junit4] 2> 6046 T2509 oasc.CoreContainer.initZooKeeper Zookeeper = client=3D127.0.0.1:52160/solr [junit4:junit4] 2> 6046 T2509 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:52160/solr sessionTimeout=3D3000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@11732a7 [junit4:junit4] 2> 6048 T2533 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:52160 [junit4:junit4] 2> 6048 T2509 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 6051 T2533 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to 127.0.0.1/127.0.0.1:52160, initiating sessi= on [junit4:junit4] 2> 6051 T2511 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /127.0.0.1:52181 [junit4:junit4] 2> 6051 T2511 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /127.0.0.1:52181 [junit4:junit4] 2> 6054 T2513 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x139b2a3b6a70006 with negotiated timeout 6000 for client /12= 7.0.0.1:52181 [junit4:junit4] 2> 6054 T2533 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server 127.0.0.1/127.0.0.1:52160, sessio= nid =3D 0x139b2a3b6a70006, negotiated timeout =3D 6000 [junit4:junit4] 2> 6054 T2534 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@11732a7 name:ZooKeeperConnectio= n Watcher:127.0.0.1:52160/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 6055 T2509 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 6057 T2514 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139b2a3b6a70006 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> 6060 T2514 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139b2a3b6a70006 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> 6062 T2509 oasc.ZkController.createEphemeralLiveNode R= egister node as live in ZooKeeper:/live_nodes/127.0.0.1:7004_solr [junit4:junit4] 2> 6063 T2514 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139b2a3b6a70006 type:del= ete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/live_nodes/127.0.0.1:7004_solr Error:KeeperErrorCode =3D NoNode for = /solr/live_nodes/127.0.0.1:7004_solr [junit4:junit4] 2> 6064 T2509 oascc.SolrZkClient.makePath makePath: /live= _nodes/127.0.0.1:7004_solr [junit4:junit4] 2> 6065 T2521 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 6067 T2528 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 6067 T2520 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 6067 T2531 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 6068 T2525 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 6069 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting socketTimeout to: 0 [junit4:junit4] 2> 6069 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting urlScheme to: http:// [junit4:junit4] 2> 6070 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting connTimeout to: 0 [junit4:junit4] 2> 6070 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 6070 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting corePoolSize to: 0 [junit4:junit4] 2> 6071 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 6071 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 6071 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting sizeOfQueue to: -1 [junit4:junit4] 2> 6071 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting fairnessPolicy to: false [junit4:junit4] 2> 6071 T2509 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&= socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 6080 T2531 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 6081 T2525 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 6082 T2528 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 6086 T2509 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 6087 T2520 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 6092 T2509 oasc.CoreContainer.create Creating SolrCore= 'collection1' using instanceDir: C:\Jenkins\workspace\Lucene-Solr-4.x-Wind= ows\solr\build\solr-core\test-files\solr\collection1 [junit4:junit4] 2> 6092 T2509 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:collection1 [junit4:junit4] 2> 6094 T2509 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 6094 T2509 oasc.ZkController.readConfigName Load colle= ction config from:/collections/collection1 [junit4:junit4] 2> 6096 T2509 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\sol= r\build\solr-core\test-files\solr\collection1\' [junit4:junit4] 2> 6099 T2509 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-= core/test-files/solr/collection1/lib/classes/' to classloader [junit4:junit4] 2> 6100 T2509 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-= core/test-files/solr/collection1/lib/README' to classloader [junit4:junit4] 2> 6178 T2509 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_40 [junit4:junit4] 2> 6260 T2509 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 6261 T2509 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 6268 T2509 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 7059 T2509 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 7076 T2509 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 7083 T2509 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 7101 T2509 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 7107 T2509 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 7113 T2509 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 7115 T2509 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 7116 T2509 oasc.SolrCore. [collection1] Opening = new SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\sol= r-core\test-files\solr\collection1\, dataDir=3DC:\Jenkins\workspace\Lucene-= Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionInte= grationTest-1347321837212\data_7004\ [junit4:junit4] 2> 7117 T2509 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@ed9f47 [junit4:junit4] 2> 7119 T2509 oasc.SolrCore.getNewIndexDir WARNING New in= dex directory detected: old=3Dnull new=3DC:\Jenkins\workspace\Lucene-Solr-4= .x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegratio= nTest-1347321837212\data_7004\index/ [junit4:junit4] 2> 7120 T2509 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\= build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321837= 212\data_7004\index' doesn't exist. Creating new index... [junit4:junit4] 2> 7121 T2509 oasc.CachingDirectoryFactory.get return new= directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr= -core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321837212\data_7= 004\index forceNew:false [junit4:junit4] 2> 7124 T2509 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@f73d21 lockFactory=3Dorg.apache.lucene.store.NativeFSLockF= actory@4fbe80),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> 7125 T2509 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1 [junit4:junit4] 2> 7126 T2509 oasc.SolrCore.initWriters created xml: solr= .XMLResponseWriter [junit4:junit4] 2> 7128 T2509 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 7129 T2509 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe-allfields" [junit4:junit4] 2> 7129 T2509 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 7129 T2509 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "u= niq-fields" [junit4:junit4] 2> 7129 T2509 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 7129 T2509 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 7130 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 7130 T2509 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 7131 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 7131 T2509 oasc.RequestHandlers.initHandlersFromConfig= created dismax: solr.SearchHandler [junit4:junit4] 2> 7132 T2509 oasc.RequestHandlers.initHandlersFromConfig= created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> 7132 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 7132 T2509 oasc.RequestHandlers.initHandlersFromConfig= created defaults: solr.StandardRequestHandler [junit4:junit4] 2> 7133 T2509 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> 7133 T2509 oasc.RequestHandlers.initHandlersFromConfig= created lazy: solr.StandardRequestHandler [junit4:junit4] 2> 7134 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 7135 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 7135 T2509 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 7136 T2509 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 7137 T2509 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchH= andler [junit4:junit4] 2> 7137 T2509 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.= SearchHandler [junit4:junit4] 2> 7138 T2509 oasc.RequestHandlers.initHandlersFromConfig= created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 7138 T2509 oasc.RequestHandlers.initHandlersFromConfig= created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> 7139 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> 7139 T2509 oasc.RequestHandlers.initHandlersFromConfig= created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> 7144 T2509 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 7151 T2509 oasc.SolrCore.initDeprecatedSupport WARNING= solrconfig.xml uses deprecated , Please update your c= onfig to use the ShowFileRequestHandler. [junit4:junit4] 2> 7153 T2509 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> 7156 T2509 oass.SolrIndexSearcher. Opening Searc= her@1d9b182 main [junit4:junit4] 2> 7157 T2509 oass.SolrIndexSearcher.getIndexDir WARNING = WARNING: Directory impl does not support setting indexDir: org.apache.lucen= e.store.MockDirectoryWrapper [junit4:junit4] 2> 7157 T2509 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 7157 T2509 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 7158 T2509 oashc.SpellCheckComponent.inform Initializi= ng spell checkers [junit4:junit4] 2> 7173 T2509 oass.DirectSolrSpellChecker.init init: {nam= e=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLen= gth=3D3} [junit4:junit4] 2> 7236 T2535 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@1d9b182 main{StandardDirectoryReader(seg= ments_1:1)} [junit4:junit4] 2> 7242 T2509 oasc.CoreContainer.register registering cor= e: collection1 [junit4:junit4] 2> 7243 T2509 oasc.ZkController.register Register shard -= core:collection1 address:http://127.0.0.1:7004/solr shardId:shard1 [junit4:junit4] 2> 7248 T2509 oasc.ZkController.register We are http://12= 7.0.0.1:7004/solr/collection1/ and leader is http://127.0.0.1:7000/solr/col= lection1/ [junit4:junit4] 2> 7248 T2509 oasc.ZkController.checkRecovery WARNING Ski= pping recovery according to sys prop solrcloud.skip.autorecovery [junit4:junit4] 2> 7252 T2509 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 7255 T2509 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 7256 T2509 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-Wind= ows\solr\build\solr-core\test-files\solr [junit4:junit4] 2> 7256 T2509 oasc.CoreContainer$Initializer.initialize l= ooking for solr.xml: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\buil= d\solr-core\test-files\solr\solr.xml [junit4:junit4] 2> 7256 T2509 oasc.CoreContainer. New CoreContainer= 15760545 [junit4:junit4] 2> 7257 T2509 oasc.CoreContainer.load Loading CoreContain= er using Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\buil= d\solr-core\test-files\solr\' [junit4:junit4] 2> 7257 T2509 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\sol= r\build\solr-core\test-files\solr\' [junit4:junit4] 2> 7302 T2509 oasc.CoreContainer.load Registering Log Lis= tener [junit4:junit4] 2> 7322 T2509 oasc.CoreContainer.initZooKeeper Zookeeper = client=3D127.0.0.1:52160/solr [junit4:junit4] 2> 7322 T2509 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:52160/solr sessionTimeout=3D3000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@1861cfa [junit4:junit4] 2> 7324 T2509 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 7324 T2536 oaz.ClientCnxn$SendThread.startConnect Open= ing socket connection to server /127.0.0.1:52160 [junit4:junit4] 2> 7326 T2536 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to 127.0.0.1/127.0.0.1:52160, initiating sessi= on [junit4:junit4] 2> 7327 T2511 oazs.NIOServerCnxn$Factory.run Accepted soc= ket connection from /127.0.0.1:52184 [junit4:junit4] 2> 7327 T2511 oazs.NIOServerCnxn.readConnectRequest Clien= t attempting to establish new session at /127.0.0.1:52184 [junit4:junit4] 2> 7330 T2513 oazs.NIOServerCnxn.finishSessionInit Establ= ished session 0x139b2a3b6a70007 with negotiated timeout 6000 for client /12= 7.0.0.1:52184 [junit4:junit4] 2> 7330 T2536 oaz.ClientCnxn$SendThread.readConnectResult= Session establishment complete on server 127.0.0.1/127.0.0.1:52160, sessio= nid =3D 0x139b2a3b6a70007, negotiated timeout =3D 6000 [junit4:junit4] 2> 7331 T2537 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@1861cfa name:ZooKeeperConnectio= n Watcher:127.0.0.1:52160/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 7331 T2509 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 7332 T2514 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139b2a3b6a70007 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> 7334 T2514 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139b2a3b6a70007 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> 7337 T2509 oasc.ZkController.createEphemeralLiveNode R= egister node as live in ZooKeeper:/live_nodes/127.0.0.1:3333_solr [junit4:junit4] 2> 7337 T2514 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x139b2a3b6a70007 type:del= ete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path= :/solr/live_nodes/127.0.0.1:3333_solr Error:KeeperErrorCode =3D NoNode for = /solr/live_nodes/127.0.0.1:3333_solr [junit4:junit4] 2> 7338 T2509 oascc.SolrZkClient.makePath makePath: /live= _nodes/127.0.0.1:3333_solr [junit4:junit4] 2> 7341 T2528 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 7341 T2531 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 7341 T2534 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 7342 T2525 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 7342 T2520 oascc.ZkStateReader$3.process Updating live= nodes [junit4:junit4] 2> 7343 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting socketTimeout to: 0 [junit4:junit4] 2> 7343 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting urlScheme to: http:// [junit4:junit4] 2> 7345 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting connTimeout to: 0 [junit4:junit4] 2> 7345 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 7345 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting corePoolSize to: 0 [junit4:junit4] 2> 7345 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 7346 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 7347 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting sizeOfQueue to: -1 [junit4:junit4] 2> 7347 T2509 oashc.HttpShardHandlerFactory.getParameter = Setting fairnessPolicy to: false [junit4:junit4] 2> 7347 T2509 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&= socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> 7363 T2509 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 7371 T2509 oasc.CoreContainer.create Creating SolrCore= 'collection1' using instanceDir: C:\Jenkins\workspace\Lucene-Solr-4.x-Wind= ows\solr\build\solr-core\test-files\solr\collection1 [junit4:junit4] 2> 7371 T2509 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:collection1 [junit4:junit4] 2> 7373 T2509 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 7373 T2509 oasc.ZkController.readConfigName Load colle= ction config from:/collections/collection1 [junit4:junit4] 2> 7375 T2509 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\sol= r\build\solr-core\test-files\solr\collection1\' [junit4:junit4] 2> 7378 T2509 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-= core/test-files/solr/collection1/lib/classes/' to classloader [junit4:junit4] 2> 7379 T2509 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-= core/test-files/solr/collection1/lib/README' to classloader [junit4:junit4] 2> 7444 T2509 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_40 [junit4:junit4] 2> 7538 T2509 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 7541 T2509 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 7547 T2509 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 7594 T2521 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 7612 T2528 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 7613 T2534 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 7613 T2537 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 7613 T2520 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 7613 T2531 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 7613 T2525 oascc.ZkStateReader$2.process A cluster sta= te change has occurred - updating... [junit4:junit4] 2> 8369 T2509 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 8379 T2509 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 8383 T2509 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> -62695 T2509 oass.FileExchangeRateProvider.reload Relo= ading exchange rates from file currency.xml [junit4:junit4] 2> -62679 T2509 oass.FileExchangeRateProvider.reload Relo= ading exchange rates from file currency.xml [junit4:junit4] 2> -62672 T2509 oass.OpenExchangeRatesOrgProvider.reload = Reloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> -62670 T2509 oass.OpenExchangeRatesOrgProvider.reload = Reloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> -62668 T2509 oasc.SolrCore. [collection1] Openin= g new SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\s= olr-core\test-files\solr\collection1\, dataDir=3DC:\Jenkins\workspace\Lucen= e-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIn= tegrationTest-1347321837212\data_3333\ [junit4:junit4] 2> -62668 T2509 oasc.JmxMonitoredMap. JMX monitorin= g is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.Jmx= MBeanServer@ed9f47 [junit4:junit4] 2> -62667 T2509 oasc.SolrCore.getNewIndexDir WARNING New = index directory detected: old=3Dnull new=3DC:\Jenkins\workspace\Lucene-Solr= -4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrat= ionTest-1347321837212\data_3333\index/ [junit4:junit4] 2> -62666 T2509 oasc.SolrCore.initIndex WARNING [collecti= on1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\sol= r\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-13473218= 37212\data_3333\index' doesn't exist. Creating new index... [junit4:junit4] 2> -62665 T2509 oasc.CachingDirectoryFactory.get return n= ew directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\so= lr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321837212\data= _3333\index forceNew:false [junit4:junit4] 2> -62663 T2509 oasc.SolrDeletionPolicy.onCommit SolrDele= tionPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@1171c11 lockFactory=3Dorg.apache.lucene.store.NativeFSLock= Factory@191f703),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> -62662 T2509 oasc.SolrDeletionPolicy.updateCommits new= est commit =3D 1 [junit4:junit4] 2> -62662 T2509 oasc.SolrCore.initWriters created xml: so= lr.XMLResponseWriter [junit4:junit4] 2> -62660 T2509 oasup.UpdateRequestProcessorChain.init in= serting DistributedUpdateProcessorFactory into updateRequestProcessorChain = "dedupe" [junit4:junit4] 2> -62659 T2509 oasup.UpdateRequestProcessorChain.init in= serting DistributedUpdateProcessorFactory into updateRequestProcessorChain = "dedupe-allfields" [junit4:junit4] 2> -62659 T2509 oasup.UpdateRequestProcessorChain.init in= serting DistributedUpdateProcessorFactory into updateRequestProcessorChain = "stored_sig" [junit4:junit4] 2> -62659 T2509 oasup.UpdateRequestProcessorChain.init in= serting DistributedUpdateProcessorFactory into updateRequestProcessorChain = "uniq-fields" [junit4:junit4] 2> -62658 T2509 oasup.UpdateRequestProcessorChain.init in= serting DistributedUpdateProcessorFactory into updateRequestProcessorChain = "distrib-dup-test-chain-implicit" [junit4:junit4] 2> -62658 T2509 oasc.RequestHandlers.initHandlersFromConf= ig adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> -62657 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created /replication: solr.ReplicationHandler [junit4:junit4] 2> -62657 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created standard: solr.StandardRequestHandler [junit4:junit4] 2> -62656 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created /get: solr.RealTimeGetHandler [junit4:junit4] 2> -62656 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created dismax: solr.SearchHandler [junit4:junit4] 2> -62655 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> -62655 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> -62654 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created defaults: solr.StandardRequestHandler [junit4:junit4] 2> -62654 T2509 oasc.RequestHandlers.initHandlersFromConf= ig adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> -62654 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created lazy: solr.StandardRequestHandler [junit4:junit4] 2> -62653 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created /update: solr.UpdateRequestHandler [junit4:junit4] 2> -62653 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> -62652 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandle= r [junit4:junit4] 2> -62652 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created spellCheckCompRH_Direct: org.apache.solr.handler.component.Searc= hHandler [junit4:junit4] 2> -62651 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created spellCheckWithWordbreak: org.apache.solr.handler.component.Searc= hHandler [junit4:junit4] 2> -62651 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.componen= t.SearchHandler [junit4:junit4] 2> -62650 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandl= er [junit4:junit4] 2> -62649 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> -62649 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> -62648 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> -62644 T2509 oashl.XMLLoader.init xsltCacheLifetimeSec= onds=3D60 [junit4:junit4] 2> -62632 T2509 oasc.SolrCore.initDeprecatedSupport WARNI= NG solrconfig.xml uses deprecated , Please update your= config to use the ShowFileRequestHandler. [junit4:junit4] 2> -62631 T2509 oasc.SolrCore.initDeprecatedSupport WARNI= NG adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONY= MS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TX= T, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT] [junit4:junit4] 2> -62627 T2509 oass.SolrIndexSearcher. Opening Sea= rcher@ab6f92 main [junit4:junit4] 2> -62626 T2509 oass.SolrIndexSearcher.getIndexDir WARNIN= G WARNING: Directory impl does not support setting indexDir: org.apache.luc= ene.store.MockDirectoryWrapper [junit4:junit4] 2> -62625 T2509 oasu.CommitTracker. Hard AutoCommit= : disabled [junit4:junit4] 2> -62625 T2509 oasu.CommitTracker. Soft AutoCommit= : disabled [junit4:junit4] 2> -62625 T2509 oashc.SpellCheckComponent.inform Initiali= zing spell checkers [junit4:junit4] 2> -62588 T2509 oass.DirectSolrSpellChecker.init init: {n= ame=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryL= ength=3D3} [junit4:junit4] 2> -62519 T2538 oasc.SolrCore.registerSearcher [collectio= n1] Registered new searcher Searcher@ab6f92 main{StandardDirectoryReader(se= gments_1:1)} [junit4:junit4] 2> -62510 T2509 oasc.CoreContainer.register registering c= ore: collection1 [junit4:junit4] 2> -62510 T2509 oasc.ZkController.register Register shard= - core:collection1 address:http://127.0.0.1:3333/solr shardId:shard2 [junit4:junit4] 2> -62509 T2509 oascc.SolrZkClient.makePath makePath: /co= llections/collection1/leader_elect/shard2/election [junit4:junit4] 2> -62499 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70007 type:d= elete cxid:0x41 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/collections/collection1/leaders/shard2 Error:KeeperErrorCode =3D = NoNode for /solr/collections/collection1/leaders/shard2 [junit4:junit4] 2> -62497 T2509 oasc.ShardLeaderElectionContext.runLeader= Process Running the leader process. afterExpiration=3Dfalse [junit4:junit4] 2> -62496 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70007 type:c= reate cxid:0x42 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -62492 T2509 oasc.ShardLeaderElectionContext.runLeader= Process I am the new leader: http://127.0.0.1:3333/solr/collection1/ [junit4:junit4] 2> -62491 T2509 oascc.SolrZkClient.makePath makePath: /co= llections/collection1/leaders/shard2 [junit4:junit4] 2> -62485 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70007 type:c= reate cxid:0x4a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -62470 T2521 oascc.ZkStateReader.updateClusterState Up= dating cloud state from ZooKeeper...=20 [junit4:junit4] 2> -62450 T2528 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -62450 T2534 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -62450 T2520 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -62449 T2537 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -62449 T2531 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -62448 T2525 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -62430 T2509 oasc.ZkController.register We are http://= 127.0.0.1:3333/solr/collection1/ and leader is http://127.0.0.1:3333/solr/c= ollection1/ [junit4:junit4] 2> -62430 T2509 oasc.ZkController.checkRecovery WARNING S= kipping recovery according to sys prop solrcloud.skip.autorecovery [junit4:junit4] 2> -62425 T2509 oascc.ZkStateReader.updateClusterState Up= dating cloud state from ZooKeeper...=20 [junit4:junit4] 2> -62423 T2509 oaz.ZooKeeper. Initiating client co= nnection, connectString=3D127.0.0.1:52160/solr sessionTimeout=3D10000 watch= er=3Dorg.apache.solr.common.cloud.ConnectionManager@1df7fbc [junit4:junit4] 2> -62421 T2539 oaz.ClientCnxn$SendThread.startConnect Op= ening socket connection to server /127.0.0.1:52160 [junit4:junit4] 2> -62421 T2509 oascc.ConnectionManager.waitForConnected = Waiting for client to connect to ZooKeeper [junit4:junit4] 2> -62420 T2539 oaz.ClientCnxn$SendThread.primeConnection= Socket connection established to 127.0.0.1/127.0.0.1:52160, initiating ses= sion [junit4:junit4] 2> -62419 T2511 oazs.NIOServerCnxn$Factory.run Accepted s= ocket connection from /127.0.0.1:52187 [junit4:junit4] 2> -62419 T2511 oazs.NIOServerCnxn.readConnectRequest Cli= ent attempting to establish new session at /127.0.0.1:52187 [junit4:junit4] 2> -62417 T2513 oazs.NIOServerCnxn.finishSessionInit Esta= blished session 0x139b2a3b6a70008 with negotiated timeout 10000 for client = /127.0.0.1:52187 [junit4:junit4] 2> -62417 T2539 oaz.ClientCnxn$SendThread.readConnectResu= lt Session establishment complete on server 127.0.0.1/127.0.0.1:52160, sess= ionid =3D 0x139b2a3b6a70008, negotiated timeout =3D 10000 [junit4:junit4] 2> -62417 T2540 oascc.ConnectionManager.process Watcher o= rg.apache.solr.common.cloud.ConnectionManager@1df7fbc name:ZooKeeperConnect= ion Watcher:127.0.0.1:52160/solr got event WatchedEvent state:SyncConnected= type:None path:null path:null type:None [junit4:junit4] 2> -62417 T2509 oascc.ConnectionManager.waitForConnected = Client is connected to ZooKeeper [junit4:junit4] 2> -62416 T2509 oascc.ZkStateReader.createClusterStateWat= chersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> -62414 T2509 oasc.LeaderElectionIntegrationTest.setUp = ####SETUP_END testSimpleSliceLeaderElection [junit4:junit4] 2> -62413 T2509 oasc.CoreContainer.shutdown Shutting down= CoreContainer instance=3D9261682 [junit4:junit4] 2> -62413 T2509 oasc.SolrCore.close [collection1] CLOSIN= G SolrCore org.apache.solr.core.SolrCore@115a41f [junit4:junit4] 2> -62409 T2509 oasc.SolrCore.closeSearcher [collection1]= Closing main searcher on request. [junit4:junit4] 2> -62409 T2509 oasu.DirectUpdateHandler2.close closing D= irectUpdateHandler2{commits=3D0,autocommits=3D0,soft autocommits=3D0,optimi= zes=3D0,rollbacks=3D0,expungeDeletes=3D0,docsPending=3D0,adds=3D0,deletesBy= Id=3D0,deletesByQuery=3D0,errors=3D0,cumulative_adds=3D0,cumulative_deletes= ById=3D0,cumulative_deletesByQuery=3D0,cumulative_errors=3D0} [junit4:junit4] 2> -62409 T2509 oasu.DefaultSolrCoreState.decref SolrCore= State ref count has reached 0 - closing IndexWriter [junit4:junit4] 2> -62408 T2509 oasu.DefaultSolrCoreState.decref Closing = SolrCoreState - canceling any ongoing recovery [junit4:junit4] 2> -62407 T2521 oasc.Overseer$ClusterStateUpdater.amILead= er According to ZK I (id=3D88298083924246530-127.0.0.1:7000_solr-n_00000000= 00) am no longer a leader. [junit4:junit4] 2> -62406 T2514 oazs.PrepRequestProcessor.pRequest Proces= sed session termination for sessionid: 0x139b2a3b6a70002 [junit4:junit4] 2> -62404 T2534 oascc.ZkStateReader$3.process Updating li= ve nodes [junit4:junit4] 2> -62403 T2540 oascc.ZkStateReader$3.process Updating li= ve nodes [junit4:junit4] 2> -62403 T2528 oascc.ZkStateReader$3.process Updating li= ve nodes [junit4:junit4] 2> -62403 T2520 oascc.ZkStateReader$3.process Updating li= ve nodes [junit4:junit4] 2> -62401 T2511 oazs.NIOServerCnxn.closeSock Closed socke= t connection for client /127.0.0.1:52169 which had sessionid 0x139b2a3b6a70= 002 [junit4:junit4] 2> -62402 T2531 oascc.ZkStateReader$3.process Updating li= ve nodes [junit4:junit4] 2> -62401 T2537 oascc.ZkStateReader$3.process Updating li= ve nodes [junit4:junit4] 2> -62400 T2509 oaz.ZooKeeper.close Session: 0x139b2a3b6a= 70002 closed [junit4:junit4] 2> -62399 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70003 type:d= elete cxid:0x48 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer_elect/leader Error:KeeperErrorCode =3D NoNode for /solr/= overseer_elect/leader [junit4:junit4] 2> -62396 T2525 oascc.SolrZkClient.makePath makePath: /ov= erseer_elect/leader [junit4:junit4] 2> -62391 T2525 oasc.Overseer.start Overseer (id=3D882980= 83924246531-127.0.0.1:7001_solr-n_0000000001) starting [junit4:junit4] 2> -62391 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70003 type:c= reate cxid:0x4d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -62389 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70003 type:c= reate cxid:0x4e zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -62386 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70003 type:c= reate cxid:0x4f zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -62384 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70003 type:c= reate cxid:0x50 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -62382 T2542 oasc.OverseerCollectionProcessor.run Proc= ess current queue of collection creations [junit4:junit4] 2> -62381 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70003 type:d= elete cxid:0x54 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode =3D = NoNode for /solr/collections/collection1/leaders/shard1 [junit4:junit4] 2> -62379 T2525 oasc.ShardLeaderElectionContext.runLeader= Process Running the leader process. afterExpiration=3Dfalse [junit4:junit4] 2> -62379 T2541 oasc.Overseer$ClusterStateUpdater.run Sta= rting to work on the main queue [junit4:junit4] 2> -62378 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70003 type:c= reate cxid:0x57 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -62375 T2525 oasc.ShardLeaderElectionContext.shouldIBe= Leader Checking if I should try and be the leader. [junit4:junit4] 2> -62375 T2525 oasc.ShardLeaderElectionContext.shouldIBe= Leader I am Active and live, it's okay to be the leader. [junit4:junit4] 2> -62375 T2525 oasc.ShardLeaderElectionContext.runLeader= Process I may be the new leader - try and sync [junit4:junit4] 2> -62375 T2525 oasc.SyncStrategy.sync Sync replicas to h= ttp://127.0.0.1:7001/solr/collection1/ [junit4:junit4] 2> -62375 T2525 oasc.SyncStrategy.sync SEVERE No UpdateLo= g found - cannot sync [junit4:junit4] 2> -62374 T2525 oasc.ShardLeaderElectionContext.rejoinLea= derElection There is a better leader candidate than us - going back into re= covery [junit4:junit4] 2> -62374 T2541 oascc.ZkStateReader.updateClusterState Up= dating cloud state from ZooKeeper...=20 [junit4:junit4] 2> -62370 T2525 oasu.DefaultSolrCoreState.doRecovery WARN= ING Skipping recovery according to sys prop solrcloud.skip.autorecovery [junit4:junit4] 2> -62367 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70004 type:d= elete cxid:0x47 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode =3D = NoNode for /solr/collections/collection1/leaders/shard1 [junit4:junit4] 2> -62364 T2528 oasc.ShardLeaderElectionContext.runLeader= Process Running the leader process. afterExpiration=3Dfalse [junit4:junit4] 2> -62363 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70004 type:c= reate cxid:0x48 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -62361 T2525 oascc.ZkStateReader$3.process Updating li= ve nodes [junit4:junit4] 2> -62359 T2528 oasc.ShardLeaderElectionContext.shouldIBe= Leader Checking if I should try and be the leader. [junit4:junit4] 2> -62359 T2528 oasc.ShardLeaderElectionContext.shouldIBe= Leader I am Active and live, it's okay to be the leader. [junit4:junit4] 2> -62358 T2528 oasc.ShardLeaderElectionContext.runLeader= Process I may be the new leader - try and sync [junit4:junit4] 2> -62358 T2528 oasc.SyncStrategy.sync Sync replicas to h= ttp://127.0.0.1:7002/solr/collection1/ [junit4:junit4] 2> -62358 T2528 oasc.SyncStrategy.sync SEVERE No UpdateLo= g found - cannot sync [junit4:junit4] 2> -62357 T2528 oasc.ShardLeaderElectionContext.rejoinLea= derElection There is a better leader candidate than us - going back into re= covery [junit4:junit4] 2> -62353 T2528 oasu.DefaultSolrCoreState.doRecovery WARN= ING Skipping recovery according to sys prop solrcloud.skip.autorecovery [junit4:junit4] 2> -62351 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70005 type:d= elete cxid:0x45 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode =3D = NoNode for /solr/collections/collection1/leaders/shard1 [junit4:junit4] 2> -62349 T2531 oasc.ShardLeaderElectionContext.runLeader= Process Running the leader process. afterExpiration=3Dfalse [junit4:junit4] 2> -62348 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70005 type:c= reate cxid:0x46 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -62344 T2531 oasc.ShardLeaderElectionContext.shouldIBe= Leader Checking if I should try and be the leader. [junit4:junit4] 2> -62342 T2531 oasc.ShardLeaderElectionContext.shouldIBe= Leader I am Active and live, it's okay to be the leader. [junit4:junit4] 2> -62342 T2531 oasc.ShardLeaderElectionContext.runLeader= Process I may be the new leader - try and sync [junit4:junit4] 2> -62342 T2531 oasc.SyncStrategy.sync Sync replicas to h= ttp://127.0.0.1:7003/solr/collection1/ [junit4:junit4] 2> -62342 T2531 oasc.SyncStrategy.sync SEVERE No UpdateLo= g found - cannot sync [junit4:junit4] 2> -62342 T2531 oasc.ShardLeaderElectionContext.rejoinLea= derElection There is a better leader candidate than us - going back into re= covery [junit4:junit4] 2> -62338 T2531 oasu.DefaultSolrCoreState.doRecovery WARN= ING Skipping recovery according to sys prop solrcloud.skip.autorecovery [junit4:junit4] 2> -62336 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70006 type:d= elete cxid:0x42 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode =3D = NoNode for /solr/collections/collection1/leaders/shard1 [junit4:junit4] 2> -62334 T2534 oasc.ShardLeaderElectionContext.runLeader= Process Running the leader process. afterExpiration=3Dfalse [junit4:junit4] 2> -62333 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70006 type:c= reate cxid:0x43 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -62329 T2534 oasc.ShardLeaderElectionContext.shouldIBe= Leader Checking if I should try and be the leader. [junit4:junit4] 2> -62328 T2534 oasc.ShardLeaderElectionContext.shouldIBe= Leader I am Active and live, it's okay to be the leader. [junit4:junit4] 2> -62328 T2534 oasc.ShardLeaderElectionContext.runLeader= Process I may be the new leader - try and sync [junit4:junit4] 2> -62328 T2534 oasc.SyncStrategy.sync Sync replicas to h= ttp://127.0.0.1:7004/solr/collection1/ [junit4:junit4] 2> -62328 T2534 oasc.SyncStrategy.sync SEVERE No UpdateLo= g found - cannot sync [junit4:junit4] 2> -62328 T2534 oasc.ShardLeaderElectionContext.rejoinLea= derElection There is a better leader candidate than us - going back into re= covery [junit4:junit4] 2> -62323 T2534 oasu.DefaultSolrCoreState.doRecovery WARN= ING Skipping recovery according to sys prop solrcloud.skip.autorecovery [junit4:junit4] 2> -62281 T2531 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -62281 T2534 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -62281 T2537 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -62280 T2528 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -62281 T2540 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -62253 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70003 type:d= elete cxid:0xb7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode =3D = NoNode for /solr/collections/collection1/leaders/shard1 [junit4:junit4] 2> -62251 T2525 oasc.ShardLeaderElectionContext.runLeader= Process Running the leader process. afterExpiration=3Dfalse [junit4:junit4] 2> -62251 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70003 type:c= reate cxid:0xb8 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -62249 T2525 oasc.ShardLeaderElectionContext.shouldIBe= Leader Checking if I should try and be the leader. [junit4:junit4] 2> -62248 T2525 oasc.ShardLeaderElectionContext.shouldIBe= Leader I am not Active but no one else is either, it's okay to be the leade= r [junit4:junit4] 2> -62246 T2525 oasc.ShardLeaderElectionContext.runLeader= Process I may be the new leader - try and sync [junit4:junit4] 2> -62246 T2525 oasc.SyncStrategy.sync Sync replicas to h= ttp://127.0.0.1:7001/solr/collection1/ [junit4:junit4] 2> -62246 T2525 oasc.SyncStrategy.sync SEVERE No UpdateLo= g found - cannot sync [junit4:junit4] 2> -62246 T2525 oasc.ShardLeaderElectionContext.runLeader= Process I am the new leader: http://127.0.0.1:7001/solr/collection1/ [junit4:junit4] 2> -62246 T2525 oascc.SolrZkClient.makePath makePath: /co= llections/collection1/leaders/shard1 [junit4:junit4] 2> -62241 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70003 type:c= reate cxid:0xc1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -62237 T2525 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -61752 T2541 oascc.ZkStateReader.updateClusterState Up= dating cloud state from ZooKeeper...=20 [junit4:junit4] 2> -61733 T2528 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -61733 T2540 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -61733 T2534 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -61733 T2525 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -61733 T2537 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -61733 T2531 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -61696 T2509 oasc.CoreContainer.shutdown Shutting down= CoreContainer instance=3D13898930 [junit4:junit4] 2> -61696 T2509 oasc.SolrCore.close [collection1] CLOSIN= G SolrCore org.apache.solr.core.SolrCore@1cea89d [junit4:junit4] 2> -61691 T2509 oasc.SolrCore.closeSearcher [collection1]= Closing main searcher on request. [junit4:junit4] 2> -61691 T2509 oasu.DirectUpdateHandler2.close closing D= irectUpdateHandler2{commits=3D0,autocommits=3D0,soft autocommits=3D0,optimi= zes=3D0,rollbacks=3D0,expungeDeletes=3D0,docsPending=3D0,adds=3D0,deletesBy= Id=3D0,deletesByQuery=3D0,errors=3D0,cumulative_adds=3D0,cumulative_deletes= ById=3D0,cumulative_deletesByQuery=3D0,cumulative_errors=3D0} [junit4:junit4] 2> -61691 T2509 oasu.DefaultSolrCoreState.decref SolrCore= State ref count has reached 0 - closing IndexWriter [junit4:junit4] 2> -61690 T2509 oasu.DefaultSolrCoreState.decref Closing = SolrCoreState - canceling any ongoing recovery [junit4:junit4] 2> -61690 T2541 oasc.Overseer$ClusterStateUpdater.amILead= er According to ZK I (id=3D88298083924246531-127.0.0.1:7001_solr-n_00000000= 01) am no longer a leader. [junit4:junit4] 2> -61689 T2514 oazs.PrepRequestProcessor.pRequest Proces= sed session termination for sessionid: 0x139b2a3b6a70003 [junit4:junit4] 2> -61686 T2531 oascc.ZkStateReader$3.process Updating li= ve nodes [junit4:junit4] 2> -61686 T2534 oascc.ZkStateReader$3.process Updating li= ve nodes [junit4:junit4] 2> -61685 T2511 oazs.NIOServerCnxn.closeSock Closed socke= t connection for client /127.0.0.1:52172 which had sessionid 0x139b2a3b6a70= 003 [junit4:junit4] 2> -61684 T2537 oascc.ZkStateReader$3.process Updating li= ve nodes [junit4:junit4] 2> -61684 T2525 oascc.ZkStateReader$3.process Updating li= ve nodes [junit4:junit4] 2> -61683 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70004 type:d= elete cxid:0x52 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode =3D = NoNode for /solr/collections/collection1/leaders/shard1 [junit4:junit4] 2> -61684 T2540 oascc.ZkStateReader$3.process Updating li= ve nodes [junit4:junit4] 2> -61684 T2509 oaz.ZooKeeper.close Session: 0x139b2a3b6a= 70003 closed [junit4:junit4] 2> -61675 T2528 oasc.ShardLeaderElectionContext.runLeader= Process Running the leader process. afterExpiration=3Dfalse [junit4:junit4] 2> -61674 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70004 type:c= reate cxid:0x53 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -61670 T2528 oasc.ShardLeaderElectionContext.shouldIBe= Leader Checking if I should try and be the leader. [junit4:junit4] 2> -61670 T2528 oasc.ShardLeaderElectionContext.shouldIBe= Leader I am not Active and someone else appears to be a better leader candi= date. [junit4:junit4] 2> -61669 T2528 oasc.ShardLeaderElectionContext.rejoinLea= derElection There is a better leader candidate than us - going back into re= covery [junit4:junit4] 2> -61666 T2528 oasu.DefaultSolrCoreState.doRecovery WARN= ING Skipping recovery according to sys prop solrcloud.skip.autorecovery [junit4:junit4] 2> -61664 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70005 type:d= elete cxid:0x51 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode =3D = NoNode for /solr/collections/collection1/leaders/shard1 [junit4:junit4] 2> -61661 T2531 oasc.ShardLeaderElectionContext.runLeader= Process Running the leader process. afterExpiration=3Dfalse [junit4:junit4] 2> -61661 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70005 type:c= reate cxid:0x52 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -61659 T2528 oascc.ZkStateReader$3.process Updating li= ve nodes [junit4:junit4] 2> -61657 T2531 oasc.ShardLeaderElectionContext.shouldIBe= Leader Checking if I should try and be the leader. [junit4:junit4] 2> -61657 T2531 oasc.ShardLeaderElectionContext.shouldIBe= Leader I am not Active but no one else is either, it's okay to be the leade= r [junit4:junit4] 2> -61655 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70004 type:d= elete cxid:0x5c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer_elect/leader Error:KeeperErrorCode =3D NoNode for /solr/= overseer_elect/leader [junit4:junit4] 2> -61654 T2531 oasc.ShardLeaderElectionContext.runLeader= Process I may be the new leader - try and sync [junit4:junit4] 2> -61654 T2531 oasc.SyncStrategy.sync Sync replicas to h= ttp://127.0.0.1:7003/solr/collection1/ [junit4:junit4] 2> -61654 T2531 oasc.SyncStrategy.sync SEVERE No UpdateLo= g found - cannot sync [junit4:junit4] 2> -61653 T2531 oasc.ShardLeaderElectionContext.runLeader= Process I am the new leader: http://127.0.0.1:7003/solr/collection1/ [junit4:junit4] 2> -61653 T2531 oascc.SolrZkClient.makePath makePath: /co= llections/collection1/leaders/shard1 [junit4:junit4] 2> -61651 T2528 oascc.SolrZkClient.makePath makePath: /ov= erseer_elect/leader [junit4:junit4] 2> -61647 T2528 oasc.Overseer.start Overseer (id=3D882980= 83924246532-127.0.0.1:7002_solr-n_0000000002) starting [junit4:junit4] 2> -61646 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70004 type:c= reate cxid:0x61 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -61646 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70005 type:c= reate cxid:0x5b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -61644 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70004 type:c= reate cxid:0x62 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -61642 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70004 type:c= reate cxid:0x63 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -61638 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70004 type:c= reate cxid:0x64 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -61634 T2544 oasc.OverseerCollectionProcessor.run Proc= ess current queue of collection creations [junit4:junit4] 2> -61633 T2543 oasc.Overseer$ClusterStateUpdater.run Sta= rting to work on the main queue [junit4:junit4] 2> -61630 T2543 oascc.ZkStateReader.updateClusterState Up= dating cloud state from ZooKeeper...=20 [junit4:junit4] 2> -61593 T2528 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -61592 T2534 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -61592 T2531 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -61592 T2537 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -61592 T2540 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -61402 T2520 oascc.ZkStateReader$3.process WARNING Zoo= Keeper watch triggered, but Solr cannot talk to ZK [junit4:junit4] 2> -61402 T2520 oaz.ClientCnxn$EventThread.run EventThrea= d shut down [junit4:junit4] 2> -61177 T2509 oasc.CoreContainer.shutdown Shutting down= CoreContainer instance=3D25353308 [junit4:junit4] 2> -61177 T2509 oasc.SolrCore.close [collection1] CLOSIN= G SolrCore org.apache.solr.core.SolrCore@155bf44 [junit4:junit4] 2> -61171 T2509 oasc.SolrCore.closeSearcher [collection1]= Closing main searcher on request. [junit4:junit4] 2> -61170 T2509 oasu.DirectUpdateHandler2.close closing D= irectUpdateHandler2{commits=3D0,autocommits=3D0,soft autocommits=3D0,optimi= zes=3D0,rollbacks=3D0,expungeDeletes=3D0,docsPending=3D0,adds=3D0,deletesBy= Id=3D0,deletesByQuery=3D0,errors=3D0,cumulative_adds=3D0,cumulative_deletes= ById=3D0,cumulative_deletesByQuery=3D0,cumulative_errors=3D0} [junit4:junit4] 2> -61170 T2509 oasu.DefaultSolrCoreState.decref SolrCore= State ref count has reached 0 - closing IndexWriter [junit4:junit4] 2> -61169 T2509 oasu.DefaultSolrCoreState.decref Closing = SolrCoreState - canceling any ongoing recovery [junit4:junit4] 2> -61168 T2514 oazs.PrepRequestProcessor.pRequest Proces= sed session termination for sessionid: 0x139b2a3b6a70005 [junit4:junit4] 2> -61167 T2528 oascc.ZkStateReader$3.process Updating li= ve nodes [junit4:junit4] 2> -61167 T2534 oascc.ZkStateReader$3.process Updating li= ve nodes [junit4:junit4] 2> -61167 T2540 oascc.ZkStateReader$3.process Updating li= ve nodes [junit4:junit4] 2> -61167 T2531 oascc.ZkStateReader$3.process Updating li= ve nodes [junit4:junit4] 2> -61165 T2511 oazs.NIOServerCnxn.closeSock Closed socke= t connection for client /127.0.0.1:52178 which had sessionid 0x139b2a3b6a70= 005 [junit4:junit4] 2> -61165 T2537 oascc.ZkStateReader$3.process Updating li= ve nodes [junit4:junit4] 2> -61164 T2509 oaz.ZooKeeper.close Session: 0x139b2a3b6a= 70005 closed [junit4:junit4] 2> -61162 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70006 type:d= elete cxid:0x52 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode =3D = NoNode for /solr/collections/collection1/leaders/shard1 [junit4:junit4] 2> -61160 T2534 oasc.ShardLeaderElectionContext.runLeader= Process Running the leader process. afterExpiration=3Dfalse [junit4:junit4] 2> -61160 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70006 type:c= reate cxid:0x53 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -61155 T2534 oasc.ShardLeaderElectionContext.shouldIBe= Leader Checking if I should try and be the leader. [junit4:junit4] 2> -61155 T2534 oasc.ShardLeaderElectionContext.shouldIBe= Leader I am not Active but no one else is either, it's okay to be the leade= r [junit4:junit4] 2> -61153 T2534 oasc.ShardLeaderElectionContext.runLeader= Process I may be the new leader - try and sync [junit4:junit4] 2> -61153 T2534 oasc.SyncStrategy.sync Sync replicas to h= ttp://127.0.0.1:7004/solr/collection1/ [junit4:junit4] 2> -61152 T2534 oasc.SyncStrategy.sync SEVERE No UpdateLo= g found - cannot sync [junit4:junit4] 2> -61152 T2534 oasc.ShardLeaderElectionContext.runLeader= Process I am the new leader: http://127.0.0.1:7004/solr/collection1/ [junit4:junit4] 2> -61152 T2534 oascc.SolrZkClient.makePath makePath: /co= llections/collection1/leaders/shard1 [junit4:junit4] 2> -61145 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70006 type:c= reate cxid:0x5c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -61075 T2543 oascc.ZkStateReader.updateClusterState Up= dating cloud state from ZooKeeper...=20 [junit4:junit4] 2> -61050 T2528 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -61049 T2537 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -61049 T2540 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -61049 T2534 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -60684 T2525 oascc.ZkStateReader$3.process WARNING Zoo= Keeper watch triggered, but Solr cannot talk to ZK [junit4:junit4] 2> -60684 T2525 oaz.ClientCnxn$EventThread.run EventThrea= d shut down [junit4:junit4] 2> -60664 T2509 oasc.CoreContainer.shutdown Shutting down= CoreContainer instance=3D7816233 [junit4:junit4] 2> -60664 T2509 oasc.SolrCore.close [collection1] CLOSIN= G SolrCore org.apache.solr.core.SolrCore@fc5fd3 [junit4:junit4] 2> -60661 T2509 oasc.SolrCore.closeSearcher [collection1]= Closing main searcher on request. [junit4:junit4] 2> -60660 T2509 oasu.DirectUpdateHandler2.close closing D= irectUpdateHandler2{commits=3D0,autocommits=3D0,soft autocommits=3D0,optimi= zes=3D0,rollbacks=3D0,expungeDeletes=3D0,docsPending=3D0,adds=3D0,deletesBy= Id=3D0,deletesByQuery=3D0,errors=3D0,cumulative_adds=3D0,cumulative_deletes= ById=3D0,cumulative_deletesByQuery=3D0,cumulative_errors=3D0} [junit4:junit4] 2> -60660 T2509 oasu.DefaultSolrCoreState.decref SolrCore= State ref count has reached 0 - closing IndexWriter [junit4:junit4] 2> -60660 T2509 oasu.DefaultSolrCoreState.decref Closing = SolrCoreState - canceling any ongoing recovery [junit4:junit4] 2> -60658 T2514 oazs.PrepRequestProcessor.pRequest Proces= sed session termination for sessionid: 0x139b2a3b6a70006 [junit4:junit4] 2> -60656 T2534 oascc.ZkStateReader$3.process Updating li= ve nodes [junit4:junit4] 2> -60656 T2511 oazs.NIOServerCnxn.closeSock Closed socke= t connection for client /127.0.0.1:52181 which had sessionid 0x139b2a3b6a70= 006 [junit4:junit4] 2> -60656 T2540 oascc.ZkStateReader$3.process Updating li= ve nodes [junit4:junit4] 2> -60655 T2509 oaz.ZooKeeper.close Session: 0x139b2a3b6a= 70006 closed [junit4:junit4] 2> -60654 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70004 type:d= elete cxid:0xc1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode =3D = NoNode for /solr/collections/collection1/leaders/shard1 [junit4:junit4] 2> -60653 T2528 oasc.ShardLeaderElectionContext.runLeader= Process Running the leader process. afterExpiration=3Dfalse [junit4:junit4] 2> -60653 T2537 oascc.ZkStateReader$3.process Updating li= ve nodes [junit4:junit4] 2> -60653 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70004 type:c= reate cxid:0xc2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -60649 T2528 oasc.ShardLeaderElectionContext.shouldIBe= Leader Checking if I should try and be the leader. [junit4:junit4] 2> -60648 T2528 oasc.ShardLeaderElectionContext.shouldIBe= Leader I am not Active and someone else appears to be a better leader candi= date. [junit4:junit4] 2> -60648 T2528 oasc.ShardLeaderElectionContext.rejoinLea= derElection There is a better leader candidate than us - going back into re= covery [junit4:junit4] 2> -60645 T2528 oasu.DefaultSolrCoreState.doRecovery WARN= ING Skipping recovery according to sys prop solrcloud.skip.autorecovery [junit4:junit4] 2> -60641 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70004 type:d= elete cxid:0xc8 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode =3D = NoNode for /solr/collections/collection1/leaders/shard1 [junit4:junit4] 2> -60640 T2528 oasc.ShardLeaderElectionContext.runLeader= Process Running the leader process. afterExpiration=3Dfalse [junit4:junit4] 2> -60639 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70004 type:c= reate cxid:0xc9 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -60635 T2528 oasc.ShardLeaderElectionContext.runLeader= Process I am the new leader: http://127.0.0.1:7002/solr/collection1/ [junit4:junit4] 2> -60635 T2528 oascc.SolrZkClient.makePath makePath: /co= llections/collection1/leaders/shard1 [junit4:junit4] 2> -60626 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70004 type:c= reate cxid:0xd1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -60622 T2528 oascc.ZkStateReader$3.process Updating li= ve nodes [junit4:junit4] 2> -60532 T2543 oascc.ZkStateReader.updateClusterState Up= dating cloud state from ZooKeeper...=20 [junit4:junit4] 2> -60504 T2528 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -60504 T2537 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -60503 T2540 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -60165 T2531 oascc.ZkStateReader$3.process WARNING Zoo= Keeper watch triggered, but Solr cannot talk to ZK [junit4:junit4] 2> -60165 T2531 oaz.ClientCnxn$EventThread.run EventThrea= d shut down [junit4:junit4] 2> -60154 T2514 oazs.PrepRequestProcessor.pRequest Proces= sed session termination for sessionid: 0x139b2a3b6a70008 [junit4:junit4] 2> -60151 T2540 oaz.ClientCnxn$EventThread.run EventThrea= d shut down [junit4:junit4] 2> -60150 T2511 oazs.NIOServerCnxn.doIO WARNING EndOfStre= amException: Unable to read additional data from client sessionid 0x139b2a3= b6a70008, likely client has closed socket [junit4:junit4] 2> -60151 T2509 oaz.ZooKeeper.close Session: 0x139b2a3b6a= 70008 closed [junit4:junit4] 2> -60149 T2511 oazs.NIOServerCnxn.closeSock Closed socke= t connection for client /127.0.0.1:52187 which had sessionid 0x139b2a3b6a70= 008 [junit4:junit4] 2> -60149 T2509 oasc.CoreContainer.shutdown Shutting down= CoreContainer instance=3D24126967 [junit4:junit4] 2> -60148 T2509 oasc.SolrCore.close [collection1] CLOSIN= G SolrCore org.apache.solr.core.SolrCore@1357681 [junit4:junit4] 2> -60138 T2509 oasc.SolrCore.closeSearcher [collection1]= Closing main searcher on request. [junit4:junit4] 2> -60137 T2509 oasu.DirectUpdateHandler2.close closing D= irectUpdateHandler2{commits=3D0,autocommits=3D0,soft autocommits=3D0,optimi= zes=3D0,rollbacks=3D0,expungeDeletes=3D0,docsPending=3D0,adds=3D0,deletesBy= Id=3D0,deletesByQuery=3D0,errors=3D0,cumulative_adds=3D0,cumulative_deletes= ById=3D0,cumulative_deletesByQuery=3D0,cumulative_errors=3D0} [junit4:junit4] 2> -60137 T2509 oasu.DefaultSolrCoreState.decref SolrCore= State ref count has reached 0 - closing IndexWriter [junit4:junit4] 2> -60135 T2509 oasu.DefaultSolrCoreState.decref Closing = SolrCoreState - canceling any ongoing recovery [junit4:junit4] 2> -60132 T2543 oasc.Overseer$ClusterStateUpdater.amILead= er According to ZK I (id=3D88298083924246532-127.0.0.1:7002_solr-n_00000000= 02) am no longer a leader. [junit4:junit4] 2> -60132 T2514 oazs.PrepRequestProcessor.pRequest Proces= sed session termination for sessionid: 0x139b2a3b6a70004 [junit4:junit4] 2> -60126 T2528 oascc.ZkStateReader$3.process Updating li= ve nodes [junit4:junit4] 2> -60125 T2511 oazs.NIOServerCnxn.closeSock Closed socke= t connection for client /127.0.0.1:52175 which had sessionid 0x139b2a3b6a70= 004 [junit4:junit4] 2> -60125 T2509 oaz.ZooKeeper.close Session: 0x139b2a3b6a= 70004 closed [junit4:junit4] 2> -60124 T2509 oasc.CoreContainer.shutdown Shutting down= CoreContainer instance=3D15760545 [junit4:junit4] 2> -60123 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70007 type:d= elete cxid:0x5d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer_elect/leader Error:KeeperErrorCode =3D NoNode for /solr/= overseer_elect/leader [junit4:junit4] 2> -60123 T2509 oasc.SolrCore.close [collection1] CLOSIN= G SolrCore org.apache.solr.core.SolrCore@5bc5cd [junit4:junit4] 2> -60119 T2537 oascc.SolrZkClient.makePath makePath: /ov= erseer_elect/leader [junit4:junit4] 2> -60103 T2537 oasc.Overseer.start Overseer (id=3D882980= 83924246535-127.0.0.1:3333_solr-n_0000000005) starting [junit4:junit4] 2> -60102 T2514 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a3b6a70007 type:c= reate cxid:0x62 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -60101 T2509 oasc.SolrCore.closeSearcher [collection1]= Closing main searcher on request. [junit4:junit4] 2> -60101 T2509 oasu.DirectUpdateHandler2.close closing D= irectUpdateHandler2{commits=3D0,autocommits=3D0,soft autocommits=3D0,optimi= zes=3D0,rollbacks=3D0,expungeDeletes=3D0,docsPending=3D0,adds=3D0,deletesBy= Id=3D0,deletesByQuery=3D0,errors=3D0,cumulative_adds=3D0,cumulative_deletes= ById=3D0,cumulative_deletesByQuery=3D0,cumulative_errors=3D0} [junit4:junit4] 2> -60101 T2509 oasu.DefaultSolrCoreState.decref SolrCore= State ref count has reached 0 - closing IndexWriter [junit4:junit4] 2> -60100 T2509 oasu.DefaultSolrCoreState.decref Closing = SolrCoreState - canceling any ongoing recovery [junit4:junit4] 2> -60098 T2514 oazs.PrepRequestProcessor.pRequest Proces= sed session termination for sessionid: 0x139b2a3b6a70007 [junit4:junit4] 2> -60096 T2511 oazs.NIOServerCnxn.closeSock Closed socke= t connection for client /127.0.0.1:52184 which had sessionid 0x139b2a3b6a70= 007 [junit4:junit4] 2> -60095 T2509 oaz.ZooKeeper.close Session: 0x139b2a3b6a= 70007 closed [junit4:junit4] 2> -60093 T2514 oazs.PrepRequestProcessor.run PrepRequest= Processor exited loop! [junit4:junit4] 2> -60091 T2513 oazs.SyncRequestProcessor.run SyncRequest= Processor exited! [junit4:junit4] 2> -60091 T2509 oazs.FinalRequestProcessor.shutdown shutd= own of request processor complete [junit4:junit4] 2> -59656 T2534 oascc.ZkStateReader$3.process WARNING Zoo= Keeper watch triggered, but Solr cannot talk to ZK [junit4:junit4] 2> -57838 T2534 oaz.ClientCnxn$EventThread.run EventThrea= d shut down [junit4:junit4] 2> -57838 T2511 oazs.NIOServerCnxn$Factory.run NIOServerC= nxn factory exited run method [junit4:junit4] 2> -59097 T2537 oasc.Overseer.createOverseerNode SEVERE C= ould not create Overseer node: class org.apache.zookeeper.KeeperException$S= essionExpiredException:KeeperErrorCode =3D Session expired for /overseer [junit4:junit4] 2> -59125 T2528 oascc.ZkStateReader$3.process WARNING Zoo= Keeper watch triggered, but Solr cannot talk to ZK [junit4:junit4] 2> -57837 T2537 oasc.LeaderElector$1.process WARNING jav= a.lang.RuntimeException: org.apache.zookeeper.KeeperException$SessionExpire= dException: KeeperErrorCode =3D Session expired for /overseer [junit4:junit4] 2> =09at org.apache.solr.cloud.Overseer.createOverseerNod= e(Overseer.java:524) [junit4:junit4] 2> =09at org.apache.solr.cloud.Overseer.getInQueue(Overse= er.java:497) [junit4:junit4] 2> =09at org.apache.solr.cloud.Overseer$ClusterStateUpdat= er.(Overseer.java:67) [junit4:junit4] 2> =09at org.apache.solr.cloud.Overseer.start(Overseer.ja= va:469) [junit4:junit4] 2> =09at org.apache.solr.cloud.OverseerElectionContext.ru= nLeaderProcess(ElectionContext.java:399) [junit4:junit4] 2> =09at org.apache.solr.cloud.LeaderElector.runIamLeader= Process(LeaderElector.java:159) [junit4:junit4] 2> =09at org.apache.solr.cloud.LeaderElector.checkIfIamLe= ader(LeaderElector.java:103) [junit4:junit4] 2> =09at org.apache.solr.cloud.LeaderElector.access$000(L= eaderElector.java:57) [junit4:junit4] 2> =09at org.apache.solr.cloud.LeaderElector$1.process(Le= aderElector.java:132) [junit4:junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.proc= essEvent(ClientCnxn.java:526) [junit4:junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.run(= ClientCnxn.java:502) [junit4:junit4] 2> Caused by: org.apache.zookeeper.KeeperException$Sessio= nExpiredException: KeeperErrorCode =3D Session expired for /overseer [junit4:junit4] 2> =09at org.apache.zookeeper.KeeperException.create(Keep= erException.java:118) [junit4:junit4] 2> =09at org.apache.zookeeper.KeeperException.create(Keep= erException.java:42) [junit4:junit4] 2> =09at org.apache.zookeeper.ZooKeeper.create(ZooKeeper.= java:643) [junit4:junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$9.exec= ute(SolrZkClient.java:349) [junit4:junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$9.exec= ute(SolrZkClient.java:346) [junit4:junit4] 2> =09at org.apache.solr.common.cloud.ZkCmdExecutor.retry= Operation(ZkCmdExecutor.java:67) [junit4:junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient.create= (SolrZkClient.java:346) [junit4:junit4] 2> =09at org.apache.solr.cloud.Overseer.createOverseerNod= e(Overseer.java:515) [junit4:junit4] 2> =09... 10 more [junit4:junit4] 2>=20 [junit4:junit4] 2> -57835 T2537 oascc.ZkStateReader$3.process Updating li= ve nodes [junit4:junit4] 2> -57835 T2537 oascc.ZkStateReader$3.process WARNING Zoo= Keeper watch triggered, but Solr cannot talk to ZK [junit4:junit4] 2> -57837 T2528 oaz.ClientCnxn$EventThread.run EventThrea= d shut down [junit4:junit4] 2> -57836 T2509 oazs.FinalRequestProcessor.shutdown shutd= own of request processor complete [junit4:junit4] 2> -57835 T2537 oaz.ClientCnxn$EventThread.run EventThrea= d shut down [junit4:junit4] 2> -57834 T2509 oas.SolrTestCaseJ4.tearDown ###Ending tes= tSimpleSliceLeaderElection [junit4:junit4] 2> -57831 T2509 oas.SolrTestCaseJ4.setUp ###Starting test= LeaderElectionAfterClientTimeout [junit4:junit4] 2> Creating dataDir: C:\Jenkins\workspace\Lucene-Solr-4.x= -Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationT= est-1347321779377 [junit4:junit4] 2> -57829 T2509 oasc.ZkTestServer.run STARTING ZK TEST SE= RVER [junit4:junit4] 2> -57824 T2545 oazs.ZooKeeperServer.setTickTime tickTime= set to 3000 [junit4:junit4] 2> -57823 T2545 oazs.NIOServerCnxn$Factory. binding= to port 0.0.0.0/0.0.0.0:0 [junit4:junit4] 2> -57822 T2545 oazsp.FileTxnSnapLog.save Snapshotting: 0 [junit4:junit4] 2> -57717 T2509 oasc.ZkTestServer.run start zk server on = port:52190 [junit4:junit4] 2> -57716 T2509 oaz.ZooKeeper. Initiating client co= nnection, connectString=3D127.0.0.1:52190 sessionTimeout=3D10000 watcher=3D= org.apache.solr.common.cloud.ConnectionManager@1fe37d8 [junit4:junit4] 2> -57715 T2550 oaz.ClientCnxn$SendThread.startConnect Op= ening socket connection to server /127.0.0.1:52190 [junit4:junit4] 2> -57715 T2509 oascc.ConnectionManager.waitForConnected = Waiting for client to connect to ZooKeeper [junit4:junit4] 2> -57714 T2550 oaz.ClientCnxn$SendThread.primeConnection= Socket connection established to 127.0.0.1/127.0.0.1:52190, initiating ses= sion [junit4:junit4] 2> -57714 T2546 oazs.NIOServerCnxn$Factory.run Accepted s= ocket connection from /127.0.0.1:52193 [junit4:junit4] 2> -57713 T2546 oazs.NIOServerCnxn.readConnectRequest Cli= ent attempting to establish new session at /127.0.0.1:52193 [junit4:junit4] 2> -57712 T2548 oazsp.FileTxnLog.append Creating new log = file: log.1 [junit4:junit4] 2> -57707 T2548 oazs.NIOServerCnxn.finishSessionInit Esta= blished session 0x139b2a2d4bc0000 with negotiated timeout 10000 for client = /127.0.0.1:52193 [junit4:junit4] 2> -57707 T2550 oaz.ClientCnxn$SendThread.readConnectResu= lt Session establishment complete on server 127.0.0.1/127.0.0.1:52190, sess= ionid =3D 0x139b2a2d4bc0000, negotiated timeout =3D 10000 [junit4:junit4] 2> -57706 T2551 oascc.ConnectionManager.process Watcher o= rg.apache.solr.common.cloud.ConnectionManager@1fe37d8 name:ZooKeeperConnect= ion Watcher:127.0.0.1:52190 got event WatchedEvent state:SyncConnected type= :None path:null path:null type:None [junit4:junit4] 2> -57706 T2509 oascc.ConnectionManager.waitForConnected = Client is connected to ZooKeeper [junit4:junit4] 2> -57705 T2509 oascc.SolrZkClient.makePath makePath: /so= lr [junit4:junit4] 2> -57701 T2549 oazs.PrepRequestProcessor.pRequest Proces= sed session termination for sessionid: 0x139b2a2d4bc0000 [junit4:junit4] 2> -57700 T2509 oaz.ZooKeeper.close Session: 0x139b2a2d4b= c0000 closed [junit4:junit4] 2> -57700 T2546 oazs.NIOServerCnxn.doIO WARNING EndOfStre= amException: Unable to read additional data from client sessionid 0x139b2a2= d4bc0000, likely client has closed socket [junit4:junit4] 2> -57700 T2509 oaz.ZooKeeper. Initiating client co= nnection, connectString=3D127.0.0.1:52190/solr sessionTimeout=3D10000 watch= er=3Dorg.apache.solr.common.cloud.ConnectionManager@156d8af [junit4:junit4] 2> -57699 T2551 oaz.ClientCnxn$EventThread.run EventThrea= d shut down [junit4:junit4] 2> -57692 T2546 oazs.NIOServerCnxn.closeSock Closed socke= t connection for client /127.0.0.1:52193 which had sessionid 0x139b2a2d4bc0= 000 [junit4:junit4] 2> -57691 T2552 oaz.ClientCnxn$SendThread.startConnect Op= ening socket connection to server /127.0.0.1:52190 [junit4:junit4] 2> -57691 T2509 oascc.ConnectionManager.waitForConnected = Waiting for client to connect to ZooKeeper [junit4:junit4] 2> -57689 T2552 oaz.ClientCnxn$SendThread.primeConnection= Socket connection established to 127.0.0.1/127.0.0.1:52190, initiating ses= sion [junit4:junit4] 2> -57688 T2546 oazs.NIOServerCnxn$Factory.run Accepted s= ocket connection from /127.0.0.1:52196 [junit4:junit4] 2> -57688 T2546 oazs.NIOServerCnxn.readConnectRequest Cli= ent attempting to establish new session at /127.0.0.1:52196 [junit4:junit4] 2> -57685 T2548 oazs.NIOServerCnxn.finishSessionInit Esta= blished session 0x139b2a2d4bc0001 with negotiated timeout 10000 for client = /127.0.0.1:52196 [junit4:junit4] 2> -57685 T2552 oaz.ClientCnxn$SendThread.readConnectResu= lt Session establishment complete on server 127.0.0.1/127.0.0.1:52190, sess= ionid =3D 0x139b2a2d4bc0001, negotiated timeout =3D 10000 [junit4:junit4] 2> -57685 T2553 oascc.ConnectionManager.process Watcher o= rg.apache.solr.common.cloud.ConnectionManager@156d8af name:ZooKeeperConnect= ion Watcher:127.0.0.1:52190/solr got event WatchedEvent state:SyncConnected= type:None path:null path:null type:None [junit4:junit4] 2> -57685 T2509 oascc.ConnectionManager.waitForConnected = Client is connected to ZooKeeper [junit4:junit4] 2> -57685 T2509 oascc.SolrZkClient.makePath makePath: /co= llections/collection1 [junit4:junit4] 2> -57678 T2509 oascc.SolrZkClient.makePath makePath: /co= llections/collection1/shards [junit4:junit4] 2> -57672 T2509 oascc.SolrZkClient.makePath makePath: /co= llections/control_collection [junit4:junit4] 2> -57669 T2509 oascc.SolrZkClient.makePath makePath: /co= llections/control_collection/shards [junit4:junit4] 2> -57664 T2509 oasc.AbstractZkTestCase.putConfig put C:\= Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\s= olr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml [junit4:junit4] 2> -57663 T2509 oascc.SolrZkClient.makePath makePath: /co= nfigs/conf1/solrconfig.xml [junit4:junit4] 2> -57652 T2509 oasc.AbstractZkTestCase.putConfig put C:\= Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\s= olr\collection1\conf\schema.xml to /configs/conf1/schema.xml [junit4:junit4] 2> -57652 T2509 oascc.SolrZkClient.makePath makePath: /co= nfigs/conf1/schema.xml [junit4:junit4] 2> -57645 T2509 oasc.AbstractZkTestCase.putConfig put C:\= Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\s= olr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml [junit4:junit4] 2> -57645 T2509 oascc.SolrZkClient.makePath makePath: /co= nfigs/conf1/solrconfig.xml [junit4:junit4] 2> -57640 T2509 oasc.AbstractZkTestCase.putConfig put C:\= Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\s= olr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt [junit4:junit4] 2> -57638 T2509 oascc.SolrZkClient.makePath makePath: /co= nfigs/conf1/stopwords.txt [junit4:junit4] 2> -57633 T2509 oasc.AbstractZkTestCase.putConfig put C:\= Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\s= olr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt [junit4:junit4] 2> -57632 T2509 oascc.SolrZkClient.makePath makePath: /co= nfigs/conf1/protwords.txt [junit4:junit4] 2> -57628 T2509 oasc.AbstractZkTestCase.putConfig put C:\= Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\s= olr\collection1\conf\currency.xml to /configs/conf1/currency.xml [junit4:junit4] 2> -57627 T2509 oascc.SolrZkClient.makePath makePath: /co= nfigs/conf1/currency.xml [junit4:junit4] 2> -57622 T2509 oasc.AbstractZkTestCase.putConfig put C:\= Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\s= olr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchan= ge-rates.json [junit4:junit4] 2> -57621 T2509 oascc.SolrZkClient.makePath makePath: /co= nfigs/conf1/open-exchange-rates.json [junit4:junit4] 2> -57615 T2509 oasc.AbstractZkTestCase.putConfig put C:\= Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\s= olr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-= ISOLatin1Accent.txt [junit4:junit4] 2> -57614 T2509 oascc.SolrZkClient.makePath makePath: /co= nfigs/conf1/mapping-ISOLatin1Accent.txt [junit4:junit4] 2> -57609 T2509 oasc.AbstractZkTestCase.putConfig put C:\= Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\s= olr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4:junit4] 2> -57608 T2509 oascc.SolrZkClient.makePath makePath: /co= nfigs/conf1/old_synonyms.txt [junit4:junit4] 2> -57604 T2509 oasc.AbstractZkTestCase.putConfig put C:\= Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\s= olr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt [junit4:junit4] 2> -57604 T2509 oascc.SolrZkClient.makePath makePath: /co= nfigs/conf1/synonyms.txt [junit4:junit4] 2> -57598 T2549 oazs.PrepRequestProcessor.pRequest Proces= sed session termination for sessionid: 0x139b2a2d4bc0001 [junit4:junit4] 2> -57597 T2546 oazs.NIOServerCnxn.doIO WARNING EndOfStre= amException: Unable to read additional data from client sessionid 0x139b2a2= d4bc0001, likely client has closed socket [junit4:junit4] 2> -57596 T2553 oaz.ClientCnxn$EventThread.run EventThrea= d shut down [junit4:junit4] 2> -57596 T2546 oazs.NIOServerCnxn.closeSock Closed socke= t connection for client /127.0.0.1:52196 which had sessionid 0x139b2a2d4bc0= 001 [junit4:junit4] 2> -57597 T2509 oaz.ZooKeeper.close Session: 0x139b2a2d4b= c0001 closed [junit4:junit4] 2> -57594 T2509 oasc.LeaderElectionIntegrationTest.setUp = ####SETUP_START testLeaderElectionAfterClientTimeout [junit4:junit4] 2> -57592 T2509 oasc.SolrResourceLoader.locateSolrHome JN= DI not configured for solr (NoInitialContextEx) [junit4:junit4] 2> -57591 T2509 oasc.SolrResourceLoader.locateSolrHome us= ing system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-Wi= ndows\solr\build\solr-core\test-files\solr [junit4:junit4] 2> -57591 T2509 oasc.CoreContainer$Initializer.initialize= looking for solr.xml: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\bu= ild\solr-core\test-files\solr\solr.xml [junit4:junit4] 2> -57591 T2509 oasc.CoreContainer. New CoreContain= er 32694012 [junit4:junit4] 2> -57590 T2509 oasc.CoreContainer.load Loading CoreConta= iner using Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\bu= ild\solr-core\test-files\solr\' [junit4:junit4] 2> -57589 T2509 oasc.SolrResourceLoader. new SolrRe= sourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\s= olr\build\solr-core\test-files\solr\' [junit4:junit4] 2> -57546 T2509 oasc.CoreContainer.load Registering Log L= istener [junit4:junit4] 2> -57521 T2509 oasc.CoreContainer.initZooKeeper Zookeepe= r client=3D127.0.0.1:52190/solr [junit4:junit4] 2> -57520 T2509 oaz.ZooKeeper. Initiating client co= nnection, connectString=3D127.0.0.1:52190/solr sessionTimeout=3D3000 watche= r=3Dorg.apache.solr.common.cloud.ConnectionManager@1a35fbb [junit4:junit4] 2> -57519 T2554 oaz.ClientCnxn$SendThread.startConnect Op= ening socket connection to server /127.0.0.1:52190 [junit4:junit4] 2> -57519 T2509 oascc.ConnectionManager.waitForConnected = Waiting for client to connect to ZooKeeper [junit4:junit4] 2> -57517 T2554 oaz.ClientCnxn$SendThread.primeConnection= Socket connection established to 127.0.0.1/127.0.0.1:52190, initiating ses= sion [junit4:junit4] 2> -57517 T2546 oazs.NIOServerCnxn$Factory.run Accepted s= ocket connection from /127.0.0.1:52199 [junit4:junit4] 2> -57516 T2546 oazs.NIOServerCnxn.readConnectRequest Cli= ent attempting to establish new session at /127.0.0.1:52199 [junit4:junit4] 2> -57514 T2548 oazs.NIOServerCnxn.finishSessionInit Esta= blished session 0x139b2a2d4bc0002 with negotiated timeout 6000 for client /= 127.0.0.1:52199 [junit4:junit4] 2> -57514 T2554 oaz.ClientCnxn$SendThread.readConnectResu= lt Session establishment complete on server 127.0.0.1/127.0.0.1:52190, sess= ionid =3D 0x139b2a2d4bc0002, negotiated timeout =3D 6000 [junit4:junit4] 2> -57514 T2555 oascc.ConnectionManager.process Watcher o= rg.apache.solr.common.cloud.ConnectionManager@1a35fbb name:ZooKeeperConnect= ion Watcher:127.0.0.1:52190/solr got event WatchedEvent state:SyncConnected= type:None path:null path:null type:None [junit4:junit4] 2> -57513 T2509 oascc.ConnectionManager.waitForConnected = Client is connected to ZooKeeper [junit4:junit4] 2> -57511 T2549 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a2d4bc0002 type:c= reate cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pa= th:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -57509 T2509 oascc.SolrZkClient.makePath makePath: /li= ve_nodes [junit4:junit4] 2> -57505 T2509 oasc.ZkController.createEphemeralLiveNode= Register node as live in ZooKeeper:/live_nodes/127.0.0.1:7000_solr [junit4:junit4] 2> -57504 T2549 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a2d4bc0002 type:d= elete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pa= th:/solr/live_nodes/127.0.0.1:7000_solr Error:KeeperErrorCode =3D NoNode fo= r /solr/live_nodes/127.0.0.1:7000_solr [junit4:junit4] 2> -57503 T2509 oascc.SolrZkClient.makePath makePath: /li= ve_nodes/127.0.0.1:7000_solr [junit4:junit4] 2> -57498 T2509 oashc.HttpShardHandlerFactory.getParamete= r Setting socketTimeout to: 0 [junit4:junit4] 2> -57498 T2509 oashc.HttpShardHandlerFactory.getParamete= r Setting urlScheme to: http:// [junit4:junit4] 2> -57498 T2509 oashc.HttpShardHandlerFactory.getParamete= r Setting connTimeout to: 0 [junit4:junit4] 2> -57497 T2509 oashc.HttpShardHandlerFactory.getParamete= r Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> -57497 T2509 oashc.HttpShardHandlerFactory.getParamete= r Setting corePoolSize to: 0 [junit4:junit4] 2> -57497 T2509 oashc.HttpShardHandlerFactory.getParamete= r Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> -57497 T2509 oashc.HttpShardHandlerFactory.getParamete= r Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> -57497 T2509 oashc.HttpShardHandlerFactory.getParamete= r Setting sizeOfQueue to: -1 [junit4:junit4] 2> -57497 T2509 oashc.HttpShardHandlerFactory.getParamete= r Setting fairnessPolicy to: false [junit4:junit4] 2> -57496 T2509 oascsi.HttpClientUtil.createClient Creati= ng new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D1000= 0&socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> -57478 T2509 oascc.SolrZkClient.makePath makePath: /ov= erseer_elect/election [junit4:junit4] 2> -57466 T2549 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a2d4bc0002 type:d= elete cxid:0x15 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer_elect/leader Error:KeeperErrorCode =3D NoNode for /solr/= overseer_elect/leader [junit4:junit4] 2> -57464 T2509 oascc.SolrZkClient.makePath makePath: /ov= erseer_elect/leader [junit4:junit4] 2> -57459 T2509 oasc.Overseer.start Overseer (id=3D882980= 80133971970-127.0.0.1:7000_solr-n_0000000000) starting [junit4:junit4] 2> -57458 T2549 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a2d4bc0002 type:c= reate cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -57456 T2549 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a2d4bc0002 type:c= reate cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -57455 T2549 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a2d4bc0002 type:c= reate cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -57453 T2549 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a2d4bc0002 type:c= reate cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -57448 T2557 oasc.OverseerCollectionProcessor.run Proc= ess current queue of collection creations [junit4:junit4] 2> -57448 T2509 oascc.SolrZkClient.makePath makePath: /cl= usterstate.json [junit4:junit4] 2> -57444 T2509 oascc.ZkStateReader.createClusterStateWat= chersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> -57440 T2556 oasc.Overseer$ClusterStateUpdater.run Sta= rting to work on the main queue [junit4:junit4] 2> -57433 T2509 oasc.CoreContainer.create Creating SolrCo= re 'collection1' using instanceDir: C:\Jenkins\workspace\Lucene-Solr-4.x-Wi= ndows\solr\build\solr-core\test-files\solr\collection1 [junit4:junit4] 2> -57432 T2509 oasc.ZkController.createCollectionZkNode = Check for collection zkNode:collection1 [junit4:junit4] 2> -57431 T2509 oasc.ZkController.createCollectionZkNode = Collection zkNode exists [junit4:junit4] 2> -57431 T2509 oasc.ZkController.readConfigName Load col= lection config from:/collections/collection1 [junit4:junit4] 2> -57429 T2509 oasc.SolrResourceLoader. new SolrRe= sourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\s= olr\build\solr-core\test-files\solr\collection1\' [junit4:junit4] 2> -57427 T2509 oasc.SolrResourceLoader.replaceClassLoade= r Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/sol= r-core/test-files/solr/collection1/lib/classes/' to classloader [junit4:junit4] 2> -57425 T2509 oasc.SolrResourceLoader.replaceClassLoade= r Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/sol= r-core/test-files/solr/collection1/lib/README' to classloader [junit4:junit4] 2> -57354 T2509 oasc.SolrConfig. Using Lucene Match= Version: LUCENE_40 [junit4:junit4] 2> -57257 T2509 oasc.SolrConfig. Loaded SolrConfig:= solrconfig.xml [junit4:junit4] 2> -57256 T2509 oass.IndexSchema.readSchema Reading Solr = Schema [junit4:junit4] 2> -57250 T2509 oass.IndexSchema.readSchema Schema name= =3Dtest [junit4:junit4] 2> -56341 T2509 oass.OpenExchangeRatesOrgProvider.init In= itialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> -56325 T2509 oass.IndexSchema.readSchema default searc= h field in schema is text [junit4:junit4] 2> -56314 T2509 oass.IndexSchema.readSchema unique key fi= eld: id [junit4:junit4] 2> -56294 T2509 oass.FileExchangeRateProvider.reload Relo= ading exchange rates from file currency.xml [junit4:junit4] 2> -56288 T2509 oass.FileExchangeRateProvider.reload Relo= ading exchange rates from file currency.xml [junit4:junit4] 2> -56280 T2509 oass.OpenExchangeRatesOrgProvider.reload = Reloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> -56277 T2509 oass.OpenExchangeRatesOrgProvider.reload = Reloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> -56276 T2509 oasc.SolrCore. [collection1] Openin= g new SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\s= olr-core\test-files\solr\collection1\, dataDir=3DC:\Jenkins\workspace\Lucen= e-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIn= tegrationTest-1347321779377\data_7000\ [junit4:junit4] 2> -56276 T2509 oasc.JmxMonitoredMap. JMX monitorin= g is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.Jmx= MBeanServer@ed9f47 [junit4:junit4] 2> -56274 T2509 oasc.SolrCore.getNewIndexDir WARNING New = index directory detected: old=3Dnull new=3DC:\Jenkins\workspace\Lucene-Solr= -4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrat= ionTest-1347321779377\data_7000\index/ [junit4:junit4] 2> -56273 T2509 oasc.SolrCore.initIndex WARNING [collecti= on1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\sol= r\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-13473217= 79377\data_7000\index' doesn't exist. Creating new index... [junit4:junit4] 2> -56272 T2509 oasc.CachingDirectoryFactory.get return n= ew directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\so= lr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321779377\data= _7000\index forceNew:false [junit4:junit4] 2> -56270 T2509 oasc.SolrDeletionPolicy.onCommit SolrDele= tionPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@105682b lockFactory=3Dorg.apache.lucene.store.NativeFSLock= Factory@1dff861),segFN=3Dsegments_1,generation=3D1,filenames=3D[segments_1] [junit4:junit4] 2> -56269 T2509 oasc.SolrDeletionPolicy.updateCommits new= est commit =3D 1 [junit4:junit4] 2> -56268 T2509 oasc.SolrCore.initWriters created xml: so= lr.XMLResponseWriter [junit4:junit4] 2> -56266 T2509 oasup.UpdateRequestProcessorChain.init in= serting DistributedUpdateProcessorFactory into updateRequestProcessorChain = "dedupe" [junit4:junit4] 2> -56266 T2509 oasup.UpdateRequestProcessorChain.init in= serting DistributedUpdateProcessorFactory into updateRequestProcessorChain = "dedupe-allfields" [junit4:junit4] 2> -56266 T2509 oasup.UpdateRequestProcessorChain.init in= serting DistributedUpdateProcessorFactory into updateRequestProcessorChain = "stored_sig" [junit4:junit4] 2> -56265 T2509 oasup.UpdateRequestProcessorChain.init in= serting DistributedUpdateProcessorFactory into updateRequestProcessorChain = "uniq-fields" [junit4:junit4] 2> -56265 T2509 oasup.UpdateRequestProcessorChain.init in= serting DistributedUpdateProcessorFactory into updateRequestProcessorChain = "distrib-dup-test-chain-implicit" [junit4:junit4] 2> -56264 T2509 oasc.RequestHandlers.initHandlersFromConf= ig adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> -56264 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created /replication: solr.ReplicationHandler [junit4:junit4] 2> -56262 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created standard: solr.StandardRequestHandler [junit4:junit4] 2> -56261 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created /get: solr.RealTimeGetHandler [junit4:junit4] 2> -56261 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created dismax: solr.SearchHandler [junit4:junit4] 2> -56261 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4:junit4] 2> -56260 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> -56260 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created defaults: solr.StandardRequestHandler [junit4:junit4] 2> -56260 T2509 oasc.RequestHandlers.initHandlersFromConf= ig adding lazy requestHandler: solr.StandardRequestHandler [junit4:junit4] 2> -56259 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created lazy: solr.StandardRequestHandler [junit4:junit4] 2> -56258 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created /update: solr.UpdateRequestHandler [junit4:junit4] 2> -56258 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created /terms: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> -56257 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandle= r [junit4:junit4] 2> -56256 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created spellCheckCompRH_Direct: org.apache.solr.handler.component.Searc= hHandler [junit4:junit4] 2> -56256 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created spellCheckWithWordbreak: org.apache.solr.handler.component.Searc= hHandler [junit4:junit4] 2> -56255 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.componen= t.SearchHandler [junit4:junit4] 2> -56255 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandl= er [junit4:junit4] 2> -56255 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created tvrh: org.apache.solr.handler.component.SearchHandler [junit4:junit4] 2> -56255 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created /mlt: solr.MoreLikeThisHandler [junit4:junit4] 2> -56254 T2509 oasc.RequestHandlers.initHandlersFromConf= ig created /debug/dump: solr.DumpRequestHandler [junit4:junit4] 2> -56250 T2509 oashl.XMLLoader.init xsltCacheLifetimeSec= onds=3D60 [junit4:junit4] 2> -56242 T2509 oasc.SolrCore.initDeprecatedSupport WARNI= NG solrconfig.xml uses deprecated , Please update your= config to use the ShowFileRequestHandler. [junit4:junit4] 2> -56241 T2509 oasc.SolrCore.initDeprecatedSupport WARNI= NG adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONY= MS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TX= T, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT] [junit4:junit4] 2> -56237 T2509 oass.SolrIndexSearcher. Opening Sea= rcher@c1b118 main [junit4:junit4] 2> -56237 T2509 oass.SolrIndexSearcher.getIndexDir WARNIN= G WARNING: Directory impl does not support setting indexDir: org.apache.luc= ene.store.MockDirectoryWrapper [junit4:junit4] 2> -56236 T2509 oasu.CommitTracker. Hard AutoCommit= : disabled [junit4:junit4] 2> -56236 T2509 oasu.CommitTracker. Soft AutoCommit= : disabled [junit4:junit4] 2> -56235 T2509 oashc.SpellCheckComponent.inform Initiali= zing spell checkers [junit4:junit4] 2> -56222 T2509 oass.DirectSolrSpellChecker.init init: {n= ame=3Ddirect,classname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryL= ength=3D3} [junit4:junit4] 2> -56154 T2558 oasc.SolrCore.registerSearcher [collectio= n1] Registered new searcher Searcher@c1b118 main{StandardDirectoryReader(se= gments_1:1)} [junit4:junit4] 2> -56150 T2549 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a2d4bc0002 type:c= reate cxid:0x56 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer/queue Error:KeeperErrorCode =3D NoNode for /solr/oversee= r/queue [junit4:junit4] 2> -56143 T2509 oasc.CoreContainer.register registering c= ore: collection1 [junit4:junit4] 2> -56143 T2509 oasc.ZkController.register Register shard= - core:collection1 address:http://127.0.0.1:7000/solr shardId:shard1 [junit4:junit4] 2> -56142 T2509 oascc.SolrZkClient.makePath makePath: /co= llections/collection1/leader_elect/shard1/election [junit4:junit4] 2> -56130 T2549 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a2d4bc0002 type:d= elete cxid:0x65 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/collections/collection1/leaders Error:KeeperErrorCode =3D NoNode = for /solr/collections/collection1/leaders [junit4:junit4] 2> -56128 T2509 oasc.ShardLeaderElectionContext.runLeader= Process Running the leader process. afterExpiration=3Dfalse [junit4:junit4] 2> -56127 T2549 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a2d4bc0002 type:c= reate cxid:0x66 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -56123 T2509 oasc.ShardLeaderElectionContext.runLeader= Process I am the new leader: http://127.0.0.1:7000/solr/collection1/ [junit4:junit4] 2> -56123 T2509 oascc.SolrZkClient.makePath makePath: /co= llections/collection1/leaders/shard1 [junit4:junit4] 2> -56116 T2549 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a2d4bc0002 type:c= reate cxid:0x6f zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -55779 T2556 oascc.ZkStateReader.updateClusterState Up= dating cloud state from ZooKeeper...=20 [junit4:junit4] 2> -55778 T2549 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a2d4bc0002 type:c= reate cxid:0x77 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error P= ath:/solr/overseer/queue-work Error:KeeperErrorCode =3D NoNode for /solr/ov= erseer/queue-work [junit4:junit4] 2> -55748 T2555 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -55718 T2509 oasc.ZkController.register We are http://= 127.0.0.1:7000/solr/collection1/ and leader is http://127.0.0.1:7000/solr/c= ollection1/ [junit4:junit4] 2> -55718 T2509 oasc.ZkController.checkRecovery WARNING S= kipping recovery according to sys prop solrcloud.skip.autorecovery [junit4:junit4] 2> -55715 T2509 oascc.ZkStateReader.updateClusterState Up= dating cloud state from ZooKeeper...=20 [junit4:junit4] 2> -55709 T2509 oasc.SolrResourceLoader.locateSolrHome JN= DI not configured for solr (NoInitialContextEx) [junit4:junit4] 2> -55708 T2509 oasc.SolrResourceLoader.locateSolrHome us= ing system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-Wi= ndows\solr\build\solr-core\test-files\solr [junit4:junit4] 2> -55708 T2509 oasc.CoreContainer$Initializer.initialize= looking for solr.xml: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\bu= ild\solr-core\test-files\solr\solr.xml [junit4:junit4] 2> -55707 T2509 oasc.CoreContainer. New CoreContain= er 9755849 [junit4:junit4] 2> -55707 T2509 oasc.CoreContainer.load Loading CoreConta= iner using Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\bu= ild\solr-core\test-files\solr\' [junit4:junit4] 2> -55707 T2509 oasc.SolrResourceLoader. new SolrRe= sourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\s= olr\build\solr-core\test-files\solr\' [junit4:junit4] 2> -55640 T2509 oasc.CoreContainer.load Registering Log L= istener [junit4:junit4] 2> -55619 T2509 oasc.CoreContainer.initZooKeeper Zookeepe= r client=3D127.0.0.1:52190/solr [junit4:junit4] 2> -55618 T2509 oaz.ZooKeeper. Initiating client co= nnection, connectString=3D127.0.0.1:52190/solr sessionTimeout=3D3000 watche= r=3Dorg.apache.solr.common.cloud.ConnectionManager@13aea05 [junit4:junit4] 2> -55616 T2559 oaz.ClientCnxn$SendThread.startConnect Op= ening socket connection to server /127.0.0.1:52190 [junit4:junit4] 2> -55616 T2509 oascc.ConnectionManager.waitForConnected = Waiting for client to connect to ZooKeeper [junit4:junit4] 2> -55615 T2559 oaz.ClientCnxn$SendThread.primeConnection= Socket connection established to 127.0.0.1/127.0.0.1:52190, initiating ses= sion [junit4:junit4] 2> -55614 T2546 oazs.NIOServerCnxn$Factory.run Accepted s= ocket connection from /127.0.0.1:52202 [junit4:junit4] 2> -55614 T2546 oazs.NIOServerCnxn.readConnectRequest Cli= ent attempting to establish new session at /127.0.0.1:52202 [junit4:junit4] 2> -55611 T2548 oazs.NIOServerCnxn.finishSessionInit Esta= blished session 0x139b2a2d4bc0003 with negotiated timeout 6000 for client /= 127.0.0.1:52202 [junit4:junit4] 2> -55610 T2559 oaz.ClientCnxn$SendThread.readConnectResu= lt Session establishment complete on server 127.0.0.1/127.0.0.1:52190, sess= ionid =3D 0x139b2a2d4bc0003, negotiated timeout =3D 6000 [junit4:junit4] 2> -55609 T2560 oascc.ConnectionManager.process Watcher o= rg.apache.solr.common.cloud.ConnectionManager@13aea05 name:ZooKeeperConnect= ion Watcher:127.0.0.1:52190/solr got event WatchedEvent state:SyncConnected= type:None path:null path:null type:None [junit4:junit4] 2> -55609 T2509 oascc.ConnectionManager.waitForConnected = Client is connected to ZooKeeper [junit4:junit4] 2> -55608 T2549 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a2d4bc0003 type:c= reate cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pa= th:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -55606 T2549 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a2d4bc0003 type:c= reate cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pa= th:/solr/overseer Error:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> -55603 T2509 oasc.ZkController.createEphemeralLiveNode= Register node as live in ZooKeeper:/live_nodes/127.0.0.1:7001_solr [junit4:junit4] 2> -55601 T2549 oazs.PrepRequestProcessor.pRequest Got us= er-level KeeperException when processing sessionid:0x139b2a2d4bc0003 type:d= elete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Pa= th:/solr/live_nodes/127.0.0.1:7001_solr Error:KeeperErrorCode =3D NoNode fo= r /solr/live_nodes/127.0.0.1:7001_solr [junit4:junit4] 2> -55600 T2509 oascc.SolrZkClient.makePath makePath: /li= ve_nodes/127.0.0.1:7001_solr [junit4:junit4] 2> -55596 T2555 oascc.ZkStateReader$3.process Updating li= ve nodes [junit4:junit4] 2> -55595 T2509 oashc.HttpShardHandlerFactory.getParamete= r Setting socketTimeout to: 0 [junit4:junit4] 2> -55595 T2509 oashc.HttpShardHandlerFactory.getParamete= r Setting urlScheme to: http:// [junit4:junit4] 2> -55594 T2509 oashc.HttpShardHandlerFactory.getParamete= r Setting connTimeout to: 0 [junit4:junit4] 2> -55594 T2509 oashc.HttpShardHandlerFactory.getParamete= r Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> -55594 T2509 oashc.HttpShardHandlerFactory.getParamete= r Setting corePoolSize to: 0 [junit4:junit4] 2> -55594 T2509 oashc.HttpShardHandlerFactory.getParamete= r Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> -55594 T2509 oashc.HttpShardHandlerFactory.getParamete= r Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> -55593 T2509 oashc.HttpShardHandlerFactory.getParamete= r Setting sizeOfQueue to: -1 [junit4:junit4] 2> -55593 T2509 oashc.HttpShardHandlerFactory.getParamete= r Setting fairnessPolicy to: false [junit4:junit4] 2> -55593 T2509 oascsi.HttpClientUtil.createClient Creati= ng new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D1000= 0&socketTimeout=3D0&connTimeout=3D0&retry=3Dfalse [junit4:junit4] 2> -55576 T2509 oascc.ZkStateReader.createClusterStateWat= chersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> -55566 T2509 oasc.CoreContainer.create Creating SolrCo= re 'collection1' using instanceDir: C:\Jenkins\workspace\Lucene-Solr-4.x-Wi= ndows\solr\build\solr-core\test-files\solr\collection1 [junit4:junit4] 2> -55566 T2509 oasc.ZkController.createCollectionZkNode = Check for collection zkNode:collection1 [junit4:junit4] 2> -55565 T2509 oasc.ZkController.createCollectionZkNode = Collection zkNode exists [junit4:junit4] 2> -55565 T2509 oasc.ZkController.readConfigName Load col= lection config from:/collections/collection1 [junit4:junit4] 2> -55563 T2509 oasc.SolrResourceLoader. new SolrRe= sourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\s= olr\build\solr-core\test-files\solr\collection1\' [junit4:junit4] 2> -55560 T2509 oasc.SolrResourceLoader.replaceClassLoade= r Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/sol= r-core/test-files/solr/collection1/lib/classes/' to classloader [junit4:junit4] 2> -55558 T2509 oasc.SolrResourceLoader.replaceClassLoade= r Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/sol= r-core/test-files/solr/collection1/lib/README' to classloader [junit4:junit4] 2> -55489 T2509 oasc.SolrConfig. Using Lucene Match= Version: LUCENE_40 [junit4:junit4] 2> -55385 T2509 oasc.SolrConfig. Loaded SolrConfig:= solrconfig.xml [junit4:junit4] 2> -55383 T2509 oass.IndexSchema.readSchema Reading Solr = Schema [junit4:junit4] 2> -55376 T2509 oass.IndexSchema.readSchema Schema name= =3Dtest [junit4:junit4] 2> -55181 T2556 oascc.ZkStateReader.updateClusterState Up= dating cloud state from ZooKeeper...=20 [junit4:junit4] 2> -55082 T2560 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -55082 T2555 oascc.ZkStateReader$2.process A cluster s= tate change has occurred - updating... [junit4:junit4] 2> -54484 T2509 oass.OpenExchangeRatesOrgProvider.init In= itialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> -54471 T2509 oass.IndexSchema.readSchema default searc= h field in schema is text [junit4:junit4] 2> -54465 T2509 oass.IndexSchema.readSchema unique key fi= eld: id [junit4:junit4] 2> -54446 T2509 oass.FileExchangeRateProvider.reload Relo= ading exchange rates from file currency.xml [junit4:junit4] 2> -54440 T2509 oass.FileExchangeRateProvider.reload Relo= ading exchange rates from file currency.xml [junit4:junit4] 2> -54433 T2509 oass.OpenExchangeRatesOrgProvider.reload = Reloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> -54431 T2509 oass.OpenExchangeRatesOrgProvider.reload = Reloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> -54429 T2509 oasc.SolrCore. [collection1] Openin= g new SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\s= olr-core\test-files\solr\collection1\, dataDir=3DC:\Jenkins\workspace\Lucen= e-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIn= tegrationTest-1347321779377\data_7001\ [junit4:junit4] 2> -54429 T2509 oasc.JmxMonitoredMap. JMX monitorin= g is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.Jmx= MBeanServer@ed9f47 [junit4:junit4] 2> -54428 T2509 oasc.SolrCore.getNewIndexDir WARNING New = index directory detected: old=3Dnull new=3DC:\Jenkins\workspace\Lucene-Solr= -4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrat= ionTest-1347321779377\data_7001\index/ [junit4:junit4] 2> -54426 T2509 oasc.SolrCore.initIndex WARNING [collecti= on1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\sol= r\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-13473217= 79377\data_7001\index' doesn't exist. Creating new index... [junit4:junit4] 2> -54424 T2509 oasc.CachingDirectoryFactory.get return n= ew directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\so= lr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321779377\data= _7001\index forceNew:false [junit4:junit4] 2> -54421 T2509 oasc.SolrDeletionPolicy.onCommit SolrDe [...truncated too long message...] lientCnxn$EventThread.run EventThread shut down [junit4:junit4] 2> -40766 T2509 oasc.CoreContainer.shutdown Shutting down= CoreContainer instance=3D32694012 [junit4:junit4] 2> -40765 T2509 oasc.SolrCore.close [collection1] CLOSIN= G SolrCore org.apache.solr.core.SolrCore@ddba98 [junit4:junit4] 2> -40762 T2509 oasc.SolrCore.closeSearcher [collection1]= Closing main searcher on request. [junit4:junit4] 2> -40762 T2509 oasu.DirectUpdateHandler2.close closing D= irectUpdateHandler2{commits=3D0,autocommits=3D0,soft autocommits=3D0,optimi= zes=3D0,rollbacks=3D0,expungeDeletes=3D0,docsPending=3D0,adds=3D0,deletesBy= Id=3D0,deletesByQuery=3D0,errors=3D0,cumulative_adds=3D0,cumulative_deletes= ById=3D0,cumulative_deletesByQuery=3D0,cumulative_errors=3D0} [junit4:junit4] 2> -40762 T2509 oasu.DefaultSolrCoreState.decref SolrCore= State ref count has reached 0 - closing IndexWriter [junit4:junit4] 2> -40761 T2509 oasu.DefaultSolrCoreState.decref Closing = SolrCoreState - canceling any ongoing recovery [junit4:junit4] 2> -40760 T2549 oazs.PrepRequestProcessor.pRequest Proces= sed session termination for sessionid: 0x139b2a2d4bc0002 [junit4:junit4] 2> -40759 T2556 oasc.Overseer$ClusterStateUpdater.amILead= er According to ZK I (id=3D88298080133971970-127.0.0.1:7000_solr-n_00000000= 00) am no longer a leader. [junit4:junit4] 2> -40754 T2555 oascc.ZkStateReader$3.process Updating li= ve nodes [junit4:junit4] 2> -40754 T2546 oazs.NIOServerCnxn.closeSock Closed socke= t connection for client /127.0.0.1:52218 which had sessionid 0x139b2a2d4bc0= 002 [junit4:junit4] 2> -40754 T2509 oaz.ZooKeeper.close Session: 0x139b2a2d4b= c0002 closed [junit4:junit4] 2> -40754 T2549 oazs.PrepRequestProcessor.run PrepRequest= Processor exited loop! [junit4:junit4] 2> -40752 T2548 oazs.SyncRequestProcessor.run SyncRequest= Processor exited! [junit4:junit4] 2> -40751 T2509 oazs.FinalRequestProcessor.shutdown shutd= own of request processor complete [junit4:junit4] 2> -39632 T2563 oascc.ZkStateReader$3.process WARNING Zoo= Keeper watch triggered, but Solr cannot talk to ZK [junit4:junit4] 2> -38026 T2563 oaz.ClientCnxn$EventThread.run EventThrea= d shut down [junit4:junit4] 2> -38689 T2547 oazs.SessionTrackerImpl.run SessionTracke= rImpl exited loop! [junit4:junit4] 2> -39544 T2555 oascc.ZkStateReader$3.process WARNING Zoo= Keeper watch triggered, but Solr cannot talk to ZK [junit4:junit4] 2> -38025 T2555 oaz.ClientCnxn$EventThread.run EventThrea= d shut down [junit4:junit4] 2> -39560 T2572 oascc.ZkStateReader$3.process WARNING Zoo= Keeper watch triggered, but Solr cannot talk to ZK [junit4:junit4] 2> -38025 T2572 oaz.ClientCnxn$EventThread.run EventThrea= d shut down [junit4:junit4] 2> -39586 T2569 oascc.ZkStateReader$3.process WARNING Zoo= Keeper watch triggered, but Solr cannot talk to ZK [junit4:junit4] 2> -38025 T2569 oaz.ClientCnxn$EventThread.run EventThrea= d shut down [junit4:junit4] 2> -39614 T2566 oascc.ZkStateReader$3.process WARNING Zoo= Keeper watch triggered, but Solr cannot talk to ZK [junit4:junit4] 2> -38026 T2546 oazs.NIOServerCnxn$Factory.run WARNING Ig= noring exception java.nio.channels.ClosedChannelException [junit4:junit4] 2> =09at sun.nio.ch.ServerSocketChannelImpl.accept(Server= SocketChannelImpl.java:135) [junit4:junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxn$Factor= y.run(NIOServerCnxn.java:242) [junit4:junit4] 2>=20 [junit4:junit4] 2> -38024 T2546 oazs.NIOServerCnxn$Factory.run NIOServerC= nxn factory exited run method [junit4:junit4] 2> -38024 T2566 oaz.ClientCnxn$EventThread.run EventThrea= d shut down [junit4:junit4] 2> -38020 T2509 oazs.FinalRequestProcessor.shutdown shutd= own of request processor complete [junit4:junit4] 2> -38019 T2509 oas.SolrTestCaseJ4.tearDown ###Ending tes= tLeaderElectionAfterClientTimeout [junit4:junit4] 2> -35598 T2509 oas.SolrTestCaseJ4.deleteCore ###deleteCo= re [junit4:junit4] 2> -35300 T2508 ccr.ThreadLeakControl.checkThreadLeaks WA= RNING Will linger awaiting termination of 1 leaked thread(s). [junit4:junit4] 2> -15104 T2508 ccr.ThreadLeakControl.checkThreadLeaks SE= VERE 1 thread leaked from SUITE scope at org.apache.solr.cloud.LeaderElecti= onIntegrationTest:=20 [junit4:junit4] 2> =09 1) Thread[id=3D2512, name=3DSessionTracker, stat= e=3DTIMED_WAITING, group=3DTGRP-LeaderElectionIntegrationTest] [junit4:junit4] 2> =09 at java.lang.Object.wait(Native Method) [junit4:junit4] 2> =09 at org.apache.zookeeper.server.SessionTrack= erImpl.run(SessionTrackerImpl.java:145) [junit4:junit4] 2> -15104 T2508 ccr.ThreadLeakControl.tryToInterruptAll S= tarting to interrupt leaked threads: [junit4:junit4] 2> =09 1) Thread[id=3D2512, name=3DSessionTracker, stat= e=3DTIMED_WAITING, group=3DTGRP-LeaderElectionIntegrationTest] [junit4:junit4] 2> -15103 T2512 oazs.SessionTrackerImpl.run SEVERE Unexpe= cted interruption java.lang.InterruptedException [junit4:junit4] 2> =09at java.lang.Object.wait(Native Method) [junit4:junit4] 2> =09at org.apache.zookeeper.server.SessionTrackerImpl.r= un(SessionTrackerImpl.java:145) [junit4:junit4] 2>=20 [junit4:junit4] 2> -15103 T2512 oazs.SessionTrackerImpl.run SessionTracke= rImpl exited loop! [junit4:junit4] 2> -15101 T2508 ccr.ThreadLeakControl.tryToInterruptAll A= ll leaked threads terminated. [junit4:junit4] 2> NOTE: test params are: codec=3DSimpleText, sim=3DRando= mSimilarityProvider(queryNorm=3Dtrue,coord=3Dno): {}, locale=3Dmt, timezone= =3DAmerica/Argentina/La_Rioja [junit4:junit4] 2> NOTE: Windows 7 6.1 x86/Sun Microsystems Inc. 1.6.0_35= (32-bit)/cpus=3D2,threads=3D1,free=3D114153648,total=3D175042560 [junit4:junit4] 2> NOTE: All tests run in this JVM: [TestStressVersions, = SearchHandlerTest, TestDocumentBuilder, TestQueryUtils, PingRequestHandlerT= est, HighlighterTest, RequestHandlersTest, TestRangeQuery, PathHierarchyTok= enizerFactoryTest, UpdateParamsTest, HighlighterConfigTest, NotRequiredUniq= ueKeyTest, TestStressRecovery, TestSolrDeletionPolicy2, TestMultiCoreConfBo= otstrap, BinaryUpdateRequestHandlerTest, XsltUpdateRequestHandlerTest, Test= ElisionMultitermQuery, TestSolrQueryParser, EchoParamsTest, TestDFRSimilari= tyFactory, DistributedSpellCheckComponentTest, PluginInfoTest, TestJmxInteg= ration, TestValueSourceCache, NumericFieldsTest, DirectSolrConnectionTest, = TestNumberUtils, SortByFunctionTest, SolrPluginUtilsTest, MinimalSchemaTest= , TestSolrJ, LegacyHTMLStripCharFilterTest, CSVRequestHandlerTest, TestTrie= , TermsComponentTest, TestPluginEnable, DirectUpdateHandlerOptimizeTest, So= lrCoreTest, TestFastWriter, TestSolrDeletionPolicy1, SignatureUpdateProcess= orFactoryTest, CurrencyFieldTest, TestBM25SimilarityFactory, TestQuerySende= rNoQuery, TestFastOutputStream, CoreAdminHandlerTest, LeaderElectionTest, S= pellingQueryConverterTest, TestOmitPositions, AlternateDirectoryTest, TestM= ergePolicyConfig, DisMaxRequestHandlerTest, TestBinaryResponseWriter, TestL= FUCache, TermVectorComponentDistributedTest, SpatialFilterTest, TestRealTim= eGet, TestIBSimilarityFactory, CacheHeaderTest, DistributedTermsComponentTe= st, TestReversedWildcardFilterFactory, FullSolrCloudDistribCmdsTest, TestFa= ceting, TestGroupingSearch, TestSuggestSpellingConverter, SOLR749Test, MBea= nsHandlerTest, CopyFieldTest, DirectSolrSpellCheckerTest, TestCharFilters, = DistributedQueryElevationComponentTest, BadIndexSchemaTest, ClusterStateTes= t, ConvertedLegacyTest, TestCSVResponseWriter, PrimUtilsTest, StatsComponen= tTest, FieldMutatingUpdateProcessorTest, TestSurroundQueryParser, Suggester= TSTTest, SampleTest, TestBadConfig, TestBinaryField, MultiTermTest, SystemI= nfoHandlerTest, UniqFieldsUpdateProcessorFactoryTest, TestCoreContainer, Ov= erseerTest, TestLMJelinekMercerSimilarityFactory, SolrCoreCheckLockOnStartu= pTest, WordBreakSolrSpellCheckerTest, ReturnFieldsTest, AutoCommitTest, Tes= tPropInjectDefaults, TestRandomFaceting, URLClassifyProcessorTest, TestColl= ationKeyRangeQueries, TestSort, JsonLoaderTest, JSONWriterTest, IndexSchema= Test, BasicZkTest, SuggesterFSTTest, TestFastLRUCache, BasicDistributedZk2T= est, TestStressReorder, BasicDistributedZkTest, RecoveryZkTest, SpellCheckC= omponentTest, TestReplicationHandler, LeaderElectionIntegrationTest] [junit4:junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DLeaderEle= ctionIntegrationTest -Dtests.seed=3D4AC9E8D0F70D13C6 -Dtests.slow=3Dtrue -D= tests.locale=3Dmt -Dtests.timezone=3DAmerica/Argentina/La_Rioja -Dtests.fil= e.encoding=3DUTF-8 [junit4:junit4] ERROR 0.00s | LeaderElectionIntegrationTest (suite) <<< [junit4:junit4] > Throwable #1: com.carrotsearch.randomizedtesting.Threa= dLeakError: 1 thread leaked from SUITE scope at org.apache.solr.cloud.Leade= rElectionIntegrationTest:=20 [junit4:junit4] > 1) Thread[id=3D2512, name=3DSessionTracker, state= =3DTIMED_WAITING, group=3DTGRP-LeaderElectionIntegrationTest] [junit4:junit4] > at java.lang.Object.wait(Native Method) [junit4:junit4] > at org.apache.zookeeper.server.SessionTrackerI= mpl.run(SessionTrackerImpl.java:145) [junit4:junit4] > =09at __randomizedtesting.SeedInfo.seed([4AC9E8D0F70D1= 3C6]:0) [junit4:junit4] Completed in -15.02s, 2 tests, 1 error <<< FAILURES! [...truncated 342 lines...] BUILD FAILED C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\build.xml:29: The following er= ror occurred while executing this line: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build.xml:146: The follow= ing error occurred while executing this line: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\lucene\module-build.xml:63: Th= e following error occurred while executing this line: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1133: = The following error occurred while executing this line: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:810: T= here were test failures: 228 suites, 913 tests, 1 suite-level error, 12 ign= ored (7 assumptions) Total time: 47 minutes 2 seconds Build step 'Invoke Ant' marked build as failure Recording test results Description set: Java: 32bit/jdk1.6.0_35 -server -XX:+UseConcMarkSweepGC Email was triggered for: Failure Sending email for trigger: Failure ------=_Part_16_1603519094.1347322302032 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_16_1603519094.1347322302032--