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.7.0_07) - Build # 871 - Failure!
Date Sat, 22 Sep 2012 04:24:42 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-4.x-Windows/871/
Java: 32bit/jdk1.7.0_07 -client -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=2887, 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=2887, 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([6FAB9BC5F7828228]:0)




Build Log:
[...truncated 7923 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.LeaderElectionIntegrationTest
[junit4:junit4]   2> 4 T2846 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-1348287378507
[junit4:junit4]   2> 5 T2846 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 9 T2847 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 9 T2847 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 11 T2847 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 104 T2846 oasc.ZkTestServer.run start zk server on port:65191
[junit4:junit4]   2> 104 T2846 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:65191 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@db1fc1
[junit4:junit4]   2> 105 T2846 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 105 T2852 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:65191
[junit4:junit4]   2> 106 T2852 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:65191, initiating session
[junit4:junit4]   2> 107 T2848 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:65194
[junit4:junit4]   2> 107 T2848 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:65194
[junit4:junit4]   2> 107 T2850 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 119 T2850 oazs.NIOServerCnxn.finishSessionInit Established session 0x139ec30b4540000 with negotiated timeout 10000 for client /127.0.0.1:65194
[junit4:junit4]   2> 119 T2852 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:65191, sessionid = 0x139ec30b4540000, negotiated timeout = 10000
[junit4:junit4]   2> 119 T2853 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@db1fc1 name:ZooKeeperConnection Watcher:127.0.0.1:65191 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 120 T2846 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 120 T2846 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 124 T2851 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139ec30b4540000
[junit4:junit4]   2> 125 T2846 oaz.ZooKeeper.close Session: 0x139ec30b4540000 closed
[junit4:junit4]   2> 125 T2853 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 125 T2846 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:65191/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@4fa43f
[junit4:junit4]   2> 125 T2848 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x139ec30b4540000, likely client has closed socket
[junit4:junit4]   2> 127 T2848 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:65194 which had sessionid 0x139ec30b4540000
[junit4:junit4]   2> 128 T2854 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:65191
[junit4:junit4]   2> 128 T2846 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 129 T2854 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:65191, initiating session
[junit4:junit4]   2> 129 T2848 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:65197
[junit4:junit4]   2> 130 T2848 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:65197
[junit4:junit4]   2> 131 T2850 oazs.NIOServerCnxn.finishSessionInit Established session 0x139ec30b4540001 with negotiated timeout 10000 for client /127.0.0.1:65197
[junit4:junit4]   2> 131 T2854 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:65191, sessionid = 0x139ec30b4540001, negotiated timeout = 10000
[junit4:junit4]   2> 132 T2855 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4fa43f name:ZooKeeperConnection Watcher:127.0.0.1:65191/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 132 T2846 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 132 T2846 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 137 T2846 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 141 T2846 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 144 T2846 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 147 T2846 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> 147 T2846 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 154 T2846 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> 155 T2846 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 168 T2846 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> 169 T2846 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 174 T2846 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> 175 T2846 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 179 T2846 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> 180 T2846 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 183 T2846 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> 183 T2846 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 187 T2846 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> 187 T2846 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 191 T2846 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> 191 T2846 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 195 T2846 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> 195 T2846 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 200 T2846 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> 201 T2846 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 204 T2851 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139ec30b4540001
[junit4:junit4]   2> 206 T2855 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 206 T2848 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x139ec30b4540001, likely client has closed socket
[junit4:junit4]   2> 206 T2848 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:65197 which had sessionid 0x139ec30b4540001
[junit4:junit4]   2> 206 T2846 oaz.ZooKeeper.close Session: 0x139ec30b4540001 closed
[junit4:junit4]   2> 207 T2846 oasc.LeaderElectionIntegrationTest.setUp ####SETUP_START testLeaderElectionAfterClientTimeout
[junit4:junit4]   2> 211 T2846 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 212 T2846 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> 212 T2846 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> 212 T2846 oasc.CoreContainer.<init> New CoreContainer 13590430
[junit4:junit4]   2> 213 T2846 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> 213 T2846 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> 263 T2846 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 287 T2846 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:65191/solr
[junit4:junit4]   2> 288 T2846 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:65191/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@73a14d
[junit4:junit4]   2> 290 T2856 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:65191
[junit4:junit4]   2> 290 T2846 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 292 T2856 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:65191, initiating session
[junit4:junit4]   2> 292 T2848 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:65200
[junit4:junit4]   2> 293 T2848 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:65200
[junit4:junit4]   2> 295 T2850 oazs.NIOServerCnxn.finishSessionInit Established session 0x139ec30b4540002 with negotiated timeout 3000 for client /127.0.0.1:65200
[junit4:junit4]   2> 295 T2856 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:65191, sessionid = 0x139ec30b4540002, negotiated timeout = 3000
[junit4:junit4]   2> 295 T2857 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@73a14d name:ZooKeeperConnection Watcher:127.0.0.1:65191/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 295 T2846 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 297 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 299 T2846 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 302 T2846 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:7000_solr
[junit4:junit4]   2> 302 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540002 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> 303 T2846 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:7000_solr
[junit4:junit4]   2> 307 T2846 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 307 T2846 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 307 T2846 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 307 T2846 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 308 T2846 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 308 T2846 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 308 T2846 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 308 T2846 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 308 T2846 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 309 T2846 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 318 T2846 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 324 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540002 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> 325 T2846 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 328 T2846 oasc.Overseer.start Overseer (id=88361361638424578-127.0.0.1:7000_solr-n_0000000000) starting
[junit4:junit4]   2> 329 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 330 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 332 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 334 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 335 T2859 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 336 T2846 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 340 T2846 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 342 T2858 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 345 T2846 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> 345 T2846 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 347 T2846 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 347 T2846 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 349 T2846 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> 350 T2846 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> 352 T2846 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> 420 T2846 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 515 T2846 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 518 T2846 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 522 T2846 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1382 T2846 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 1393 T2846 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 1397 T2846 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1411 T2846 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1417 T2846 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1421 T2846 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1424 T2846 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1425 T2846 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-1348287378507\data_7000\
[junit4:junit4]   2> 1425 T2846 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1882760
[junit4:junit4]   2> 1426 T2846 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-1348287378507\data_7000\index/
[junit4:junit4]   2> 1428 T2846 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1348287378507\data_7000\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 1428 T2846 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1348287378507\data_7000\index forceNew:false
[junit4:junit4]   2> 1431 T2846 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b55a60 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1d586b5),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1431 T2846 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 1433 T2846 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 1435 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 1435 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 1435 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 1436 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 1436 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 1436 T2846 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 1436 T2846 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 1438 T2846 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1438 T2846 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 1438 T2846 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 1438 T2846 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 1439 T2846 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 1439 T2846 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 1439 T2846 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 1440 T2846 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 1447 T2846 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1447 T2846 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1448 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1449 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1449 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1450 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1451 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1452 T2846 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1453 T2846 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 1453 T2846 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 1457 T2846 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1461 T2846 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 1463 T2846 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> 1466 T2846 oass.SolrIndexSearcher.<init> Opening Searcher@111c296 main
[junit4:junit4]   2> 1467 T2846 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 1467 T2846 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1467 T2846 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1468 T2846 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 1479 T2846 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 1531 T2860 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@111c296 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1534 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540002 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> 1538 T2846 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 1538 T2846 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:7000/solr shardId:shard1
[junit4:junit4]   2> 1539 T2846 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 1549 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540002 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> 1551 T2846 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 1552 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540002 type:create cxid:0x66 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1819 T2858 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1820 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540002 type:create cxid:0x6d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 1835 T2857 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2043 T2846 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 2043 T2846 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 2043 T2846 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:7000/solr/collection1/
[junit4:junit4]   2> 2043 T2846 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 2050 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540002 type:create cxid:0x8b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2329 T2858 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2336 T2857 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2351 T2846 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> 2351 T2846 oasc.ZkController.checkRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> 2355 T2846 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2361 T2846 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2362 T2846 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> 2362 T2846 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> 2363 T2846 oasc.CoreContainer.<init> New CoreContainer 9239075
[junit4:junit4]   2> 2364 T2846 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> 2365 T2846 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> 2453 T2846 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 2478 T2846 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:65191/solr
[junit4:junit4]   2> 2479 T2846 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:65191/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@1702c44
[junit4:junit4]   2> 2481 T2861 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:65191
[junit4:junit4]   2> 2481 T2846 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2485 T2848 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:65203
[junit4:junit4]   2> 2484 T2861 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:65191, initiating session
[junit4:junit4]   2> 2486 T2848 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:65203
[junit4:junit4]   2> 2488 T2850 oazs.NIOServerCnxn.finishSessionInit Established session 0x139ec30b4540003 with negotiated timeout 3000 for client /127.0.0.1:65203
[junit4:junit4]   2> 2488 T2861 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:65191, sessionid = 0x139ec30b4540003, negotiated timeout = 3000
[junit4:junit4]   2> 2489 T2862 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1702c44 name:ZooKeeperConnection Watcher:127.0.0.1:65191/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2490 T2846 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2490 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540003 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2492 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540003 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2494 T2846 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:7001_solr
[junit4:junit4]   2> 2495 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540003 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> 2497 T2846 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:7001_solr
[junit4:junit4]   2> 2509 T2857 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 2510 T2846 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2510 T2846 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2510 T2846 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2511 T2846 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2511 T2846 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2511 T2846 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2511 T2846 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2513 T2846 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2513 T2846 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2514 T2846 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2525 T2846 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2533 T2846 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> 2533 T2846 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 2534 T2846 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2534 T2846 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 2535 T2846 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> 2537 T2846 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> 2539 T2846 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> 2605 T2846 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 2710 T2846 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2712 T2846 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2718 T2846 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2855 T2858 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2872 T2857 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2872 T2862 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3674 T2846 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 3687 T2846 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 3692 T2846 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3715 T2846 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3720 T2846 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3725 T2846 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3728 T2846 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3729 T2846 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-1348287378507\data_7001\
[junit4:junit4]   2> 3729 T2846 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1882760
[junit4:junit4]   2> 3730 T2846 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-1348287378507\data_7001\index/
[junit4:junit4]   2> 3732 T2846 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1348287378507\data_7001\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 3733 T2846 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1348287378507\data_7001\index forceNew:false
[junit4:junit4]   2> 3735 T2846 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@d2139d lockFactory=org.apache.lucene.store.NativeFSLockFactory@19ca808),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3735 T2846 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 3737 T2846 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 3738 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 3738 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 3739 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 3739 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 3740 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 3740 T2846 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 3740 T2846 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3741 T2846 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3742 T2846 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 3742 T2846 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 3743 T2846 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 3743 T2846 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 3743 T2846 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 3743 T2846 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 3744 T2846 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 3745 T2846 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3747 T2846 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3747 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3748 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3748 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3749 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3749 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3749 T2846 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3750 T2846 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 3751 T2846 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 3754 T2846 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3759 T2846 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 3761 T2846 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> 3764 T2846 oass.SolrIndexSearcher.<init> Opening Searcher@bd7f44 main
[junit4:junit4]   2> 3765 T2846 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 3765 T2846 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3765 T2846 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3766 T2846 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 3779 T2846 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 3838 T2863 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@bd7f44 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 3851 T2846 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 3851 T2846 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:7001/solr shardId:shard1
[junit4:junit4]   2> 3855 T2846 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> 3855 T2846 oasc.ZkController.checkRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> 3860 T2846 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3863 T2846 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3863 T2846 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> 3863 T2846 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> 3864 T2846 oasc.CoreContainer.<init> New CoreContainer 19060680
[junit4:junit4]   2> 3864 T2846 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> 3864 T2846 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> 3916 T2846 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3943 T2846 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:65191/solr
[junit4:junit4]   2> 3944 T2846 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:65191/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@116751d
[junit4:junit4]   2> 3947 T2864 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:65191
[junit4:junit4]   2> 3947 T2846 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3949 T2864 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:65191, initiating session
[junit4:junit4]   2> 3949 T2848 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:65206
[junit4:junit4]   2> 3949 T2848 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:65206
[junit4:junit4]   2> 3951 T2850 oazs.NIOServerCnxn.finishSessionInit Established session 0x139ec30b4540004 with negotiated timeout 3000 for client /127.0.0.1:65206
[junit4:junit4]   2> 3951 T2864 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:65191, sessionid = 0x139ec30b4540004, negotiated timeout = 3000
[junit4:junit4]   2> 3952 T2865 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@116751d name:ZooKeeperConnection Watcher:127.0.0.1:65191/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3952 T2846 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3953 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3954 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3956 T2846 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:7002_solr
[junit4:junit4]   2> 3957 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540004 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> 3958 T2846 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:7002_solr
[junit4:junit4]   2> 3961 T2862 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 3961 T2857 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 3962 T2858 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3962 T2846 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 3963 T2846 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 3964 T2846 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 3964 T2846 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 3964 T2846 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 3965 T2846 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 3965 T2846 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 3965 T2846 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 3965 T2846 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 3966 T2846 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 3975 T2862 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3980 T2846 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 3983 T2857 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3986 T2846 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> 3987 T2846 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 3990 T2846 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 3991 T2846 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 3993 T2846 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> 3995 T2846 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> 3996 T2846 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> 4073 T2846 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 4185 T2846 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 4187 T2846 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4194 T2846 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 5117 T2846 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 5133 T2846 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 5140 T2846 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 5156 T2846 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5162 T2846 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5168 T2846 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5170 T2846 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5171 T2846 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-1348287378507\data_7002\
[junit4:junit4]   2> 5172 T2846 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1882760
[junit4:junit4]   2> 5174 T2846 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-1348287378507\data_7002\index/
[junit4:junit4]   2> 5175 T2846 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1348287378507\data_7002\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 5175 T2846 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1348287378507\data_7002\index forceNew:false
[junit4:junit4]   2> 5178 T2846 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6cc560 lockFactory=org.apache.lucene.store.NativeFSLockFactory@f15c6e),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5178 T2846 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 5180 T2846 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 5183 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 5183 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 5184 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 5184 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 5185 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 5185 T2846 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 5185 T2846 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 5186 T2846 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 5187 T2846 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 5188 T2846 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 5188 T2846 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 5189 T2846 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 5189 T2846 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 5189 T2846 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 5190 T2846 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 5190 T2846 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 5191 T2846 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5192 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5193 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5193 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5193 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5194 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5194 T2846 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5195 T2846 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 5196 T2846 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 5199 T2846 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 5213 T2846 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 5215 T2846 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> 5219 T2846 oass.SolrIndexSearcher.<init> Opening Searcher@1ddaaa5 main
[junit4:junit4]   2> 5219 T2846 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 5220 T2846 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 5220 T2846 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 5221 T2846 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 5235 T2846 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 5291 T2866 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ddaaa5 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 5298 T2846 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 5299 T2846 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:7002/solr shardId:shard1
[junit4:junit4]   2> 5303 T2846 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> 5303 T2846 oasc.ZkController.checkRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> 5305 T2846 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5309 T2846 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5309 T2846 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> 5309 T2846 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> 5310 T2846 oasc.CoreContainer.<init> New CoreContainer 21169832
[junit4:junit4]   2> 5311 T2846 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> 5311 T2846 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> 5373 T2846 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 5393 T2846 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:65191/solr
[junit4:junit4]   2> 5394 T2846 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:65191/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@b55844
[junit4:junit4]   2> 5395 T2867 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:65191
[junit4:junit4]   2> 5395 T2846 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 5398 T2867 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:65191, initiating session
[junit4:junit4]   2> 5398 T2848 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:65209
[junit4:junit4]   2> 5398 T2848 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:65209
[junit4:junit4]   2> 5400 T2850 oazs.NIOServerCnxn.finishSessionInit Established session 0x139ec30b4540005 with negotiated timeout 3000 for client /127.0.0.1:65209
[junit4:junit4]   2> 5400 T2867 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:65191, sessionid = 0x139ec30b4540005, negotiated timeout = 3000
[junit4:junit4]   2> 5401 T2868 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@b55844 name:ZooKeeperConnection Watcher:127.0.0.1:65191/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 5401 T2846 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 5402 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540005 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5405 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540005 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5407 T2846 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:7003_solr
[junit4:junit4]   2> 5407 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540005 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> 5409 T2846 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:7003_solr
[junit4:junit4]   2> 5412 T2862 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 5412 T2857 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 5413 T2865 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 5415 T2846 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 5415 T2846 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 5415 T2846 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 5415 T2846 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 5416 T2846 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 5416 T2846 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 5416 T2846 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 5416 T2846 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 5416 T2846 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 5416 T2846 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 5430 T2846 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 5447 T2846 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> 5449 T2846 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 5450 T2846 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 5450 T2846 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 5453 T2846 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> 5455 T2846 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> 5457 T2846 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> 5533 T2846 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 5597 T2858 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5612 T2868 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5612 T2865 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5612 T2857 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5612 T2862 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5644 T2846 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 5646 T2846 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 5652 T2846 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 6626 T2846 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 6638 T2846 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 6644 T2846 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 6661 T2846 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 6676 T2846 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 6682 T2846 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 6684 T2846 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 6687 T2846 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-1348287378507\data_7003\
[junit4:junit4]   2> 6687 T2846 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1882760
[junit4:junit4]   2> 6688 T2846 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-1348287378507\data_7003\index/
[junit4:junit4]   2> 6690 T2846 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1348287378507\data_7003\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 6690 T2846 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1348287378507\data_7003\index forceNew:false
[junit4:junit4]   2> 6693 T2846 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=BaseDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@14fbfc3 lockFactory=org.apache.lucene.store.NativeFSLockFactory@18b97c7; maxCacheMB=0.4638671875 maxMergeSizeMB=0.4951171875)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 6693 T2846 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 6695 T2846 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 6697 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 6697 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 6698 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 6698 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 6699 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 6700 T2846 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 6700 T2846 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 6701 T2846 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 6702 T2846 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 6702 T2846 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 6703 T2846 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 6703 T2846 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 6703 T2846 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 6703 T2846 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 6705 T2846 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 6705 T2846 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 6706 T2846 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6706 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6706 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6708 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6709 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6709 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6710 T2846 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6710 T2846 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 6711 T2846 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 6715 T2846 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 6723 T2846 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 6724 T2846 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> 6726 T2846 oass.SolrIndexSearcher.<init> Opening Searcher@1126b38 main
[junit4:junit4]   2> 6727 T2846 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.BaseDirectoryWrapper
[junit4:junit4]   2> 6727 T2846 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 6727 T2846 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 6728 T2846 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 6749 T2846 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 6819 T2869 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1126b38 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 6825 T2846 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 6825 T2846 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:7003/solr shardId:shard1
[junit4:junit4]   2> 6830 T2846 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> 6830 T2846 oasc.ZkController.checkRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> 6833 T2846 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6837 T2846 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 6837 T2846 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> 6838 T2846 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> 6838 T2846 oasc.CoreContainer.<init> New CoreContainer 16288766
[junit4:junit4]   2> 6839 T2846 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> 6839 T2846 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> 6902 T2846 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 6932 T2846 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:65191/solr
[junit4:junit4]   2> 6932 T2846 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:65191/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@a8894d
[junit4:junit4]   2> 6934 T2870 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:65191
[junit4:junit4]   2> 6934 T2846 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 6936 T2870 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:65191, initiating session
[junit4:junit4]   2> 6936 T2848 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:65212
[junit4:junit4]   2> 6938 T2848 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:65212
[junit4:junit4]   2> 6940 T2850 oazs.NIOServerCnxn.finishSessionInit Established session 0x139ec30b4540006 with negotiated timeout 3000 for client /127.0.0.1:65212
[junit4:junit4]   2> 6940 T2870 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:65191, sessionid = 0x139ec30b4540006, negotiated timeout = 3000
[junit4:junit4]   2> 6941 T2871 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@a8894d name:ZooKeeperConnection Watcher:127.0.0.1:65191/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 6941 T2846 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 6942 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540006 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6943 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540006 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6945 T2846 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:7004_solr
[junit4:junit4]   2> 6946 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540006 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> 6947 T2846 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:7004_solr
[junit4:junit4]   2> 6951 T2868 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 6952 T2862 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 6952 T2865 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 6951 T2857 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 6954 T2846 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 6954 T2846 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 6954 T2846 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 6955 T2846 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 6955 T2846 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 6956 T2846 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 6956 T2846 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 6956 T2846 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 6956 T2846 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 6956 T2846 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 6971 T2846 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 6980 T2846 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> 6980 T2846 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 6981 T2846 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 6981 T2846 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 6983 T2846 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> 6986 T2846 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> 6988 T2846 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> 7071 T2846 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 7179 T2846 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 7181 T2846 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 7186 T2846 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 7245 T2858 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7279 T2871 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7279 T2868 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7279 T2857 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7279 T2862 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7279 T2865 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8225 T2846 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 8247 T2846 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 8252 T2846 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 8268 T2846 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 8274 T2846 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 8280 T2846 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 8282 T2846 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 8284 T2846 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-1348287378507\data_7004\
[junit4:junit4]   2> 8284 T2846 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1882760
[junit4:junit4]   2> 8286 T2846 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-1348287378507\data_7004\index/
[junit4:junit4]   2> 8287 T2846 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1348287378507\data_7004\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 8287 T2846 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1348287378507\data_7004\index forceNew:false
[junit4:junit4]   2> 8290 T2846 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@bd7310 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8489ba),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 8290 T2846 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 8291 T2846 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 8293 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 8293 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 8294 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 8294 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 8295 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 8295 T2846 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 8296 T2846 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 8297 T2846 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 8299 T2846 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 8299 T2846 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 8300 T2846 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 8300 T2846 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 8301 T2846 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 8301 T2846 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 8301 T2846 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 8302 T2846 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 8302 T2846 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8303 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8304 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8304 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8305 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8305 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8306 T2846 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8306 T2846 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 8307 T2846 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 8311 T2846 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 8316 T2846 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 8318 T2846 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> 8323 T2846 oass.SolrIndexSearcher.<init> Opening Searcher@1ea10af main
[junit4:junit4]   2> 8323 T2846 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 8324 T2846 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 8324 T2846 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 8332 T2846 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 8382 T2846 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 8559 T2872 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ea10af main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 8566 T2846 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 8567 T2846 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:7004/solr shardId:shard1
[junit4:junit4]   2> 8571 T2846 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> 8571 T2846 oasc.ZkController.checkRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> 8575 T2846 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8579 T2846 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 8580 T2846 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> 8580 T2846 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> 8580 T2846 oasc.CoreContainer.<init> New CoreContainer 23344259
[junit4:junit4]   2> 8581 T2846 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> 8581 T2846 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> 8637 T2846 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 8668 T2846 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:65191/solr
[junit4:junit4]   2> 8669 T2846 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:65191/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@7d7144
[junit4:junit4]   2> 8670 T2873 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:65191
[junit4:junit4]   2> 8670 T2846 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 8672 T2873 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:65191, initiating session
[junit4:junit4]   2> 8672 T2848 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:65215
[junit4:junit4]   2> 8673 T2848 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:65215
[junit4:junit4]   2> 8676 T2850 oazs.NIOServerCnxn.finishSessionInit Established session 0x139ec30b4540007 with negotiated timeout 3000 for client /127.0.0.1:65215
[junit4:junit4]   2> 8676 T2873 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:65191, sessionid = 0x139ec30b4540007, negotiated timeout = 3000
[junit4:junit4]   2> 8677 T2874 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7d7144 name:ZooKeeperConnection Watcher:127.0.0.1:65191/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 8678 T2846 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 8678 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540007 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8680 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540007 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8682 T2846 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:3333_solr
[junit4:junit4]   2> 8683 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540007 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> 8685 T2846 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:3333_solr
[junit4:junit4]   2> 8688 T2862 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 8688 T2868 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 8688 T2865 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 8688 T2857 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 8688 T2871 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 8691 T2846 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 8691 T2846 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 8691 T2846 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 8693 T2846 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 8693 T2846 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 8693 T2846 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 8693 T2846 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 8693 T2846 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 8694 T2846 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 8694 T2846 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 8710 T2846 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 8729 T2846 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> 8730 T2846 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 8731 T2846 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 8731 T2846 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 8733 T2846 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> 8735 T2846 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> 8737 T2846 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> 8807 T2846 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 8915 T2858 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8926 T2846 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 8928 T2846 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 8936 T2846 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 8942 T2862 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8942 T2868 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8943 T2874 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8943 T2871 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8944 T2857 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8944 T2865 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9990 T2846 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 10002 T2846 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 10007 T2846 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 10026 T2846 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 10032 T2846 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 10047 T2846 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 10048 T2846 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 10051 T2846 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-1348287378507\data_3333\
[junit4:junit4]   2> 10051 T2846 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1882760
[junit4:junit4]   2> 10052 T2846 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-1348287378507\data_3333\index/
[junit4:junit4]   2> 10053 T2846 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1348287378507\data_3333\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 10054 T2846 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1348287378507\data_3333\index forceNew:false
[junit4:junit4]   2> 10057 T2846 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@971274 lockFactory=org.apache.lucene.store.NativeFSLockFactory@171b95a),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 10057 T2846 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 10058 T2846 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 10060 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 10060 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 10060 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 10061 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 10062 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 10063 T2846 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 10063 T2846 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 10063 T2846 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 10064 T2846 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 10065 T2846 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 10065 T2846 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 10067 T2846 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 10067 T2846 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 10067 T2846 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 10067 T2846 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 10068 T2846 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 10069 T2846 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10069 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10070 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10071 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10071 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10071 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10072 T2846 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10073 T2846 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 10073 T2846 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 10077 T2846 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 10082 T2846 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 10084 T2846 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> 10090 T2846 oass.SolrIndexSearcher.<init> Opening Searcher@18bebec main
[junit4:junit4]   2> 10091 T2846 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 10092 T2846 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 10092 T2846 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 10093 T2846 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 10109 T2846 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 10190 T2875 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@18bebec main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 10196 T2846 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 10196 T2846 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:3333/solr shardId:shard2
[junit4:junit4]   2> 10198 T2846 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4:junit4]   2> 10207 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540007 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> 10209 T2846 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 10209 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540007 type:create cxid:0x42 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 10586 T2858 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10600 T2871 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10601 T2857 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10601 T2862 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10601 T2865 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10602 T2874 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10601 T2868 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10748 T2846 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 10749 T2846 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 10749 T2846 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:3333/solr/collection1/
[junit4:junit4]   2> 10749 T2846 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4:junit4]   2> 10756 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540007 type:create cxid:0x4c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 11147 T2858 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11154 T2868 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 11155 T2874 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 11155 T2862 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 11155 T2865 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 11155 T2857 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 11155 T2871 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 11205 T2846 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> 11206 T2846 oasc.ZkController.checkRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> 11210 T2846 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11211 T2846 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:65191/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@19ce34a
[junit4:junit4]   2> 11212 T2876 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:65191
[junit4:junit4]   2> 11212 T2846 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 11214 T2876 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:65191, initiating session
[junit4:junit4]   2> 11216 T2848 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:65218
[junit4:junit4]   2> 11216 T2848 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:65218
[junit4:junit4]   2> 11219 T2850 oazs.NIOServerCnxn.finishSessionInit Established session 0x139ec30b4540008 with negotiated timeout 10000 for client /127.0.0.1:65218
[junit4:junit4]   2> 11219 T2876 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:65191, sessionid = 0x139ec30b4540008, negotiated timeout = 10000
[junit4:junit4]   2> 11220 T2877 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@19ce34a name:ZooKeeperConnection Watcher:127.0.0.1:65191/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 11220 T2846 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 11221 T2846 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 11224 T2846 oasc.LeaderElectionIntegrationTest.setUp ####SETUP_END testLeaderElectionAfterClientTimeout
[junit4:junit4]   2> 11225 T2848 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x139ec30b4540002, likely client has closed socket
[junit4:junit4]   2> 11226 T2848 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:65200 which had sessionid 0x139ec30b4540002
[junit4:junit4]   2> 11226 T2856 oaz.ClientCnxn$SendThread.run WARNING Session 0x139ec30b4540002 for server null, unexpected error, closing socket connection and attempting reconnect java.nio.channels.CancelledKeyException
[junit4:junit4]   2> 	at sun.nio.ch.SelectionKeyImpl.ensureValid(SelectionKeyImpl.java:73)
[junit4:junit4]   2> 	at sun.nio.ch.SelectionKeyImpl.interestOps(SelectionKeyImpl.java:77)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$SendThread.disableWrite(ClientCnxn.java:919)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1163)
[junit4:junit4]   2> 
[junit4:junit4]   2> 11335 T2857 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@73a14d name:ZooKeeperConnection Watcher:127.0.0.1:65191/solr got event WatchedEvent state:Disconnected type:None path:null path:null type:None
[junit4:junit4]   2> 11335 T2857 oascc.ConnectionManager.process zkClient has disconnected
[junit4:junit4]   2> 13459 T2856 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server 127.0.0.1/127.0.0.1:65191
[junit4:junit4]   2> 13459 T2856 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:65191, initiating session
[junit4:junit4]   2> 13460 T2848 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:65219
[junit4:junit4]   2> 14661 T2849 oazs.ZooKeeperServer.expire Expiring session 0x139ec30b4540002, timeout of 3000ms exceeded
[junit4:junit4]   2> 14661 T2851 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139ec30b4540002
[junit4:junit4]   2> 14665 T2871 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 14665 T2865 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 14665 T2877 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 14666 T2868 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 14666 T2874 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 14667 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540003 type:delete cxid:0x49 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> 14670 T2862 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 14671 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540003 type:create cxid:0x4a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 14676 T2848 oazs.NIOServerCnxn.readConnectRequest Client attempting to renew session 0x139ec30b4540002 at /127.0.0.1:65219
[junit4:junit4]   2> 14676 T2848 oazs.NIOServerCnxn.finishSessionInit Invalid session 0x139ec30b4540002 for client /127.0.0.1:65219, probably expired
[junit4:junit4]   2> 14677 T2856 oaz.ClientCnxn$SendThread.run Unable to reconnect to ZooKeeper service, session 0x139ec30b4540002 has expired, closing socket connection
[junit4:junit4]   2> 14677 T2857 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@73a14d name:ZooKeeperConnection Watcher:127.0.0.1:65191/solr got event WatchedEvent state:Expired type:None path:null path:null type:None
[junit4:junit4]   2> 14679 T2862 oasc.ShardLeaderElectionContext.shouldIBeLeader Checking if I should try and be the leader.
[junit4:junit4]   2> 14679 T2857 oascc.ConnectionManager.process Our previous ZooKeeper session was expired. Attempting to reconnect to recover relationship with ZooKeeper...
[junit4:junit4]   2> 14677 T2848 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:65219 which had sessionid 0x139ec30b4540002
[junit4:junit4]   2> 14680 T2862 oasc.ShardLeaderElectionContext.shouldIBeLeader My last published State was Active, it's okay to be the leader.
[junit4:junit4]   2> 14680 T2862 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 14680 T2857 oascc.DefaultConnectionStrategy.reconnect Connection expired - starting a new one...
[junit4:junit4]   2> 14680 T2862 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:7001/solr/collection1/
[junit4:junit4]   2> 14681 T2857 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:65191/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@73a14d
[junit4:junit4]   2> 14681 T2862 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 14682 T2857 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 14684 T2879 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:65191
[junit4:junit4]   2> 14685 T2879 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:65191, initiating session
[junit4:junit4]   2> 14686 T2848 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:65222
[junit4:junit4]   2> 14687 T2848 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:65222
[junit4:junit4]   2> 14689 T2850 oazs.NIOServerCnxn.finishSessionInit Established session 0x139ec30b4540009 with negotiated timeout 3000 for client /127.0.0.1:65222
[junit4:junit4]   2> 14689 T2879 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:65191, sessionid = 0x139ec30b4540009, negotiated timeout = 3000
[junit4:junit4]   2> 14691 T2880 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@73a14d name:ZooKeeperConnection Watcher:127.0.0.1:65191/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 14691 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540003 type:create cxid:0x52 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 14692 T2857 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 14692 T2857 oascc.ConnectionManager$1.update Connection with ZooKeeper reestablished.
[junit4:junit4]   2> 14697 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540003 type:delete cxid:0x55 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 14700 T2862 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 14703 T2857 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 14703 T2862 oasc.Overseer.start Overseer (id=88361361638424579-127.0.0.1:7001_solr-n_0000000001) starting
[junit4:junit4]   2> 14704 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540003 type:create cxid:0x5a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 14707 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540003 type:create cxid:0x5b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 14709 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540003 type:create cxid:0x5c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 14709 T2857 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:7000_solr
[junit4:junit4]   2> 14710 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540009 type:delete cxid:0xa 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> 14710 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540003 type:create cxid:0x5d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 14711 T2857 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:7000_solr
[junit4:junit4]   2> 14714 T2862 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 14714 T2882 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 14717 T2871 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 14717 T2865 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 14717 T2877 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 14718 T2862 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 14718 T2868 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 14719 T2874 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 14719 T2880 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 14720 T2881 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 14722 T2857 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:7000/solr shardId:shard1
[junit4:junit4]   2> 14725 T2881 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 14750 T2871 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 14750 T2865 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 14750 T2862 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 14752 T2877 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 14752 T2874 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 14752 T2880 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 14752 T2868 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 14795 T2858 oasc.Overseer$ClusterStateUpdater.amILeader WARNING  org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[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.getData(ZooKeeper.java:927)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:291)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:288)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:67)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:288)
[junit4:junit4]   2> 	at org.apache.solr.cloud.Overseer$ClusterStateUpdater.amILeader(Overseer.java:195)
[junit4:junit4]   2> 	at org.apache.solr.cloud.Overseer$ClusterStateUpdater.run(Overseer.java:119)
[junit4:junit4]   2> 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 
[junit4:junit4]   2> 14795 T2859 oasc.OverseerCollectionProcessor.run WARNING Overseer cannot talk to ZK
[junit4:junit4]   2> 14795 T2858 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=88361361638424578-127.0.0.1:7000_solr-n_0000000000) am no longer a leader.
[junit4:junit4]   2> 15906 T2857 oasc.ZkController.register We are http://127.0.0.1:7000/solr/collection1/ and leader is http://127.0.0.1:7001/solr/collection1/
[junit4:junit4]   2> 15906 T2857 oasc.ZkController.checkRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> 15912 T2857 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 15913 T2857 oascc.DefaultConnectionStrategy.reconnect Reconnected to ZooKeeper
[junit4:junit4]   2> 15913 T2857 oascc.ConnectionManager.process Connected:true
[junit4:junit4]   2> 15913 T2857 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 15949 T2881 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 15958 T2862 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 15959 T2871 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 15959 T2865 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 15959 T2877 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 15959 T2868 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 15960 T2880 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 15960 T2874 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 17292 T2846 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=19060680
[junit4:junit4]   2> 17292 T2846 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1bced04
[junit4:junit4]   2> 17298 T2846 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 17298 T2846 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> 17299 T2846 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 17299 T2846 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 17300 T2851 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139ec30b4540004
[junit4:junit4]   2> 17304 T2871 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 17305 T2865 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 17305 T2877 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 17305 T2848 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:65206 which had sessionid 0x139ec30b4540004
[junit4:junit4]   2> 17305 T2862 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 17306 T2880 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 17306 T2874 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 17306 T2846 oaz.ZooKeeper.close Session: 0x139ec30b4540004 closed
[junit4:junit4]   2> 17307 T2846 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=21169832
[junit4:junit4]   2> 17307 T2846 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@a3da48
[junit4:junit4]   2> 17308 T2868 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 17323 T2846 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 17323 T2846 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> 17323 T2846 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 17324 T2846 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 17325 T2851 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139ec30b4540005
[junit4:junit4]   2> 17328 T2877 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 17328 T2868 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 17328 T2848 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:65209 which had sessionid 0x139ec30b4540005
[junit4:junit4]   2> 17329 T2862 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 17329 T2874 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 17329 T2846 oaz.ZooKeeper.close Session: 0x139ec30b4540005 closed
[junit4:junit4]   2> 17329 T2846 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=9239075
[junit4:junit4]   2> 17329 T2880 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 17329 T2846 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@2d4fcc
[junit4:junit4]   2> 17330 T2871 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 17336 T2846 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 17336 T2846 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> 17336 T2846 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 17336 T2846 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 17338 T2881 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=88361361638424579-127.0.0.1:7001_solr-n_0000000001) am no longer a leader.
[junit4:junit4]   2> 17338 T2851 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139ec30b4540003
[junit4:junit4]   2> 17340 T2871 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 17342 T2877 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 17342 T2874 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 17342 T2848 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:65203 which had sessionid 0x139ec30b4540003
[junit4:junit4]   2> 17343 T2862 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 17343 T2880 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 17343 T2846 oaz.ZooKeeper.close Session: 0x139ec30b4540003 closed
[junit4:junit4]   2> 17344 T2846 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=16288766
[junit4:junit4]   2> 17344 T2846 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@15f4161
[junit4:junit4]   2> 17344 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540006 type:delete cxid:0x4e 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> 17346 T2871 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 17347 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540006 type:create cxid:0x4f zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 17350 T2846 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 17350 T2846 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> 17350 T2846 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 17351 T2846 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 17352 T2851 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139ec30b4540006
[junit4:junit4]   2> 17354 T2871 oasc.LeaderElector$1.process WARNING  org.apache.solr.common.SolrException: Fatal Error, SolrCore not found:collection1 in []
[junit4:junit4]   2> 	at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:152)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:158)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:102)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:56)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:131)
[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> 
[junit4:junit4]   2> 17355 T2877 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 17355 T2848 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:65212 which had sessionid 0x139ec30b4540006
[junit4:junit4]   2> 17356 T2846 oaz.ZooKeeper.close Session: 0x139ec30b4540006 closed
[junit4:junit4]   2> 17356 T2846 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=23344259
[junit4:junit4]   2> 17358 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540009 type:delete cxid:0x1f 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> 17358 T2846 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1954b9c
[junit4:junit4]   2> 17358 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540007 type:delete cxid:0x5b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 17366 T2880 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 17367 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540009 type:create cxid:0x20 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 17369 T2874 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 17370 T2846 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 17371 T2846 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> 17371 T2846 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 17373 T2846 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 17373 T2880 oasc.ShardLeaderElectionContext.shouldIBeLeader Checking if I should try and be the leader.
[junit4:junit4]   2> 17374 T2880 oasc.ShardLeaderElectionContext.shouldIBeLeader My last published State was Active, it's okay to be the leader.
[junit4:junit4]   2> 17374 T2880 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 17374 T2880 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:7000/solr/collection1/
[junit4:junit4]   2> 17374 T2880 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 17375 T2851 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139ec30b4540007
[junit4:junit4]   2> 17376 T2877 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 17377 T2848 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:65215 which had sessionid 0x139ec30b4540007
[junit4:junit4]   2> 17377 T2846 oaz.ZooKeeper.close Session: 0x139ec30b4540007 closed
[junit4:junit4]   2> 17382 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540009 type:create cxid:0x28 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 17385 T2880 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 17388 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540009 type:delete cxid:0x2c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 17389 T2880 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 17392 T2880 oasc.Overseer.start Overseer (id=88361361638424585-127.0.0.1:7000_solr-n_0000000007) starting
[junit4:junit4]   2> 17392 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540009 type:create cxid:0x31 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 17395 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540009 type:create cxid:0x32 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 17397 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540009 type:create cxid:0x33 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 17399 T2851 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec30b4540009 type:create cxid:0x34 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 17401 T2884 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 17401 T2883 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 17405 T2883 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 17423 T2880 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 17423 T2877 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 17496 T2851 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139ec30b4540008
[junit4:junit4]   2> 17499 T2877 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 17499 T2846 oaz.ZooKeeper.close Session: 0x139ec30b4540008 closed
[junit4:junit4]   2> 17499 T2848 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:65218 which had sessionid 0x139ec30b4540008
[junit4:junit4]   2> 17499 T2846 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=13590430
[junit4:junit4]   2> 17501 T2846 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@ba3fd7
[junit4:junit4]   2> 17502 T2846 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 17502 T2846 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> 17502 T2846 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 17503 T2846 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 17504 T2883 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=88361361638424585-127.0.0.1:7000_solr-n_0000000007) am no longer a leader.
[junit4:junit4]   2> 17504 T2851 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139ec30b4540009
[junit4:junit4]   2> 17506 T2880 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 17506 T2846 oaz.ZooKeeper.close Session: 0x139ec30b4540009 closed
[junit4:junit4]   2> 17507 T2880 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 17507 T2848 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x139ec30b4540009, likely client has closed socket
[junit4:junit4]   2> 17507 T2850 oazs.SyncRequestProcessor.run SyncRequestProcessor exited!
[junit4:junit4]   2> 17507 T2848 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:65222 which had sessionid 0x139ec30b4540009
[junit4:junit4]   2> 17507 T2851 oazs.PrepRequestProcessor.run PrepRequestProcessor exited loop!
[junit4:junit4]   2> 17507 T2880 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 17509 T2846 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
[junit4:junit4]   2> 17650 T2849 oazs.SessionTrackerImpl.run SessionTrackerImpl exited loop!
[junit4:junit4]   2> 18555 T2874 oasc.LeaderElector$1.process WARNING  org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[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$10.execute(SolrZkClient.java:488)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:67)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:485)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:433)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:414)
[junit4:junit4]   2> 	at org.apache.solr.cloud.OverseerElectionContext.runLeaderProcess(ElectionContext.java:369)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:158)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:102)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:56)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:131)
[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> 
[junit4:junit4]   2> 20168 T2848 oazs.NIOServerCnxn$Factory.run NIOServerCnxn factory exited run method
[junit4:junit4]   2> 18534 T2871 oasc.LeaderElector$1.process WARNING  org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/election
[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.getChildren(ZooKeeper.java:1249)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:268)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:265)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:67)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:265)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:90)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:56)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:131)
[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> 
[junit4:junit4]   2> 18522 T2862 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 18509 T2868 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 18484 T2865 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 20169 T2862 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 20169 T2871 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 20169 T2871 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 20169 T2871 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 20168 T2874 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 20171 T2874 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 20169 T2865 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 20172 T2846 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
[junit4:junit4]   2> 20169 T2868 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 20171 T2874 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 20172 T2846 oas.SolrTestCaseJ4.tearDown ###Ending testLeaderElectionAfterClientTimeout
[junit4:junit4]   2> 20178 T2846 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-1348287398681
[junit4:junit4]   2> 20179 T2846 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 20184 T2885 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 20185 T2885 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 20187 T2885 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 20299 T2846 oasc.ZkTestServer.run start zk server on port:65225
[junit4:junit4]   2> 20299 T2846 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:65225 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@14c584
[junit4:junit4]   2> 20301 T2890 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:65225
[junit4:junit4]   2> 20301 T2846 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 20302 T2890 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:65225, initiating session
[junit4:junit4]   2> 20302 T2886 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:65228
[junit4:junit4]   2> 20304 T2886 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:65228
[junit4:junit4]   2> 20304 T2888 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 20335 T2888 oazs.NIOServerCnxn.finishSessionInit Established session 0x139ec3103230000 with negotiated timeout 10000 for client /127.0.0.1:65228
[junit4:junit4]   2> 20335 T2890 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:65225, sessionid = 0x139ec3103230000, negotiated timeout = 10000
[junit4:junit4]   2> 20335 T2891 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@14c584 name:ZooKeeperConnection Watcher:127.0.0.1:65225 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 20336 T2846 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 20336 T2846 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 20362 T2889 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139ec3103230000
[junit4:junit4]   2> 20384 T2846 oaz.ZooKeeper.close Session: 0x139ec3103230000 closed
[junit4:junit4]   2> 20385 T2891 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 20387 T2886 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:65228 which had sessionid 0x139ec3103230000
[junit4:junit4]   2> 20387 T2846 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:65225/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@a0d6aa
[junit4:junit4]   2> 20388 T2892 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:65225
[junit4:junit4]   2> 20388 T2846 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 20389 T2892 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:65225, initiating session
[junit4:junit4]   2> 20390 T2886 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:65231
[junit4:junit4]   2> 20390 T2886 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:65231
[junit4:junit4]   2> 20407 T2888 oazs.NIOServerCnxn.finishSessionInit Established session 0x139ec3103230001 with negotiated timeout 10000 for client /127.0.0.1:65231
[junit4:junit4]   2> 20407 T2892 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:65225, sessionid = 0x139ec3103230001, negotiated timeout = 10000
[junit4:junit4]   2> 20408 T2893 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@a0d6aa name:ZooKeeperConnection Watcher:127.0.0.1:65225/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 20408 T2846 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 20410 T2846 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 20467 T2846 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 20503 T2846 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 20539 T2846 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 20573 T2846 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> 20574 T2846 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 20655 T2846 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> 20657 T2846 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 20668 T2846 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> 20668 T2846 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 20679 T2846 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> 20680 T2846 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 20702 T2846 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> 20703 T2846 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 20726 T2846 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> 20728 T2846 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 20749 T2846 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> 20749 T2846 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 20772 T2846 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> 20772 T2846 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 20783 T2846 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> 20784 T2846 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 20807 T2846 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> 20807 T2846 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 20819 T2889 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139ec3103230001
[junit4:junit4]   2> 20852 T2846 oaz.ZooKeeper.close Session: 0x139ec3103230001 closed
[junit4:junit4]   2> 20853 T2893 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 20853 T2886 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x139ec3103230001, likely client has closed socket
[junit4:junit4]   2> 20853 T2846 oasc.LeaderElectionIntegrationTest.setUp ####SETUP_START testSimpleSliceLeaderElection
[junit4:junit4]   2> 20853 T2886 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:65231 which had sessionid 0x139ec3103230001
[junit4:junit4]   2> 20857 T2846 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 20857 T2846 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> 20857 T2846 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> 20858 T2846 oasc.CoreContainer.<init> New CoreContainer 15545026
[junit4:junit4]   2> 20859 T2846 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> 20859 T2846 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> 20928 T2846 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 20959 T2846 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:65225/solr
[junit4:junit4]   2> 20959 T2846 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:65225/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@10e6a41
[junit4:junit4]   2> 20961 T2846 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 20961 T2894 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:65225
[junit4:junit4]   2> 20965 T2894 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:65225, initiating session
[junit4:junit4]   2> 20965 T2886 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:65234
[junit4:junit4]   2> 20965 T2886 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:65234
[junit4:junit4]   2> 20975 T2888 oazs.NIOServerCnxn.finishSessionInit Established session 0x139ec3103230002 with negotiated timeout 3000 for client /127.0.0.1:65234
[junit4:junit4]   2> 20975 T2894 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:65225, sessionid = 0x139ec3103230002, negotiated timeout = 3000
[junit4:junit4]   2> 20975 T2895 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@10e6a41 name:ZooKeeperConnection Watcher:127.0.0.1:65225/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 20976 T2846 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 21011 T2889 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec3103230002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 21034 T2846 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 21057 T2846 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:7000_solr
[junit4:junit4]   2> 21058 T2889 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec3103230002 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> 21080 T2846 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:7000_solr
[junit4:junit4]   2> 21104 T2846 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 21105 T2846 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 21105 T2846 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 21105 T2846 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 21107 T2846 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 21107 T2846 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 21107 T2846 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 21108 T2846 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 21108 T2846 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 21108 T2846 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 21118 T2846 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 21162 T2889 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec3103230002 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> 21184 T2846 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 21195 T2846 oasc.Overseer.start Overseer (id=88361362960613378-127.0.0.1:7000_solr-n_0000000000) starting
[junit4:junit4]   2> 21196 T2889 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec3103230002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 21219 T2889 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec3103230002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 21255 T2889 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec3103230002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 21278 T2889 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec3103230002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 21302 T2897 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 21302 T2846 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 21350 T2846 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 21353 T2896 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 21358 T2846 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> 21359 T2846 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 21360 T2846 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 21360 T2846 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 21361 T2846 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> 21365 T2846 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> 21366 T2846 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> 21455 T2846 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 21563 T2846 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 21565 T2846 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 21580 T2846 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 22589 T2846 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 22603 T2846 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 22608 T2846 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 22633 T2846 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 22639 T2846 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 22646 T2846 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 22648 T2846 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 22649 T2846 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-1348287398681\data_7000\
[junit4:junit4]   2> 22649 T2846 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1882760
[junit4:junit4]   2> 22652 T2846 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-1348287398681\data_7000\index/
[junit4:junit4]   2> 22653 T2846 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1348287398681\data_7000\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 22654 T2846 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1348287398681\data_7000\index forceNew:false
[junit4:junit4]   2> 22656 T2846 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@102bc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@f82107),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 22656 T2846 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 22658 T2846 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 22661 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 22661 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 22661 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 22662 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 22662 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 22662 T2846 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 22662 T2846 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 22664 T2846 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 22664 T2846 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 22665 T2846 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 22665 T2846 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 22667 T2846 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 22667 T2846 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 22667 T2846 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 22668 T2846 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 22668 T2846 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 22669 T2846 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 22669 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 22670 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 22670 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 22670 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 22671 T2846 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 22671 T2846 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 22672 T2846 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 22672 T2846 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 22677 T2846 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 22683 T2846 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 22684 T2846 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> 22688 T2846 oass.SolrIndexSearcher.<init> Opening Searcher@1210922 main
[junit4:junit4]   2> 22688 T2846 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 22690 T2846 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 22690 T2846 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 22691 T2846 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 22703 T2846 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 22777 T2898 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1210922 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 22789 T2889 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec3103230002 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> 22794 T2846 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 22794 T2846 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:7000/solr shardId:shard1
[junit4:junit4]   2> 22796 T2846 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 22807 T2889 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec3103230002 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> 22809 T2846 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 22811 T2889 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec3103230002 type:create cxid:0x66 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 23134 T2896 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 23135 T2889 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec3103230002 type:create cxid:0x6d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 23154 T2895 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 23405 T2846 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 23405 T2846 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 23405 T2846 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:7000/solr/collection1/
[junit4:junit4]   2> 23406 T2846 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 23413 T2889 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec3103230002 type:create cxid:0x8b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 23753 T2896 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 23761 T2895 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 23777 T2846 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> 23777 T2846 oasc.ZkController.checkRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> 23781 T2846 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 23785 T2846 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 23785 T2846 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> 23786 T2846 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> 23786 T2846 oasc.CoreContainer.<init> New CoreContainer 16209842
[junit4:junit4]   2> 23788 T2846 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> 23788 T2846 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> 23850 T2846 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 23877 T2846 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:65225/solr
[junit4:junit4]   2> 23879 T2846 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:65225/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@115e96f
[junit4:junit4]   2> 23880 T2846 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 23880 T2899 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:65225
[junit4:junit4]   2> 23882 T2899 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:65225, initiating session
[junit4:junit4]   2> 23883 T2886 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:65237
[junit4:junit4]   2> 23883 T2886 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:65237
[junit4:junit4]   2> 23885 T2888 oazs.NIOServerCnxn.finishSessionInit Established session 0x139ec3103230003 with negotiated timeout 3000 for client /127.0.0.1:65237
[junit4:junit4]   2> 23885 T2899 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:65225, sessionid = 0x139ec3103230003, negotiated timeout = 3000
[junit4:junit4]   2> 23885 T2900 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@115e96f name:ZooKeeperConnection Watcher:127.0.0.1:65225/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 23887 T2846 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 23887 T2889 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec3103230003 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 23889 T2889 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec3103230003 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 23891 T2846 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:7001_solr
[junit4:junit4]   2> 23894 T2889 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139ec3103230003 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> 23895 T2846 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:7001_solr
[junit4:junit4]   2> 23898 T2895 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 23899 T2846 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 23899 T2846 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 23899 T2846 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 23899 T2846 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 23900 T2846 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 23900 T2846 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 23900 T2846 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 23900 T2846 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 23900 T2846 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 23902 T2846 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 23917 T2846 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 23925 T2846 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> 23925 T2846 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 23926 T2846 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 23926 T2846 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 23928 T2846 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> 23930 T2846 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> 23933 T2846 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> 24012 T2846 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 24125 T2846 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 24127 T2846 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 24134 T2846 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 24370 T2896 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 24383 T2900 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 24384 T2895 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 25214 T2846 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 25226 T2846 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 25231 T2846 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 25249 T2846 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 25254 T2846 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 25260 T2846 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 25262 T2846 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 25264 T2846 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-1348287398681\data_7001\
[junit4:junit4]   2> 25264 T2846 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1882760
[junit4:junit4]   2> 25265 T2846 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-1348287398681\data_7001\index/
[junit4:junit4]   2> 25266 T2846 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1348287398681\data_7001\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 25267 T2846 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1348287398681\data_7001\index forceNew:false
[junit4:junit4]   2> 25271 T2846 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1e48bb4 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1c8fb4e),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 25271 T2846 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 25272 T2846 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 25274 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 25274 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 25275 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 25276 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 25277 T2846 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 25277 T2846 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 25277 T2846 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 25278 T2846 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 25278 T2846 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 25280 T2846 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 25280 T2846 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 25281 T2846 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 25281 T2846 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 25282 T2846 oasc.RequestHandler

[...truncated too long message...]

olr.cloud.OverseerCollectionProcessor.<init>(OverseerCollectionProcessor.java:78)
[junit4:junit4]   2> 	at org.apache.solr.cloud.Overseer.start(Overseer.java:499)
[junit4:junit4]   2> 	at org.apache.solr.cloud.OverseerElectionContext.runLeaderProcess(ElectionContext.java:372)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:158)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:102)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:56)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:131)
[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> 
[junit4:junit4]   2> -30539 T2886 oazs.NIOServerCnxn$Factory.run NIOServerCnxn factory exited run method
[junit4:junit4]   2> -30535 T2912 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:550)
[junit4:junit4]   2> 	at org.apache.solr.cloud.Overseer.getCollectionQueue(Overseer.java:535)
[junit4:junit4]   2> 	at org.apache.solr.cloud.OverseerCollectionProcessor.<init>(OverseerCollectionProcessor.java:78)
[junit4:junit4]   2> 	at org.apache.solr.cloud.Overseer.start(Overseer.java:499)
[junit4:junit4]   2> 	at org.apache.solr.cloud.OverseerElectionContext.runLeaderProcess(ElectionContext.java:372)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:158)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:102)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:56)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:131)
[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:339)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$9.execute(SolrZkClient.java:336)
[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:336)
[junit4:junit4]   2> 	at org.apache.solr.cloud.Overseer.createOverseerNode(Overseer.java:541)
[junit4:junit4]   2> 	... 10 more
[junit4:junit4]   2> 
[junit4:junit4]   2> -30535 T2912 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> -30535 T2912 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> -30534 T2912 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> -30534 T2846 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
[junit4:junit4]   2> -30533 T2846 oas.SolrTestCaseJ4.tearDown ###Ending testSimpleSliceLeaderElection
[junit4:junit4]   2> -28332 T2846 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> -28090 T2845 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s).
[junit4:junit4]   2> -7874 T2845 ccr.ThreadLeakControl.checkThreadLeaks SEVERE 1 thread leaked from SUITE scope at org.apache.solr.cloud.LeaderElectionIntegrationTest: 
[junit4:junit4]   2> 	   1) Thread[id=2887, 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> -7874 T2845 ccr.ThreadLeakControl.tryToInterruptAll Starting to interrupt leaked threads:
[junit4:junit4]   2> 	   1) Thread[id=2887, name=SessionTracker, state=TIMED_WAITING, group=TGRP-LeaderElectionIntegrationTest]
[junit4:junit4]   2> -7871 T2887 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> -7871 T2887 oazs.SessionTrackerImpl.run SessionTrackerImpl exited loop!
[junit4:junit4]   2> -7871 T2845 ccr.ThreadLeakControl.tryToInterruptAll All leaked threads terminated.
[junit4:junit4]   2> NOTE: test params are: codec=Lucene3x, sim=DefaultSimilarity, locale=hr_HR, timezone=Canada/Mountain
[junit4:junit4]   2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.7.0_07 (32-bit)/cpus=2,threads=1,free=100311032,total=238329856
[junit4:junit4]   2> NOTE: All tests run in this JVM: [SolrIndexConfigTest, TestWordDelimiterFilterFactory, TestQuerySenderListener, RequestHandlersTest, TestStressVersions, TestCoreContainer, TestUtils, SOLR749Test, TestPropInject, TestFastOutputStream, SolrPluginUtilsTest, IndexSchemaRuntimeFieldTest, DirectUpdateHandlerTest, SuggesterTest, MinimalSchemaTest, TestValueSourceCache, BasicFunctionalityTest, ConvertedLegacyTest, IndexBasedSpellCheckerTest, SimpleFacetsTest, SampleTest, SortByFunctionTest, TestReplicationHandler, TestNumberUtils, TestSearchPerf, TestQueryTypes, SpatialFilterTest, TestFoldingMultitermQuery, EchoParamsTest, TestExtendedDismaxParser, TestRandomFaceting, TestDistributedGrouping, TestRemoteStreaming, UUIDFieldTest, DistanceFunctionTest, SignatureUpdateProcessorFactoryTest, SolrCmdDistributorTest, ResourceLoaderTest, NotRequiredUniqueKeyTest, TestPseudoReturnFields, TestJmxIntegration, SolrCoreCheckLockOnStartupTest, NoCacheHeaderTest, TestLRUCache, TestFiltering, BinaryUpdateRequestHandlerTest, TestDocSet, TestPropInjectDefaults, ClusterStateUpdateTest, SoftAutoCommitTest, TestSuggestSpellingConverter, ShowFileRequestHandlerTest, TestStressReorder, QueryElevationComponentTest, TestJoin, CircularListTest, TestIndexSearcher, RecoveryZkTest, PolyFieldTest, TestStressRecovery, DistributedSpellCheckComponentTest, UpdateRequestProcessorFactoryTest, TestPluginEnable, TestTrie, PrimUtilsTest, BasicDistributedZk2Test, PingRequestHandlerTest, TimeZoneUtilsTest, TestDefaultSimilarityFactory, TestJmxMonitoredMap, TestCodecSupport, DistributedTermsComponentTest, TestCSVResponseWriter, DebugComponentTest, TestXIncludeConfig, LeaderElectionTest, TestGroupingSearch, ResponseLogComponentTest, JsonLoaderTest, DocumentBuilderTest, JSONWriterTest, TestSolrCoreProperties, TermsComponentTest, HighlighterConfigTest, TermVectorComponentTest, SolrRequestParserTest, AnalysisAfterCoreReloadTest, IndexReaderFactoryTest, TestSolr4Spatial, TestSolrDeletionPolicy1, DirectSolrConnectionTest, TestStressLucene, TestSolrQueryParser, TestBM25SimilarityFactory, TestUpdate, TestBadConfig, SpellingQueryConverterTest, TestCharFilters, DistributedQueryElevationComponentTest, DefaultValueUpdateProcessorTest, SpellCheckCollatorTest, SystemInfoHandlerTest, PreAnalyzedFieldTest, ZkControllerTest, TestLFUCache, BadIndexSchemaTest, TestFunctionQuery, SpellPossibilityIteratorTest, TestFastWriter, CSVRequestHandlerTest, BasicDistributedZkTest, OverseerTest, SpellCheckComponentTest, FullSolrCloudDistribCmdsTest, LeaderElectionIntegrationTest]
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=LeaderElectionIntegrationTest -Dtests.seed=6FAB9BC5F7828228 -Dtests.slow=true -Dtests.locale=hr_HR -Dtests.timezone=Canada/Mountain -Dtests.file.encoding=Cp1252
[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=2887, 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([6FAB9BC5F7828228]:0)
[junit4:junit4] Completed in -7.80s, 2 tests, 1 error <<< FAILURES!

[...truncated 342 lines...]
BUILD FAILED
C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\build.xml:32: The following error occurred while executing this line:
C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build.xml:179: 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:1140: The following error occurred while executing this line:
C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:813: There were test failures: 231 suites, 954 tests, 1 suite-level error, 12 ignored (7 assumptions)

Total time: 38 minutes 0 seconds
Build step 'Invoke Ant' marked build as failure
Recording test results
Description set: Java: 32bit/jdk1.7.0_07 -client -XX:+UseConcMarkSweepGC
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message