lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@sd-datasolutions.de>
Subject [JENKINS] Lucene-Solr-4.x-Windows (32bit/jdk1.6.0_35) - Build # 712 - Failure!
Date Tue, 11 Sep 2012 00:11:42 GMT
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.LeaderElectionIntegrationTest

Error Message:
1 thread leaked from SUITE scope at org.apache.solr.cloud.LeaderElectionIntegrationTest:     1) Thread[id=2512, name=SessionTracker, state=TIMED_WAITING, group=TGRP-LeaderElectionIntegrationTest]         at java.lang.Object.wait(Native Method)         at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:145)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.cloud.LeaderElectionIntegrationTest: 
   1) Thread[id=2512, name=SessionTracker, state=TIMED_WAITING, group=TGRP-LeaderElectionIntegrationTest]
        at java.lang.Object.wait(Native Method)
        at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:145)
	at __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 testSimpleSliceLeaderElection
[junit4:junit4]   2> Creating dataDir: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-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.<init> 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 port:52160
[junit4:junit4]   2> 107 T2509 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52160 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1a5b5cb
[junit4:junit4]   2> 108 T2515 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:52160
[junit4:junit4]   2> 108 T2509 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 109 T2515 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:52160, initiating session
[junit4:junit4]   2> 110 T2511 oazs.NIOServerCnxn$Factory.run Accepted socket 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 file: log.1
[junit4:junit4]   2> 116 T2513 oazs.NIOServerCnxn.finishSessionInit Established session 0x139b2a3b6a70000 with negotiated timeout 10000 for client /127.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, sessionid = 0x139b2a3b6a70000, negotiated timeout = 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:None path:null path:null type:None
[junit4:junit4]   2> 117 T2509 oascc.ConnectionManager.waitForConnected Client 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 shut down
[junit4:junit4]   2> 131 T2509 oaz.ZooKeeper.close Session: 0x139b2a3b6a70000 closed
[junit4:junit4]   2> 131 T2509 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52160/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@9dd4b1
[junit4:junit4]   2> 132 T2511 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x139b2a3b6a70000, likely client has closed socket
[junit4:junit4]   2> 133 T2511 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:52163 which had sessionid 0x139b2a3b6a70000
[junit4:junit4]   2> 133 T2517 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:52160
[junit4:junit4]   2> 133 T2509 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 135 T2517 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:52160, initiating session
[junit4:junit4]   2> 135 T2511 oazs.NIOServerCnxn$Factory.run Accepted socket 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 Established session 0x139b2a3b6a70001 with negotiated timeout 10000 for client /127.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, sessionid = 0x139b2a3b6a70001, negotiated timeout = 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 type:None path:null path:null type:None
[junit4:junit4]   2> 150 T2509 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 150 T2509 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 157 T2509 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 163 T2509 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 167 T2509 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 172 T2509 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\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: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 184 T2509 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\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: /configs/conf1/schema.xml
[junit4:junit4]   2> 194 T2509 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\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: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 202 T2509 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\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: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 210 T2509 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\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: /configs/conf1/protwords.txt
[junit4:junit4]   2> 219 T2509 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\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: /configs/conf1/currency.xml
[junit4:junit4]   2> 231 T2509 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\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: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 237 T2509 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 239 T2509 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 244 T2509 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\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: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 249 T2509 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\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: /configs/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 shut down
[junit4:junit4]   2> 257 T2511 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x139b2a3b6a70001, likely client has closed socket
[junit4:junit4]   2> 256 T2509 oaz.ZooKeeper.close Session: 0x139b2a3b6a70001 closed
[junit4:junit4]   2> 258 T2511 oazs.NIOServerCnxn.closeSock Closed socket connection 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-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 264 T2509 oasc.CoreContainer$Initializer.initialize looking 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.<init> New CoreContainer 9261682
[junit4:junit4]   2> 266 T2509 oasc.CoreContainer.load Loading CoreContainer 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.<init> new SolrResourceLoader 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 Listener
[junit4:junit4]   2> 341 T2509 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:52160/solr
[junit4:junit4]   2> 342 T2509 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52160/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@8ba538
[junit4:junit4]   2> 344 T2519 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:52160
[junit4:junit4]   2> 344 T2509 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 346 T2519 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:52160, initiating session
[junit4:junit4]   2> 347 T2511 oazs.NIOServerCnxn$Factory.run Accepted socket 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 Established 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, sessionid = 0x139b2a3b6a70002, negotiated timeout = 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 type:None path:null path:null type:None
[junit4:junit4]   2> 351 T2509 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 355 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 357 T2509 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 362 T2509 oasc.ZkController.createEphemeralLiveNode Register 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:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:7000_solr Error:KeeperErrorCode = 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 Setting socketTimeout to: 0
[junit4:junit4]   2> 376 T2509 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 376 T2509 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 377 T2509 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 377 T2509 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 378 T2509 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 378 T2509 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 378 T2509 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 379 T2509 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 379 T2509 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 389 T2509 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 398 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70002 type:delete cxid:0x15 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 400 T2509 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 405 T2509 oasc.Overseer.start Overseer (id=88298083924246530-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:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 409 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 411 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 414 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = 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: /clusterstate.json
[junit4:junit4]   2> 420 T2509 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 423 T2521 oasc.Overseer$ClusterStateUpdater.run Starting 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-Windows\solr\build\solr-core\test-files\solr\collection1
[junit4:junit4]   2> 428 T2509 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 430 T2509 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 430 T2509 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 432 T2509 oasc.SolrResourceLoader.<init> new SolrResourceLoader 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 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> 435 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> 501 T2509 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 601 T2509 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 603 T2509 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 611 T2509 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1500 T2509 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[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 field: id
[junit4:junit4]   2> 1536 T2509 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1542 T2509 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1548 T2509 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1550 T2509 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1551 T2509 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321837212\data_7000\
[junit4:junit4]   2> 1552 T2509 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed9f47
[junit4:junit4]   2> 1553 T2509 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321837212\data_7000\index/
[junit4:junit4]   2> 1555 T2509 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321837212\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_7000\index forceNew:false
[junit4:junit4]   2> 1558 T2509 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1c9f7fc lockFactory=org.apache.lucene.store.NativeFSLockFactory@aeb161),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1559 T2509 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 1560 T2509 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 1562 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 1563 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 1563 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 1564 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 1564 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-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.SearchHandler
[junit4:junit4]   2> 1571 T2509 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[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 xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1583 T2509 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config 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.<init> Opening Searcher@113fa54 main
[junit4:junit4]   2> 1587 T2509 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 1587 T2509 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1587 T2509 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1588 T2509 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 1600 T2509 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 1666 T2523 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@113fa54 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1671 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70002 type:create cxid:0x56 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 1679 T2509 oasc.CoreContainer.register registering core: 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: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 1692 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70002 type:delete cxid:0x65 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 1694 T2509 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> 1695 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70002 type:create cxid:0x66 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1698 T2509 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:7000/solr/collection1/
[junit4:junit4]   2> 1698 T2509 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 1705 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70002 type:create cxid:0x6f zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1935 T2521 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1936 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70002 type:create cxid:0x77 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 1959 T2520 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2015 T2509 oasc.ZkController.register We are http://127.0.0.1:7000/solr/collection1/ and leader is http://127.0.0.1:7000/solr/collection1/
[junit4:junit4]   2> 2015 T2509 oasc.ZkController.checkRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> 2019 T2509 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2024 T2509 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2024 T2509 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 2025 T2509 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\solr.xml
[junit4:junit4]   2> 2025 T2509 oasc.CoreContainer.<init> New CoreContainer 13898930
[junit4:junit4]   2> 2026 T2509 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 2026 T2509 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 2075 T2509 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 2092 T2509 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:52160/solr
[junit4:junit4]   2> 2093 T2509 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52160/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@18b62bb
[junit4:junit4]   2> 2095 T2524 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:52160
[junit4:junit4]   2> 2095 T2509 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2097 T2524 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:52160, initiating session
[junit4:junit4]   2> 2097 T2511 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:52172
[junit4:junit4]   2> 2098 T2511 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:52172
[junit4:junit4]   2> 2100 T2513 oazs.NIOServerCnxn.finishSessionInit Established session 0x139b2a3b6a70003 with negotiated timeout 6000 for client /127.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, sessionid = 0x139b2a3b6a70003, negotiated timeout = 6000
[junit4:junit4]   2> 2100 T2525 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@18b62bb name:ZooKeeperConnection Watcher:127.0.0.1:52160/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2101 T2509 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2102 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70003 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2104 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70003 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2106 T2509 oasc.ZkController.createEphemeralLiveNode Register 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:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:7001_solr Error:KeeperErrorCode = 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=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2128 T2509 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2136 T2509 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1
[junit4:junit4]   2> 2136 T2509 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 2138 T2509 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2138 T2509 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 2140 T2509 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\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.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 2289 T2509 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2290 T2509 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2297 T2509 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2475 T2521 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2486 T2520 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2486 T2525 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3114 T2509 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[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 field: id
[junit4:junit4]   2> 3151 T2509 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3157 T2509 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3163 T2509 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3165 T2509 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3167 T2509 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321837212\data_7001\
[junit4:junit4]   2> 3167 T2509 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed9f47
[junit4:junit4]   2> 3169 T2509 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321837212\data_7001\index/
[junit4:junit4]   2> 3170 T2509 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321837212\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_7001\index forceNew:false
[junit4:junit4]   2> 3173 T2509 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@9fe69a lockFactory=org.apache.lucene.store.NativeFSLockFactory@2bb272),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3173 T2509 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 3174 T2509 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 3176 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 3177 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 3177 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 3178 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 3179 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-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.SearchHandler
[junit4:junit4]   2> 3191 T2509 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[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 xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3204 T2509 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config 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.<init> Opening Searcher@1f1cea3 main
[junit4:junit4]   2> 3209 T2509 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 3210 T2509 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3210 T2509 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3211 T2509 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 3223 T2509 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 3284 T2526 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1f1cea3 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 3291 T2509 oasc.CoreContainer.register registering core: 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://127.0.0.1:7001/solr/collection1/ and leader is http://127.0.0.1:7000/solr/collection1/
[junit4:junit4]   2> 3296 T2509 oasc.ZkController.checkRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> 3298 T2509 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3303 T2509 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3303 T2509 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 3303 T2509 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\solr.xml
[junit4:junit4]   2> 3304 T2509 oasc.CoreContainer.<init> New CoreContainer 24126967
[junit4:junit4]   2> 3305 T2509 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 3305 T2509 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 3348 T2509 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3373 T2509 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:52160/solr
[junit4:junit4]   2> 3373 T2509 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52160/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@5ce511
[junit4:junit4]   2> 3375 T2527 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:52160
[junit4:junit4]   2> 3375 T2509 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3377 T2527 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:52160, initiating session
[junit4:junit4]   2> 3377 T2511 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:52175
[junit4:junit4]   2> 3377 T2511 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:52175
[junit4:junit4]   2> 3379 T2513 oazs.NIOServerCnxn.finishSessionInit Established session 0x139b2a3b6a70004 with negotiated timeout 6000 for client /127.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, sessionid = 0x139b2a3b6a70004, negotiated timeout = 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 type:None path:null path:null type:None
[junit4:junit4]   2> 3379 T2509 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3380 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3382 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3384 T2509 oasc.ZkController.createEphemeralLiveNode Register 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:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:7002_solr Error:KeeperErrorCode = 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=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 3410 T2509 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 3418 T2509 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1
[junit4:junit4]   2> 3418 T2509 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 3419 T2509 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 3420 T2509 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 3421 T2509 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\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.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 3494 T2521 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3510 T2525 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3511 T2520 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3510 T2528 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3596 T2509 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 3597 T2509 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 3609 T2509 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 4424 T2509 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[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 field: id
[junit4:junit4]   2> 4465 T2509 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4470 T2509 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4476 T2509 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4478 T2509 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4480 T2509 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321837212\data_7002\
[junit4:junit4]   2> 4480 T2509 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed9f47
[junit4:junit4]   2> 4482 T2509 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321837212\data_7002\index/
[junit4:junit4]   2> 4483 T2509 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321837212\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_7002\index forceNew:false
[junit4:junit4]   2> 4486 T2509 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@f7319f lockFactory=org.apache.lucene.store.NativeFSLockFactory@efa96b),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4487 T2509 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 4488 T2509 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 4490 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 4490 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 4490 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 4491 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 4491 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-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.SearchHandler
[junit4:junit4]   2> 4498 T2509 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[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 xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 4510 T2509 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config 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.<init> Opening Searcher@1941ea main
[junit4:junit4]   2> 4518 T2509 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 4518 T2509 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 4519 T2509 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 4519 T2509 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 4532 T2509 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 4605 T2529 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1941ea main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 4608 T2509 oasc.CoreContainer.register registering core: 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://127.0.0.1:7002/solr/collection1/ and leader is http://127.0.0.1:7000/solr/collection1/
[junit4:junit4]   2> 4614 T2509 oasc.ZkController.checkRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> 4618 T2509 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4623 T2509 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4624 T2509 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 4624 T2509 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\solr.xml
[junit4:junit4]   2> 4624 T2509 oasc.CoreContainer.<init> New CoreContainer 25353308
[junit4:junit4]   2> 4625 T2509 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 4625 T2509 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 4687 T2509 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 4712 T2509 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:52160/solr
[junit4:junit4]   2> 4713 T2509 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52160/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@cd1a50
[junit4:junit4]   2> 4715 T2509 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4717 T2530 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:52160
[junit4:junit4]   2> 4725 T2530 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:52160, initiating session
[junit4:junit4]   2> 4725 T2511 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:52178
[junit4:junit4]   2> 4725 T2511 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:52178
[junit4:junit4]   2> 4727 T2513 oazs.NIOServerCnxn.finishSessionInit Established session 0x139b2a3b6a70005 with negotiated timeout 6000 for client /127.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, sessionid = 0x139b2a3b6a70005, negotiated timeout = 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 type:None path:null path:null type:None
[junit4:junit4]   2> 4729 T2509 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4730 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70005 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4733 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70005 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4735 T2509 oasc.ZkController.createEphemeralLiveNode Register 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:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:7003_solr Error:KeeperErrorCode = 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=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 4762 T2509 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4775 T2509 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1
[junit4:junit4]   2> 4776 T2509 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 4778 T2509 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 4778 T2509 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 4780 T2509 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\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.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 4957 T2509 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 4959 T2509 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4964 T2509 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 5027 T2521 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5050 T2531 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5050 T2525 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5051 T2528 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5052 T2520 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5786 T2509 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[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 field: id
[junit4:junit4]   2> 5820 T2509 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5825 T2509 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5831 T2509 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5832 T2509 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5834 T2509 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321837212\data_7003\
[junit4:junit4]   2> 5834 T2509 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed9f47
[junit4:junit4]   2> 5836 T2509 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321837212\data_7003\index/
[junit4:junit4]   2> 5837 T2509 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321837212\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_7003\index forceNew:false
[junit4:junit4]   2> 5842 T2509 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@804170 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6a0453),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5843 T2509 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 5844 T2509 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 5847 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 5847 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 5847 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 5848 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 5848 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-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.SearchHandler
[junit4:junit4]   2> 5856 T2509 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[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 xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 5877 T2509 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config 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.<init> Opening Searcher@d62399 main
[junit4:junit4]   2> 5883 T2509 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 5884 T2509 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 5884 T2509 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 5885 T2509 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 5896 T2509 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 5959 T2532 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@d62399 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 5964 T2509 oasc.CoreContainer.register registering core: 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://127.0.0.1:7003/solr/collection1/ and leader is http://127.0.0.1:7000/solr/collection1/
[junit4:junit4]   2> 5970 T2509 oasc.ZkController.checkRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> 5973 T2509 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5978 T2509 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5978 T2509 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 5978 T2509 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\solr.xml
[junit4:junit4]   2> 5978 T2509 oasc.CoreContainer.<init> New CoreContainer 7816233
[junit4:junit4]   2> 5979 T2509 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 5980 T2509 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 6021 T2509 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 6046 T2509 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:52160/solr
[junit4:junit4]   2> 6046 T2509 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52160/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@11732a7
[junit4:junit4]   2> 6048 T2533 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:52160
[junit4:junit4]   2> 6048 T2509 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 6051 T2533 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:52160, initiating session
[junit4:junit4]   2> 6051 T2511 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:52181
[junit4:junit4]   2> 6051 T2511 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:52181
[junit4:junit4]   2> 6054 T2513 oazs.NIOServerCnxn.finishSessionInit Established session 0x139b2a3b6a70006 with negotiated timeout 6000 for client /127.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, sessionid = 0x139b2a3b6a70006, negotiated timeout = 6000
[junit4:junit4]   2> 6054 T2534 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@11732a7 name:ZooKeeperConnection Watcher:127.0.0.1:52160/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 6055 T2509 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 6057 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70006 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6060 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70006 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6062 T2509 oasc.ZkController.createEphemeralLiveNode Register 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:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:7004_solr Error:KeeperErrorCode = 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 Updating cloud state from ZooKeeper... 
[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=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 6080 T2531 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6081 T2525 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6082 T2528 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6086 T2509 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 6087 T2520 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6092 T2509 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1
[junit4:junit4]   2> 6092 T2509 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 6094 T2509 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 6094 T2509 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 6096 T2509 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\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.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 6260 T2509 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 6261 T2509 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 6268 T2509 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 7059 T2509 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[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 field: id
[junit4:junit4]   2> 7101 T2509 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 7107 T2509 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 7113 T2509 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 7115 T2509 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 7116 T2509 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321837212\data_7004\
[junit4:junit4]   2> 7117 T2509 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed9f47
[junit4:junit4]   2> 7119 T2509 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321837212\data_7004\index/
[junit4:junit4]   2> 7120 T2509 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321837212\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_7004\index forceNew:false
[junit4:junit4]   2> 7124 T2509 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@f73d21 lockFactory=org.apache.lucene.store.NativeFSLockFactory@4fbe80),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 7125 T2509 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 7126 T2509 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 7128 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 7129 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 7129 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 7129 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 7129 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-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.SearchHandler
[junit4:junit4]   2> 7137 T2509 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[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 xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 7151 T2509 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config 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.<init> Opening Searcher@1d9b182 main
[junit4:junit4]   2> 7157 T2509 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7157 T2509 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 7157 T2509 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 7158 T2509 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 7173 T2509 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 7236 T2535 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1d9b182 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 7242 T2509 oasc.CoreContainer.register registering core: 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://127.0.0.1:7004/solr/collection1/ and leader is http://127.0.0.1:7000/solr/collection1/
[junit4:junit4]   2> 7248 T2509 oasc.ZkController.checkRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> 7252 T2509 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7255 T2509 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 7256 T2509 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 7256 T2509 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\solr.xml
[junit4:junit4]   2> 7256 T2509 oasc.CoreContainer.<init> New CoreContainer 15760545
[junit4:junit4]   2> 7257 T2509 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 7257 T2509 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 7302 T2509 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 7322 T2509 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:52160/solr
[junit4:junit4]   2> 7322 T2509 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52160/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@1861cfa
[junit4:junit4]   2> 7324 T2509 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 7324 T2536 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:52160
[junit4:junit4]   2> 7326 T2536 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:52160, initiating session
[junit4:junit4]   2> 7327 T2511 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:52184
[junit4:junit4]   2> 7327 T2511 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:52184
[junit4:junit4]   2> 7330 T2513 oazs.NIOServerCnxn.finishSessionInit Established session 0x139b2a3b6a70007 with negotiated timeout 6000 for client /127.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, sessionid = 0x139b2a3b6a70007, negotiated timeout = 6000
[junit4:junit4]   2> 7331 T2537 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1861cfa name:ZooKeeperConnection Watcher:127.0.0.1:52160/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 7331 T2509 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 7332 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70007 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7334 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70007 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7337 T2509 oasc.ZkController.createEphemeralLiveNode Register 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:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:3333_solr Error:KeeperErrorCode = 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=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 7363 T2509 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 7371 T2509 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1
[junit4:junit4]   2> 7371 T2509 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 7373 T2509 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 7373 T2509 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 7375 T2509 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\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.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 7538 T2509 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 7541 T2509 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 7547 T2509 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 7594 T2521 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7612 T2528 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7613 T2534 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7613 T2537 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7613 T2520 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7613 T2531 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7613 T2525 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8369 T2509 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[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 field: id
[junit4:junit4]   2> -62695 T2509 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> -62679 T2509 oass.FileExchangeRateProvider.reload Reloading 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.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321837212\data_3333\
[junit4:junit4]   2> -62668 T2509 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed9f47
[junit4:junit4]   2> -62667 T2509 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321837212\data_3333\index/
[junit4:junit4]   2> -62666 T2509 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321837212\data_3333\index' doesn't exist. Creating new index...
[junit4:junit4]   2> -62665 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_3333\index forceNew:false
[junit4:junit4]   2> -62663 T2509 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1171c11 lockFactory=org.apache.lucene.store.NativeFSLockFactory@191f703),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> -62662 T2509 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> -62662 T2509 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> -62660 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> -62659 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> -62659 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> -62659 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> -62658 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> -62658 T2509 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> -62657 T2509 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> -62657 T2509 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> -62656 T2509 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> -62656 T2509 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> -62655 T2509 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> -62655 T2509 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> -62654 T2509 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> -62654 T2509 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> -62654 T2509 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> -62653 T2509 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> -62653 T2509 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> -62652 T2509 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> -62652 T2509 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> -62651 T2509 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> -62651 T2509 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> -62650 T2509 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> -62649 T2509 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> -62649 T2509 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> -62648 T2509 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> -62644 T2509 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> -62632 T2509 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> -62631 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> -62627 T2509 oass.SolrIndexSearcher.<init> Opening Searcher@ab6f92 main
[junit4:junit4]   2> -62626 T2509 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> -62625 T2509 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> -62625 T2509 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> -62625 T2509 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> -62588 T2509 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> -62519 T2538 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ab6f92 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> -62510 T2509 oasc.CoreContainer.register registering core: 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: /collections/collection1/leader_elect/shard2/election
[junit4:junit4]   2> -62499 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70007 type:delete cxid:0x41 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard2 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard2
[junit4:junit4]   2> -62497 T2509 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> -62496 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70007 type:create cxid:0x42 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -62492 T2509 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:3333/solr/collection1/
[junit4:junit4]   2> -62491 T2509 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4:junit4]   2> -62485 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70007 type:create cxid:0x4a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -62470 T2521 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> -62450 T2528 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -62450 T2534 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -62450 T2520 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -62449 T2537 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -62449 T2531 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -62448 T2525 oascc.ZkStateReader$2.process A cluster state 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/collection1/
[junit4:junit4]   2> -62430 T2509 oasc.ZkController.checkRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> -62425 T2509 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> -62423 T2509 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52160/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1df7fbc
[junit4:junit4]   2> -62421 T2539 oaz.ClientCnxn$SendThread.startConnect Opening 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 session
[junit4:junit4]   2> -62419 T2511 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:52187
[junit4:junit4]   2> -62419 T2511 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:52187
[junit4:junit4]   2> -62417 T2513 oazs.NIOServerCnxn.finishSessionInit Established session 0x139b2a3b6a70008 with negotiated timeout 10000 for client /127.0.0.1:52187
[junit4:junit4]   2> -62417 T2539 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:52160, sessionid = 0x139b2a3b6a70008, negotiated timeout = 10000
[junit4:junit4]   2> -62417 T2540 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1df7fbc name:ZooKeeperConnection 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.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> -62414 T2509 oasc.LeaderElectionIntegrationTest.setUp ####SETUP_END testSimpleSliceLeaderElection
[junit4:junit4]   2> -62413 T2509 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=9261682
[junit4:junit4]   2> -62413 T2509 oasc.SolrCore.close [collection1]  CLOSING 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 DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> -62409 T2509 oasu.DefaultSolrCoreState.decref SolrCoreState 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.amILeader According to ZK I (id=88298083924246530-127.0.0.1:7000_solr-n_0000000000) am no longer a leader.
[junit4:junit4]   2> -62406 T2514 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139b2a3b6a70002
[junit4:junit4]   2> -62404 T2534 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> -62403 T2540 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> -62403 T2528 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> -62403 T2520 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> -62401 T2511 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:52169 which had sessionid 0x139b2a3b6a70002
[junit4:junit4]   2> -62402 T2531 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> -62401 T2537 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> -62400 T2509 oaz.ZooKeeper.close Session: 0x139b2a3b6a70002 closed
[junit4:junit4]   2> -62399 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70003 type:delete cxid:0x48 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> -62396 T2525 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> -62391 T2525 oasc.Overseer.start Overseer (id=88298083924246531-127.0.0.1:7001_solr-n_0000000001) starting
[junit4:junit4]   2> -62391 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70003 type:create cxid:0x4d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -62389 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70003 type:create cxid:0x4e zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -62386 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70003 type:create cxid:0x4f zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -62384 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70003 type:create cxid:0x50 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -62382 T2542 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> -62381 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70003 type:delete cxid:0x54 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard1
[junit4:junit4]   2> -62379 T2525 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> -62379 T2541 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> -62378 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70003 type:create cxid:0x57 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -62375 T2525 oasc.ShardLeaderElectionContext.shouldIBeLeader Checking if I should try and be the leader.
[junit4:junit4]   2> -62375 T2525 oasc.ShardLeaderElectionContext.shouldIBeLeader I am Active and live, it's okay to be the leader.
[junit4:junit4]   2> -62375 T2525 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> -62375 T2525 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:7001/solr/collection1/
[junit4:junit4]   2> -62375 T2525 oasc.SyncStrategy.sync SEVERE No UpdateLog found - cannot sync
[junit4:junit4]   2> -62374 T2525 oasc.ShardLeaderElectionContext.rejoinLeaderElection There is a better leader candidate than us - going back into recovery
[junit4:junit4]   2> -62374 T2541 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> -62370 T2525 oasu.DefaultSolrCoreState.doRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> -62367 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70004 type:delete cxid:0x47 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard1
[junit4:junit4]   2> -62364 T2528 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> -62363 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70004 type:create cxid:0x48 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -62361 T2525 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> -62359 T2528 oasc.ShardLeaderElectionContext.shouldIBeLeader Checking if I should try and be the leader.
[junit4:junit4]   2> -62359 T2528 oasc.ShardLeaderElectionContext.shouldIBeLeader I am Active and live, it's okay to be the leader.
[junit4:junit4]   2> -62358 T2528 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> -62358 T2528 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:7002/solr/collection1/
[junit4:junit4]   2> -62358 T2528 oasc.SyncStrategy.sync SEVERE No UpdateLog found - cannot sync
[junit4:junit4]   2> -62357 T2528 oasc.ShardLeaderElectionContext.rejoinLeaderElection There is a better leader candidate than us - going back into recovery
[junit4:junit4]   2> -62353 T2528 oasu.DefaultSolrCoreState.doRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> -62351 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70005 type:delete cxid:0x45 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard1
[junit4:junit4]   2> -62349 T2531 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> -62348 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70005 type:create cxid:0x46 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -62344 T2531 oasc.ShardLeaderElectionContext.shouldIBeLeader Checking if I should try and be the leader.
[junit4:junit4]   2> -62342 T2531 oasc.ShardLeaderElectionContext.shouldIBeLeader I am Active and live, it's okay to be the leader.
[junit4:junit4]   2> -62342 T2531 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> -62342 T2531 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:7003/solr/collection1/
[junit4:junit4]   2> -62342 T2531 oasc.SyncStrategy.sync SEVERE No UpdateLog found - cannot sync
[junit4:junit4]   2> -62342 T2531 oasc.ShardLeaderElectionContext.rejoinLeaderElection There is a better leader candidate than us - going back into recovery
[junit4:junit4]   2> -62338 T2531 oasu.DefaultSolrCoreState.doRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> -62336 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70006 type:delete cxid:0x42 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard1
[junit4:junit4]   2> -62334 T2534 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> -62333 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70006 type:create cxid:0x43 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -62329 T2534 oasc.ShardLeaderElectionContext.shouldIBeLeader Checking if I should try and be the leader.
[junit4:junit4]   2> -62328 T2534 oasc.ShardLeaderElectionContext.shouldIBeLeader I am Active and live, it's okay to be the leader.
[junit4:junit4]   2> -62328 T2534 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> -62328 T2534 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:7004/solr/collection1/
[junit4:junit4]   2> -62328 T2534 oasc.SyncStrategy.sync SEVERE No UpdateLog found - cannot sync
[junit4:junit4]   2> -62328 T2534 oasc.ShardLeaderElectionContext.rejoinLeaderElection There is a better leader candidate than us - going back into recovery
[junit4:junit4]   2> -62323 T2534 oasu.DefaultSolrCoreState.doRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> -62281 T2531 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -62281 T2534 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -62281 T2537 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -62280 T2528 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -62281 T2540 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -62253 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70003 type:delete cxid:0xb7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard1
[junit4:junit4]   2> -62251 T2525 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> -62251 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70003 type:create cxid:0xb8 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -62249 T2525 oasc.ShardLeaderElectionContext.shouldIBeLeader Checking if I should try and be the leader.
[junit4:junit4]   2> -62248 T2525 oasc.ShardLeaderElectionContext.shouldIBeLeader I am not Active but no one else is either, it's okay to be the leader
[junit4:junit4]   2> -62246 T2525 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> -62246 T2525 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:7001/solr/collection1/
[junit4:junit4]   2> -62246 T2525 oasc.SyncStrategy.sync SEVERE No UpdateLog found - cannot sync
[junit4:junit4]   2> -62246 T2525 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:7001/solr/collection1/
[junit4:junit4]   2> -62246 T2525 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> -62241 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70003 type:create cxid:0xc1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -62237 T2525 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -61752 T2541 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> -61733 T2528 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -61733 T2540 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -61733 T2534 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -61733 T2525 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -61733 T2537 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -61733 T2531 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -61696 T2509 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=13898930
[junit4:junit4]   2> -61696 T2509 oasc.SolrCore.close [collection1]  CLOSING 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 DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> -61691 T2509 oasu.DefaultSolrCoreState.decref SolrCoreState 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.amILeader According to ZK I (id=88298083924246531-127.0.0.1:7001_solr-n_0000000001) am no longer a leader.
[junit4:junit4]   2> -61689 T2514 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139b2a3b6a70003
[junit4:junit4]   2> -61686 T2531 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> -61686 T2534 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> -61685 T2511 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:52172 which had sessionid 0x139b2a3b6a70003
[junit4:junit4]   2> -61684 T2537 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> -61684 T2525 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> -61683 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70004 type:delete cxid:0x52 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard1
[junit4:junit4]   2> -61684 T2540 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> -61684 T2509 oaz.ZooKeeper.close Session: 0x139b2a3b6a70003 closed
[junit4:junit4]   2> -61675 T2528 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> -61674 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70004 type:create cxid:0x53 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -61670 T2528 oasc.ShardLeaderElectionContext.shouldIBeLeader Checking if I should try and be the leader.
[junit4:junit4]   2> -61670 T2528 oasc.ShardLeaderElectionContext.shouldIBeLeader I am not Active and someone else appears to be a better leader candidate.
[junit4:junit4]   2> -61669 T2528 oasc.ShardLeaderElectionContext.rejoinLeaderElection There is a better leader candidate than us - going back into recovery
[junit4:junit4]   2> -61666 T2528 oasu.DefaultSolrCoreState.doRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> -61664 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70005 type:delete cxid:0x51 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard1
[junit4:junit4]   2> -61661 T2531 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> -61661 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70005 type:create cxid:0x52 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -61659 T2528 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> -61657 T2531 oasc.ShardLeaderElectionContext.shouldIBeLeader Checking if I should try and be the leader.
[junit4:junit4]   2> -61657 T2531 oasc.ShardLeaderElectionContext.shouldIBeLeader I am not Active but no one else is either, it's okay to be the leader
[junit4:junit4]   2> -61655 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70004 type:delete cxid:0x5c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> -61654 T2531 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> -61654 T2531 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:7003/solr/collection1/
[junit4:junit4]   2> -61654 T2531 oasc.SyncStrategy.sync SEVERE No UpdateLog found - cannot sync
[junit4:junit4]   2> -61653 T2531 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:7003/solr/collection1/
[junit4:junit4]   2> -61653 T2531 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> -61651 T2528 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> -61647 T2528 oasc.Overseer.start Overseer (id=88298083924246532-127.0.0.1:7002_solr-n_0000000002) starting
[junit4:junit4]   2> -61646 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70004 type:create cxid:0x61 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -61646 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70005 type:create cxid:0x5b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -61644 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70004 type:create cxid:0x62 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -61642 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70004 type:create cxid:0x63 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -61638 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70004 type:create cxid:0x64 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -61634 T2544 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> -61633 T2543 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> -61630 T2543 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> -61593 T2528 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -61592 T2534 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -61592 T2531 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -61592 T2537 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -61592 T2540 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -61402 T2520 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> -61402 T2520 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> -61177 T2509 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=25353308
[junit4:junit4]   2> -61177 T2509 oasc.SolrCore.close [collection1]  CLOSING 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 DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> -61170 T2509 oasu.DefaultSolrCoreState.decref SolrCoreState 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 Processed session termination for sessionid: 0x139b2a3b6a70005
[junit4:junit4]   2> -61167 T2528 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> -61167 T2534 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> -61167 T2540 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> -61167 T2531 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> -61165 T2511 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:52178 which had sessionid 0x139b2a3b6a70005
[junit4:junit4]   2> -61165 T2537 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> -61164 T2509 oaz.ZooKeeper.close Session: 0x139b2a3b6a70005 closed
[junit4:junit4]   2> -61162 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70006 type:delete cxid:0x52 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard1
[junit4:junit4]   2> -61160 T2534 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> -61160 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70006 type:create cxid:0x53 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -61155 T2534 oasc.ShardLeaderElectionContext.shouldIBeLeader Checking if I should try and be the leader.
[junit4:junit4]   2> -61155 T2534 oasc.ShardLeaderElectionContext.shouldIBeLeader I am not Active but no one else is either, it's okay to be the leader
[junit4:junit4]   2> -61153 T2534 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> -61153 T2534 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:7004/solr/collection1/
[junit4:junit4]   2> -61152 T2534 oasc.SyncStrategy.sync SEVERE No UpdateLog found - cannot sync
[junit4:junit4]   2> -61152 T2534 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:7004/solr/collection1/
[junit4:junit4]   2> -61152 T2534 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> -61145 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70006 type:create cxid:0x5c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -61075 T2543 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> -61050 T2528 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -61049 T2537 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -61049 T2540 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -61049 T2534 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -60684 T2525 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> -60684 T2525 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> -60664 T2509 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=7816233
[junit4:junit4]   2> -60664 T2509 oasc.SolrCore.close [collection1]  CLOSING 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 DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> -60660 T2509 oasu.DefaultSolrCoreState.decref SolrCoreState 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 Processed session termination for sessionid: 0x139b2a3b6a70006
[junit4:junit4]   2> -60656 T2534 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> -60656 T2511 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:52181 which had sessionid 0x139b2a3b6a70006
[junit4:junit4]   2> -60656 T2540 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> -60655 T2509 oaz.ZooKeeper.close Session: 0x139b2a3b6a70006 closed
[junit4:junit4]   2> -60654 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70004 type:delete cxid:0xc1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard1
[junit4:junit4]   2> -60653 T2528 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> -60653 T2537 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> -60653 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70004 type:create cxid:0xc2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -60649 T2528 oasc.ShardLeaderElectionContext.shouldIBeLeader Checking if I should try and be the leader.
[junit4:junit4]   2> -60648 T2528 oasc.ShardLeaderElectionContext.shouldIBeLeader I am not Active and someone else appears to be a better leader candidate.
[junit4:junit4]   2> -60648 T2528 oasc.ShardLeaderElectionContext.rejoinLeaderElection There is a better leader candidate than us - going back into recovery
[junit4:junit4]   2> -60645 T2528 oasu.DefaultSolrCoreState.doRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> -60641 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70004 type:delete cxid:0xc8 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard1
[junit4:junit4]   2> -60640 T2528 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> -60639 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70004 type:create cxid:0xc9 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -60635 T2528 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:7002/solr/collection1/
[junit4:junit4]   2> -60635 T2528 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> -60626 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70004 type:create cxid:0xd1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -60622 T2528 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> -60532 T2543 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> -60504 T2528 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -60504 T2537 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -60503 T2540 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -60165 T2531 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> -60165 T2531 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> -60154 T2514 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139b2a3b6a70008
[junit4:junit4]   2> -60151 T2540 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> -60150 T2511 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x139b2a3b6a70008, likely client has closed socket
[junit4:junit4]   2> -60151 T2509 oaz.ZooKeeper.close Session: 0x139b2a3b6a70008 closed
[junit4:junit4]   2> -60149 T2511 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:52187 which had sessionid 0x139b2a3b6a70008
[junit4:junit4]   2> -60149 T2509 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=24126967
[junit4:junit4]   2> -60148 T2509 oasc.SolrCore.close [collection1]  CLOSING 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 DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> -60137 T2509 oasu.DefaultSolrCoreState.decref SolrCoreState 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.amILeader According to ZK I (id=88298083924246532-127.0.0.1:7002_solr-n_0000000002) am no longer a leader.
[junit4:junit4]   2> -60132 T2514 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139b2a3b6a70004
[junit4:junit4]   2> -60126 T2528 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> -60125 T2511 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:52175 which had sessionid 0x139b2a3b6a70004
[junit4:junit4]   2> -60125 T2509 oaz.ZooKeeper.close Session: 0x139b2a3b6a70004 closed
[junit4:junit4]   2> -60124 T2509 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=15760545
[junit4:junit4]   2> -60123 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70007 type:delete cxid:0x5d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> -60123 T2509 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@5bc5cd
[junit4:junit4]   2> -60119 T2537 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> -60103 T2537 oasc.Overseer.start Overseer (id=88298083924246535-127.0.0.1:3333_solr-n_0000000005) starting
[junit4:junit4]   2> -60102 T2514 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a3b6a70007 type:create cxid:0x62 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = 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 DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> -60101 T2509 oasu.DefaultSolrCoreState.decref SolrCoreState 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 Processed session termination for sessionid: 0x139b2a3b6a70007
[junit4:junit4]   2> -60096 T2511 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:52184 which had sessionid 0x139b2a3b6a70007
[junit4:junit4]   2> -60095 T2509 oaz.ZooKeeper.close Session: 0x139b2a3b6a70007 closed
[junit4:junit4]   2> -60093 T2514 oazs.PrepRequestProcessor.run PrepRequestProcessor exited loop!
[junit4:junit4]   2> -60091 T2513 oazs.SyncRequestProcessor.run SyncRequestProcessor exited!
[junit4:junit4]   2> -60091 T2509 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
[junit4:junit4]   2> -59656 T2534 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> -57838 T2534 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> -57838 T2511 oazs.NIOServerCnxn$Factory.run NIOServerCnxn factory exited run method
[junit4:junit4]   2> -59097 T2537 oasc.Overseer.createOverseerNode SEVERE Could not create Overseer node: class org.apache.zookeeper.KeeperException$SessionExpiredException:KeeperErrorCode = Session expired for /overseer
[junit4:junit4]   2> -59125 T2528 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> -57837 T2537 oasc.LeaderElector$1.process WARNING  java.lang.RuntimeException: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer
[junit4:junit4]   2> 	at org.apache.solr.cloud.Overseer.createOverseerNode(Overseer.java:524)
[junit4:junit4]   2> 	at org.apache.solr.cloud.Overseer.getInQueue(Overseer.java:497)
[junit4:junit4]   2> 	at org.apache.solr.cloud.Overseer$ClusterStateUpdater.<init>(Overseer.java:67)
[junit4:junit4]   2> 	at org.apache.solr.cloud.Overseer.start(Overseer.java:469)
[junit4:junit4]   2> 	at org.apache.solr.cloud.OverseerElectionContext.runLeaderProcess(ElectionContext.java:399)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:159)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:103)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:57)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:132)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:526)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
[junit4:junit4]   2> Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer
[junit4:junit4]   2> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:118)
[junit4:junit4]   2> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:42)
[junit4:junit4]   2> 	at org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:643)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$9.execute(SolrZkClient.java:349)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$9.execute(SolrZkClient.java:346)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:67)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient.create(SolrZkClient.java:346)
[junit4:junit4]   2> 	at org.apache.solr.cloud.Overseer.createOverseerNode(Overseer.java:515)
[junit4:junit4]   2> 	... 10 more
[junit4:junit4]   2> 
[junit4:junit4]   2> -57835 T2537 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> -57835 T2537 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> -57837 T2528 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> -57836 T2509 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
[junit4:junit4]   2> -57835 T2537 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> -57834 T2509 oas.SolrTestCaseJ4.tearDown ###Ending testSimpleSliceLeaderElection
[junit4:junit4]   2> -57831 T2509 oas.SolrTestCaseJ4.setUp ###Starting testLeaderElectionAfterClientTimeout
[junit4:junit4]   2> Creating dataDir: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321779377
[junit4:junit4]   2> -57829 T2509 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> -57824 T2545 oazs.ZooKeeperServer.setTickTime tickTime set to 3000
[junit4:junit4]   2> -57823 T2545 oazs.NIOServerCnxn$Factory.<init> 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.<init> Initiating client connection, connectString=127.0.0.1:52190 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1fe37d8
[junit4:junit4]   2> -57715 T2550 oaz.ClientCnxn$SendThread.startConnect Opening 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 session
[junit4:junit4]   2> -57714 T2546 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:52193
[junit4:junit4]   2> -57713 T2546 oazs.NIOServerCnxn.readConnectRequest Client 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 Established session 0x139b2a2d4bc0000 with negotiated timeout 10000 for client /127.0.0.1:52193
[junit4:junit4]   2> -57707 T2550 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:52190, sessionid = 0x139b2a2d4bc0000, negotiated timeout = 10000
[junit4:junit4]   2> -57706 T2551 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1fe37d8 name:ZooKeeperConnection 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: /solr
[junit4:junit4]   2> -57701 T2549 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139b2a2d4bc0000
[junit4:junit4]   2> -57700 T2509 oaz.ZooKeeper.close Session: 0x139b2a2d4bc0000 closed
[junit4:junit4]   2> -57700 T2546 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x139b2a2d4bc0000, likely client has closed socket
[junit4:junit4]   2> -57700 T2509 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52190/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@156d8af
[junit4:junit4]   2> -57699 T2551 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> -57692 T2546 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:52193 which had sessionid 0x139b2a2d4bc0000
[junit4:junit4]   2> -57691 T2552 oaz.ClientCnxn$SendThread.startConnect Opening 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 session
[junit4:junit4]   2> -57688 T2546 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:52196
[junit4:junit4]   2> -57688 T2546 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:52196
[junit4:junit4]   2> -57685 T2548 oazs.NIOServerCnxn.finishSessionInit Established session 0x139b2a2d4bc0001 with negotiated timeout 10000 for client /127.0.0.1:52196
[junit4:junit4]   2> -57685 T2552 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:52190, sessionid = 0x139b2a2d4bc0001, negotiated timeout = 10000
[junit4:junit4]   2> -57685 T2553 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@156d8af name:ZooKeeperConnection 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: /collections/collection1
[junit4:junit4]   2> -57678 T2509 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> -57672 T2509 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> -57669 T2509 oascc.SolrZkClient.makePath makePath: /collections/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\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> -57663 T2509 oascc.SolrZkClient.makePath makePath: /configs/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\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> -57652 T2509 oascc.SolrZkClient.makePath makePath: /configs/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\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> -57645 T2509 oascc.SolrZkClient.makePath makePath: /configs/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\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> -57638 T2509 oascc.SolrZkClient.makePath makePath: /configs/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\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> -57632 T2509 oascc.SolrZkClient.makePath makePath: /configs/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\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> -57627 T2509 oascc.SolrZkClient.makePath makePath: /configs/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\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> -57621 T2509 oascc.SolrZkClient.makePath makePath: /configs/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\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> -57614 T2509 oascc.SolrZkClient.makePath makePath: /configs/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\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> -57608 T2509 oascc.SolrZkClient.makePath makePath: /configs/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\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> -57604 T2509 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> -57598 T2549 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139b2a2d4bc0001
[junit4:junit4]   2> -57597 T2546 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x139b2a2d4bc0001, likely client has closed socket
[junit4:junit4]   2> -57596 T2553 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> -57596 T2546 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:52196 which had sessionid 0x139b2a2d4bc0001
[junit4:junit4]   2> -57597 T2509 oaz.ZooKeeper.close Session: 0x139b2a2d4bc0001 closed
[junit4:junit4]   2> -57594 T2509 oasc.LeaderElectionIntegrationTest.setUp ####SETUP_START testLeaderElectionAfterClientTimeout
[junit4:junit4]   2> -57592 T2509 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> -57591 T2509 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\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\build\solr-core\test-files\solr\solr.xml
[junit4:junit4]   2> -57591 T2509 oasc.CoreContainer.<init> New CoreContainer 32694012
[junit4:junit4]   2> -57590 T2509 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> -57589 T2509 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> -57546 T2509 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> -57521 T2509 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:52190/solr
[junit4:junit4]   2> -57520 T2509 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52190/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@1a35fbb
[junit4:junit4]   2> -57519 T2554 oaz.ClientCnxn$SendThread.startConnect Opening 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 session
[junit4:junit4]   2> -57517 T2546 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:52199
[junit4:junit4]   2> -57516 T2546 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:52199
[junit4:junit4]   2> -57514 T2548 oazs.NIOServerCnxn.finishSessionInit Established session 0x139b2a2d4bc0002 with negotiated timeout 6000 for client /127.0.0.1:52199
[junit4:junit4]   2> -57514 T2554 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:52190, sessionid = 0x139b2a2d4bc0002, negotiated timeout = 6000
[junit4:junit4]   2> -57514 T2555 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1a35fbb name:ZooKeeperConnection 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 user-level KeeperException when processing sessionid:0x139b2a2d4bc0002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -57509 T2509 oascc.SolrZkClient.makePath makePath: /live_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 user-level KeeperException when processing sessionid:0x139b2a2d4bc0002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:7000_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:7000_solr
[junit4:junit4]   2> -57503 T2509 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:7000_solr
[junit4:junit4]   2> -57498 T2509 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> -57498 T2509 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> -57498 T2509 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> -57497 T2509 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> -57497 T2509 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> -57497 T2509 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> -57497 T2509 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> -57497 T2509 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> -57497 T2509 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> -57496 T2509 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> -57478 T2509 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> -57466 T2549 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a2d4bc0002 type:delete cxid:0x15 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> -57464 T2509 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> -57459 T2509 oasc.Overseer.start Overseer (id=88298080133971970-127.0.0.1:7000_solr-n_0000000000) starting
[junit4:junit4]   2> -57458 T2549 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a2d4bc0002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -57456 T2549 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a2d4bc0002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -57455 T2549 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a2d4bc0002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -57453 T2549 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a2d4bc0002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -57448 T2557 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> -57448 T2509 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> -57444 T2509 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> -57440 T2556 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> -57433 T2509 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\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 collection config from:/collections/collection1
[junit4:junit4]   2> -57429 T2509 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> -57427 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> -57425 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> -57354 T2509 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> -57257 T2509 oasc.SolrConfig.<init> 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=test
[junit4:junit4]   2> -56341 T2509 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> -56325 T2509 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> -56314 T2509 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> -56294 T2509 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> -56288 T2509 oass.FileExchangeRateProvider.reload Reloading 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.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321779377\data_7000\
[junit4:junit4]   2> -56276 T2509 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed9f47
[junit4:junit4]   2> -56274 T2509 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321779377\data_7000\index/
[junit4:junit4]   2> -56273 T2509 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321779377\data_7000\index' doesn't exist. Creating new index...
[junit4:junit4]   2> -56272 T2509 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321779377\data_7000\index forceNew:false
[junit4:junit4]   2> -56270 T2509 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@105682b lockFactory=org.apache.lucene.store.NativeFSLockFactory@1dff861),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> -56269 T2509 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> -56268 T2509 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> -56266 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> -56266 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> -56266 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> -56265 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> -56265 T2509 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> -56264 T2509 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> -56264 T2509 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> -56262 T2509 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> -56261 T2509 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> -56261 T2509 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> -56261 T2509 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> -56260 T2509 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> -56260 T2509 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> -56260 T2509 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> -56259 T2509 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> -56258 T2509 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> -56258 T2509 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> -56257 T2509 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> -56256 T2509 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> -56256 T2509 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> -56255 T2509 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> -56255 T2509 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> -56255 T2509 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> -56255 T2509 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> -56254 T2509 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> -56250 T2509 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> -56242 T2509 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> -56241 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> -56237 T2509 oass.SolrIndexSearcher.<init> Opening Searcher@c1b118 main
[junit4:junit4]   2> -56237 T2509 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> -56236 T2509 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> -56236 T2509 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> -56235 T2509 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> -56222 T2509 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> -56154 T2558 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c1b118 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> -56150 T2549 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a2d4bc0002 type:create cxid:0x56 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> -56143 T2509 oasc.CoreContainer.register registering core: 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: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> -56130 T2549 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a2d4bc0002 type:delete cxid:0x65 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> -56128 T2509 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> -56127 T2549 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a2d4bc0002 type:create cxid:0x66 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -56123 T2509 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:7000/solr/collection1/
[junit4:junit4]   2> -56123 T2509 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> -56116 T2549 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a2d4bc0002 type:create cxid:0x6f zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -55779 T2556 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> -55778 T2549 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a2d4bc0002 type:create cxid:0x77 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> -55748 T2555 oascc.ZkStateReader$2.process A cluster state 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/collection1/
[junit4:junit4]   2> -55718 T2509 oasc.ZkController.checkRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> -55715 T2509 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> -55709 T2509 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> -55708 T2509 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\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\build\solr-core\test-files\solr\solr.xml
[junit4:junit4]   2> -55707 T2509 oasc.CoreContainer.<init> New CoreContainer 9755849
[junit4:junit4]   2> -55707 T2509 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> -55707 T2509 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> -55640 T2509 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> -55619 T2509 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:52190/solr
[junit4:junit4]   2> -55618 T2509 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52190/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@13aea05
[junit4:junit4]   2> -55616 T2559 oaz.ClientCnxn$SendThread.startConnect Opening 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 session
[junit4:junit4]   2> -55614 T2546 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:52202
[junit4:junit4]   2> -55614 T2546 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:52202
[junit4:junit4]   2> -55611 T2548 oazs.NIOServerCnxn.finishSessionInit Established session 0x139b2a2d4bc0003 with negotiated timeout 6000 for client /127.0.0.1:52202
[junit4:junit4]   2> -55610 T2559 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:52190, sessionid = 0x139b2a2d4bc0003, negotiated timeout = 6000
[junit4:junit4]   2> -55609 T2560 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@13aea05 name:ZooKeeperConnection 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 user-level KeeperException when processing sessionid:0x139b2a2d4bc0003 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> -55606 T2549 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b2a2d4bc0003 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = 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 user-level KeeperException when processing sessionid:0x139b2a2d4bc0003 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:7001_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:7001_solr
[junit4:junit4]   2> -55600 T2509 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:7001_solr
[junit4:junit4]   2> -55596 T2555 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> -55595 T2509 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> -55595 T2509 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> -55594 T2509 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> -55594 T2509 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> -55594 T2509 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> -55594 T2509 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> -55594 T2509 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> -55593 T2509 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> -55593 T2509 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> -55593 T2509 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> -55576 T2509 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> -55566 T2509 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\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 collection config from:/collections/collection1
[junit4:junit4]   2> -55563 T2509 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> -55560 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> -55558 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> -55489 T2509 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> -55385 T2509 oasc.SolrConfig.<init> 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=test
[junit4:junit4]   2> -55181 T2556 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> -55082 T2560 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -55082 T2555 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> -54484 T2509 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> -54471 T2509 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> -54465 T2509 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> -54446 T2509 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> -54440 T2509 oass.FileExchangeRateProvider.reload Reloading 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.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321779377\data_7001\
[junit4:junit4]   2> -54429 T2509 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed9f47
[junit4:junit4]   2> -54428 T2509 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321779377\data_7001\index/
[junit4:junit4]   2> -54426 T2509 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1347321779377\data_7001\index' doesn't exist. Creating new index...
[junit4:junit4]   2> -54424 T2509 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-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=32694012
[junit4:junit4]   2> -40765 T2509 oasc.SolrCore.close [collection1]  CLOSING 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 DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> -40762 T2509 oasu.DefaultSolrCoreState.decref SolrCoreState 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 Processed session termination for sessionid: 0x139b2a2d4bc0002
[junit4:junit4]   2> -40759 T2556 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=88298080133971970-127.0.0.1:7000_solr-n_0000000000) am no longer a leader.
[junit4:junit4]   2> -40754 T2555 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> -40754 T2546 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:52218 which had sessionid 0x139b2a2d4bc0002
[junit4:junit4]   2> -40754 T2509 oaz.ZooKeeper.close Session: 0x139b2a2d4bc0002 closed
[junit4:junit4]   2> -40754 T2549 oazs.PrepRequestProcessor.run PrepRequestProcessor exited loop!
[junit4:junit4]   2> -40752 T2548 oazs.SyncRequestProcessor.run SyncRequestProcessor exited!
[junit4:junit4]   2> -40751 T2509 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
[junit4:junit4]   2> -39632 T2563 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> -38026 T2563 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> -38689 T2547 oazs.SessionTrackerImpl.run SessionTrackerImpl exited loop!
[junit4:junit4]   2> -39544 T2555 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> -38025 T2555 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> -39560 T2572 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> -38025 T2572 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> -39586 T2569 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> -38025 T2569 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> -39614 T2566 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> -38026 T2546 oazs.NIOServerCnxn$Factory.run WARNING Ignoring exception java.nio.channels.ClosedChannelException
[junit4:junit4]   2> 	at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:135)
[junit4:junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn$Factory.run(NIOServerCnxn.java:242)
[junit4:junit4]   2> 
[junit4:junit4]   2> -38024 T2546 oazs.NIOServerCnxn$Factory.run NIOServerCnxn factory exited run method
[junit4:junit4]   2> -38024 T2566 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> -38020 T2509 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
[junit4:junit4]   2> -38019 T2509 oas.SolrTestCaseJ4.tearDown ###Ending testLeaderElectionAfterClientTimeout
[junit4:junit4]   2> -35598 T2509 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> -35300 T2508 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s).
[junit4:junit4]   2> -15104 T2508 ccr.ThreadLeakControl.checkThreadLeaks SEVERE 1 thread leaked from SUITE scope at org.apache.solr.cloud.LeaderElectionIntegrationTest: 
[junit4:junit4]   2> 	   1) Thread[id=2512, name=SessionTracker, state=TIMED_WAITING, group=TGRP-LeaderElectionIntegrationTest]
[junit4:junit4]   2> 	        at java.lang.Object.wait(Native Method)
[junit4:junit4]   2> 	        at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:145)
[junit4:junit4]   2> -15104 T2508 ccr.ThreadLeakControl.tryToInterruptAll Starting to interrupt leaked threads:
[junit4:junit4]   2> 	   1) Thread[id=2512, name=SessionTracker, state=TIMED_WAITING, group=TGRP-LeaderElectionIntegrationTest]
[junit4:junit4]   2> -15103 T2512 oazs.SessionTrackerImpl.run SEVERE Unexpected interruption java.lang.InterruptedException
[junit4:junit4]   2> 	at java.lang.Object.wait(Native Method)
[junit4:junit4]   2> 	at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:145)
[junit4:junit4]   2> 
[junit4:junit4]   2> -15103 T2512 oazs.SessionTrackerImpl.run SessionTrackerImpl exited loop!
[junit4:junit4]   2> -15101 T2508 ccr.ThreadLeakControl.tryToInterruptAll All leaked threads terminated.
[junit4:junit4]   2> NOTE: test params are: codec=SimpleText, sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=mt, timezone=America/Argentina/La_Rioja
[junit4:junit4]   2> NOTE: Windows 7 6.1 x86/Sun Microsystems Inc. 1.6.0_35 (32-bit)/cpus=2,threads=1,free=114153648,total=175042560
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestStressVersions, SearchHandlerTest, TestDocumentBuilder, TestQueryUtils, PingRequestHandlerTest, HighlighterTest, RequestHandlersTest, TestRangeQuery, PathHierarchyTokenizerFactoryTest, UpdateParamsTest, HighlighterConfigTest, NotRequiredUniqueKeyTest, TestStressRecovery, TestSolrDeletionPolicy2, TestMultiCoreConfBootstrap, BinaryUpdateRequestHandlerTest, XsltUpdateRequestHandlerTest, TestElisionMultitermQuery, TestSolrQueryParser, EchoParamsTest, TestDFRSimilarityFactory, DistributedSpellCheckComponentTest, PluginInfoTest, TestJmxIntegration, TestValueSourceCache, NumericFieldsTest, DirectSolrConnectionTest, TestNumberUtils, SortByFunctionTest, SolrPluginUtilsTest, MinimalSchemaTest, TestSolrJ, LegacyHTMLStripCharFilterTest, CSVRequestHandlerTest, TestTrie, TermsComponentTest, TestPluginEnable, DirectUpdateHandlerOptimizeTest, SolrCoreTest, TestFastWriter, TestSolrDeletionPolicy1, SignatureUpdateProcessorFactoryTest, CurrencyFieldTest, TestBM25SimilarityFactory, TestQuerySenderNoQuery, TestFastOutputStream, CoreAdminHandlerTest, LeaderElectionTest, SpellingQueryConverterTest, TestOmitPositions, AlternateDirectoryTest, TestMergePolicyConfig, DisMaxRequestHandlerTest, TestBinaryResponseWriter, TestLFUCache, TermVectorComponentDistributedTest, SpatialFilterTest, TestRealTimeGet, TestIBSimilarityFactory, CacheHeaderTest, DistributedTermsComponentTest, TestReversedWildcardFilterFactory, FullSolrCloudDistribCmdsTest, TestFaceting, TestGroupingSearch, TestSuggestSpellingConverter, SOLR749Test, MBeansHandlerTest, CopyFieldTest, DirectSolrSpellCheckerTest, TestCharFilters, DistributedQueryElevationComponentTest, BadIndexSchemaTest, ClusterStateTest, ConvertedLegacyTest, TestCSVResponseWriter, PrimUtilsTest, StatsComponentTest, FieldMutatingUpdateProcessorTest, TestSurroundQueryParser, SuggesterTSTTest, SampleTest, TestBadConfig, TestBinaryField, MultiTermTest, SystemInfoHandlerTest, UniqFieldsUpdateProcessorFactoryTest, TestCoreContainer, OverseerTest, TestLMJelinekMercerSimilarityFactory, SolrCoreCheckLockOnStartupTest, WordBreakSolrSpellCheckerTest, ReturnFieldsTest, AutoCommitTest, TestPropInjectDefaults, TestRandomFaceting, URLClassifyProcessorTest, TestCollationKeyRangeQueries, TestSort, JsonLoaderTest, JSONWriterTest, IndexSchemaTest, BasicZkTest, SuggesterFSTTest, TestFastLRUCache, BasicDistributedZk2Test, TestStressReorder, BasicDistributedZkTest, RecoveryZkTest, SpellCheckComponentTest, TestReplicationHandler, LeaderElectionIntegrationTest]
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=LeaderElectionIntegrationTest -Dtests.seed=4AC9E8D0F70D13C6 -Dtests.slow=true -Dtests.locale=mt -Dtests.timezone=America/Argentina/La_Rioja -Dtests.file.encoding=UTF-8
[junit4:junit4] ERROR   0.00s | LeaderElectionIntegrationTest (suite) <<<
[junit4:junit4]    > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.cloud.LeaderElectionIntegrationTest: 
[junit4:junit4]    >    1) Thread[id=2512, name=SessionTracker, state=TIMED_WAITING, group=TGRP-LeaderElectionIntegrationTest]
[junit4:junit4]    >         at java.lang.Object.wait(Native Method)
[junit4:junit4]    >         at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:145)
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([4AC9E8D0F70D13C6]: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 error occurred while executing this line:
C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build.xml:146: The following error occurred while executing this line:
C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\lucene\module-build.xml:63: The 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: There were test failures: 228 suites, 913 tests, 1 suite-level error, 12 ignored (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



Mime
View raw message