You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2014/02/01 14:59:49 UTC

[JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.8.0-ea-b124) - Build # 3733 - Failure!

Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/3733/
Java: 32bit/jdk1.8.0-ea-b124 -server -XX:+UseSerialGC

1 tests failed.
REGRESSION:  org.apache.solr.cloud.OverseerRolesTest.testDistribSearch

Error Message:
Captured an uncaught exception in thread: Thread[id=11042, name=Overseer-91177808647553036-127.0.0.1:54006_jy_p-n_0000000011, state=RUNNABLE, group=Overseer collection creation process.]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=11042, name=Overseer-91177808647553036-127.0.0.1:54006_jy_p-n_0000000011, state=RUNNABLE, group=Overseer collection creation process.]
Caused by: org.apache.solr.common.cloud.ZooKeeperException: 
	at __randomizedtesting.SeedInfo.seed([7A97E18DDE1B7C41]:0)
	at org.apache.solr.cloud.OverseerCollectionProcessor.run(OverseerCollectionProcessor.java:202)
	at java.lang.Thread.run(Thread.java:744)
Caused by: org.apache.zookeeper.KeeperException$NoNodeException: KeeperErrorCode = NoNode for /overseer/collection-queue-work/qn-0000000004
	at org.apache.zookeeper.KeeperException.create(KeeperException.java:111)
	at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
	at org.apache.zookeeper.ZooKeeper.delete(ZooKeeper.java:873)
	at org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:173)
	at org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:170)
	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
	at org.apache.solr.common.cloud.SolrZkClient.delete(SolrZkClient.java:170)
	at org.apache.solr.cloud.DistributedQueue.remove(DistributedQueue.java:193)
	at org.apache.solr.cloud.OverseerCollectionProcessor.run(OverseerCollectionProcessor.java:193)
	... 1 more




Build Log:
[...truncated 10971 lines...]
   [junit4] Suite: org.apache.solr.cloud.OverseerRolesTest
   [junit4]   2> 2440141 T10873 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /jy_p/
   [junit4]   2> 2440143 T10873 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
   [junit4]   2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-OverseerRolesTest-1391262949328
   [junit4]   2> 2440145 T10873 oasc.ZkTestServer.run STARTING ZK TEST SERVER
   [junit4]   2> 2440147 T10874 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
   [junit4]   2> 2440227 T10873 oasc.ZkTestServer.run start zk server on port:53960
   [junit4]   2> 2440228 T10873 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2440244 T10880 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6dec22 name:ZooKeeperConnection Watcher:127.0.0.1:53960 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2440245 T10873 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2440245 T10873 oascc.SolrZkClient.makePath makePath: /solr
   [junit4]   2> 2440251 T10875 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x143edbbdfd60000, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:744)
   [junit4]   2> 
   [junit4]   2> 2440254 T10873 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2440256 T10882 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@19832c7 name:ZooKeeperConnection Watcher:127.0.0.1:53960/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2440257 T10873 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2440257 T10873 oascc.SolrZkClient.makePath makePath: /collections/collection1
   [junit4]   2> 2440260 T10873 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
   [junit4]   2> 2440262 T10873 oascc.SolrZkClient.makePath makePath: /collections/control_collection
   [junit4]   2> 2440265 T10873 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
   [junit4]   2> 2440268 T10873 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 2440269 T10873 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 2440273 T10873 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
   [junit4]   2> 2440274 T10873 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
   [junit4]   2> 2440277 T10873 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 2440277 T10873 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 2440281 T10873 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
   [junit4]   2> 2440281 T10873 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 2440284 T10873 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
   [junit4]   2> 2440284 T10873 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
   [junit4]   2> 2440288 T10873 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
   [junit4]   2> 2440288 T10873 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
   [junit4]   2> 2440292 T10873 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml
   [junit4]   2> 2440292 T10873 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 2440296 T10873 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 2440296 T10873 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 2440300 T10873 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 2440301 T10873 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 2440303 T10873 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
   [junit4]   2> 2440304 T10873 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 2440307 T10873 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
   [junit4]   2> 2440307 T10873 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 2440311 T10875 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x143edbbdfd60001, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:744)
   [junit4]   2> 
   [junit4]   2> 2440709 T10873 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 2440720 T10873 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53967
   [junit4]   2> 2440720 T10873 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 2440720 T10873 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2440720 T10873 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-controljetty-1391262949494
   [junit4]   2> 2440720 T10873 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-controljetty-1391262949494\'
   [junit4]   2> 2440748 T10873 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-controljetty-1391262949494\solr.xml
   [junit4]   2> 2440756 T10873 oasc.ConfigSolrXml.<init> Config-defined core root directory: 
   [junit4]   2> 2440758 T10873 oasc.CoreContainer.<init> New CoreContainer 18155814
   [junit4]   2> 2440759 T10873 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-controljetty-1391262949494\]
   [junit4]   2> 2440759 T10873 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 2440759 T10873 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 2440759 T10873 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 2440760 T10873 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 2440760 T10873 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 2440760 T10873 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 2440761 T10873 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 2440761 T10873 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 2440762 T10873 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 2440766 T10873 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2440766 T10873 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2440766 T10873 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 2440766 T10873 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53960/solr
   [junit4]   2> 2440766 T10873 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 2440770 T10873 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2440776 T10893 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@ec53a1 name:ZooKeeperConnection Watcher:127.0.0.1:53960 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2440777 T10873 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2440780 T10873 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2440784 T10895 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1ebebcd name:ZooKeeperConnection Watcher:127.0.0.1:53960/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2440784 T10873 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2440786 T10873 oascc.SolrZkClient.makePath makePath: /overseer/queue
   [junit4]   2> 2440790 T10873 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
   [junit4]   2> 2440794 T10873 oascc.SolrZkClient.makePath makePath: /live_nodes
   [junit4]   2> 2440797 T10873 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53967_jy_p
   [junit4]   2> 2440798 T10873 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53967_jy_p
   [junit4]   2> 2440801 T10873 oascc.SolrZkClient.makePath makePath: /overseer_elect
   [junit4]   2> 2440804 T10873 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
   [junit4]   2> 2440808 T10873 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:53967_jy_p
   [junit4]   2> 2440808 T10873 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
   [junit4]   2> 2440811 T10873 oasc.Overseer.start Overseer (id=91177808647553027-127.0.0.1:53967_jy_p-n_0000000000) starting
   [junit4]   2> 2440815 T10873 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
   [junit4]   2> 2440819 T10897 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
   [junit4]   2> 2440820 T10873 oascc.SolrZkClient.makePath makePath: /clusterstate.json
   [junit4]   2> 2440820 T10897 oasc.OverseerCollectionProcessor.prioritizeOverseerNodes prioritizing overseer nodes
   [junit4]   2> 2440823 T10873 oascc.SolrZkClient.makePath makePath: /aliases.json
   [junit4]   2> 2440825 T10873 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 2440828 T10896 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
   [junit4]   2> 2440829 T10873 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-controljetty-1391262949494
   [junit4]   2> 2441032 T10873 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-controljetty-1391262949494\conf\
   [junit4]   2> 2441045 T10873 oasc.CorePropertiesLocator.discover Found 1 core definitions
   [junit4]   2> 2441046 T10873 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 2441046 T10873 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 2441048 T10873 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2441050 T10900 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@77c532 name:ZooKeeperConnection Watcher:127.0.0.1:53960/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2441051 T10873 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2441052 T10873 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 2441055 T10873 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 2441452 T10873 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
   [junit4]   2> 2441453 T10873 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 2441457 T10873 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53979
   [junit4]   2> 2441457 T10873 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 2441457 T10873 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2441458 T10873 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty1-1391262950238
   [junit4]   2> 2441458 T10873 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty1-1391262950238\'
   [junit4]   2> 2441484 T10873 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty1-1391262950238\solr.xml
   [junit4]   2> 2441503 T10873 oasc.ConfigSolrXml.<init> Config-defined core root directory: 
   [junit4]   2> 2441504 T10873 oasc.CoreContainer.<init> New CoreContainer 9322817
   [junit4]   2> 2441504 T10873 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty1-1391262950238\]
   [junit4]   2> 2441505 T10873 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 2441505 T10873 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 2441505 T10873 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 2441506 T10873 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 2441506 T10873 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 2441507 T10873 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 2441507 T10873 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 2441507 T10873 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 2441507 T10873 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 2441510 T10873 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2441511 T10873 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2441511 T10873 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 2441512 T10873 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53960/solr
   [junit4]   2> 2441512 T10873 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 2441514 T10873 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2441519 T10911 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@aea46b name:ZooKeeperConnection Watcher:127.0.0.1:53960 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2441519 T10873 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2441521 T10875 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x143edbbdfd60005, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:744)
   [junit4]   2> 
   [junit4]   2> 2441523 T10873 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2441524 T10913 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1882ef7 name:ZooKeeperConnection Watcher:127.0.0.1:53960/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2441525 T10873 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2441529 T10873 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 2442331 T10873 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53979_jy_p
   [junit4]   2> 2442332 T10873 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53979_jy_p
   [junit4]   2> 2442336 T10895 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 2442336 T10913 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 2442336 T10900 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 2442341 T10873 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty1-1391262950238
   [junit4]   2> 2442544 T10873 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty1-1391262950238\conf\
   [junit4]   2> 2442554 T10873 oasc.CorePropertiesLocator.discover Found 1 core definitions
   [junit4]   2> 2442555 T10873 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 2442556 T10873 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 2442963 T10873 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
   [junit4]   2> 2442963 T10873 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 2442968 T10873 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53988
   [junit4]   2> 2442968 T10873 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 2442968 T10873 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2442968 T10873 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.cloud.OverseerRolesTest-jetty2-1391262951739
   [junit4]   2> 2442968 T10873 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.OverseerRolesTest-jetty2-1391262951739\'
   [junit4]   2> 2443006 T10873 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty2-1391262951739\solr.xml
   [junit4]   2> 2443014 T10873 oasc.ConfigSolrXml.<init> Config-defined core root directory: 
   [junit4]   2> 2443014 T10873 oasc.CoreContainer.<init> New CoreContainer 19510666
   [junit4]   2> 2443014 T10873 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.cloud.OverseerRolesTest-jetty2-1391262951739\]
   [junit4]   2> 2443016 T10873 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 2443016 T10873 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 2443016 T10873 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 2443016 T10873 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 2443016 T10873 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 2443017 T10873 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 2443017 T10873 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 2443017 T10873 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 2443017 T10873 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 2443020 T10873 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2443020 T10873 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2443022 T10873 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 2443022 T10873 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53960/solr
   [junit4]   2> 2443023 T10873 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 2443024 T10873 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2443033 T10925 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@c03c05 name:ZooKeeperConnection Watcher:127.0.0.1:53960 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2443033 T10873 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2443035 T10875 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x143edbbdfd60007, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:744)
   [junit4]   2> 
   [junit4]   2> 2443037 T10873 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2443039 T10927 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@c32795 name:ZooKeeperConnection Watcher:127.0.0.1:53960/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2443039 T10873 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2443046 T10873 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 2443849 T10873 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53988_jy_p
   [junit4]   2> 2443850 T10873 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53988_jy_p
   [junit4]   2> 2443855 T10900 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 2443855 T10927 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 2443856 T10895 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 2443856 T10913 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 2443861 T10873 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty2-1391262951739
   [junit4]   2> 2444053 T10873 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty2-1391262951739\conf\
   [junit4]   2> 2444064 T10873 oasc.CorePropertiesLocator.discover Found 1 core definitions
   [junit4]   2> 2444066 T10873 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 2444066 T10873 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 2444465 T10873 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3
   [junit4]   2> 2444466 T10873 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 2444470 T10873 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53997
   [junit4]   2> 2444471 T10873 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 2444471 T10873 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2444471 T10873 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty3-1391262953250
   [junit4]   2> 2444472 T10873 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty3-1391262953250\'
   [junit4]   2> 2444497 T10873 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty3-1391262953250\solr.xml
   [junit4]   2> 2444506 T10873 oasc.ConfigSolrXml.<init> Config-defined core root directory: 
   [junit4]   2> 2444507 T10873 oasc.CoreContainer.<init> New CoreContainer 15459912
   [junit4]   2> 2444508 T10873 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty3-1391262953250\]
   [junit4]   2> 2444508 T10873 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 2444508 T10873 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 2444508 T10873 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 2444509 T10873 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 2444509 T10873 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 2444510 T10873 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 2444510 T10873 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 2444510 T10873 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 2444511 T10873 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 2444515 T10873 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2444515 T10873 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2444515 T10873 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 2444516 T10873 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53960/solr
   [junit4]   2> 2444516 T10873 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 2444519 T10873 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2444525 T10939 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@38c1c4 name:ZooKeeperConnection Watcher:127.0.0.1:53960 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2444526 T10873 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2444529 T10873 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2444533 T10941 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1691b59 name:ZooKeeperConnection Watcher:127.0.0.1:53960/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2444533 T10873 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2444540 T10873 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 2445359 T10873 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53997_jy_p
   [junit4]   2> 2445361 T10873 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53997_jy_p
   [junit4]   2> 2445365 T10927 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 2445365 T10913 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 2445366 T10895 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 2445367 T10900 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 2445367 T10941 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 2445373 T10873 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty3-1391262953250
   [junit4]   2> 2445586 T10873 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty3-1391262953250\conf\
   [junit4]   2> 2445597 T10873 oasc.CorePropertiesLocator.discover Found 1 core definitions
   [junit4]   2> 2445599 T10873 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 2445599 T10873 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 2446043 T10873 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 4
   [junit4]   2> 2446044 T10873 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 2446050 T10873 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54006
   [junit4]   2> 2446051 T10873 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 2446051 T10873 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2446052 T10873 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty4-1391262954782
   [junit4]   2> 2446052 T10873 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty4-1391262954782\'
   [junit4]   2> 2446079 T10873 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty4-1391262954782\solr.xml
   [junit4]   2> 2446089 T10873 oasc.ConfigSolrXml.<init> Config-defined core root directory: 
   [junit4]   2> 2446089 T10873 oasc.CoreContainer.<init> New CoreContainer 24617151
   [junit4]   2> 2446089 T10873 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty4-1391262954782\]
   [junit4]   2> 2446090 T10873 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 2446090 T10873 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 2446090 T10873 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 2446090 T10873 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 2446090 T10873 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 2446090 T10873 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 2446090 T10873 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 2446092 T10873 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 2446092 T10873 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 2446092 T10873 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2446096 T10873 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2446096 T10873 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 2446096 T10873 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53960/solr
   [junit4]   2> 2446096 T10873 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 2446098 T10873 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2446107 T10953 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1168a9f name:ZooKeeperConnection Watcher:127.0.0.1:53960 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2446108 T10873 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2446113 T10873 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2446115 T10955 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1106d0c name:ZooKeeperConnection Watcher:127.0.0.1:53960/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2446116 T10873 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2446120 T10873 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 2447001 T10873 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54006_jy_p
   [junit4]   2> 2447003 T10873 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54006_jy_p
   [junit4]   2> 2447008 T10927 oascc.ZkStateReader$3.process Updating live nodes... (5)
   [junit4]   2> 2447008 T10913 oascc.ZkStateReader$3.process Updating live nodes... (5)
   [junit4]   2> 2447008 T10895 oascc.ZkStateReader$3.process Updating live nodes... (5)
   [junit4]   2> 2447008 T10941 oascc.ZkStateReader$3.process Updating live nodes... (5)
   [junit4]   2> 2447009 T10955 oascc.ZkStateReader$3.process Updating live nodes... (5)
   [junit4]   2> 2447009 T10900 oascc.ZkStateReader$3.process Updating live nodes... (5)
   [junit4]   2> 2447016 T10873 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty4-1391262954782
   [junit4]   2> 2447244 T10873 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty4-1391262954782\conf\
   [junit4]   2> 2447254 T10873 oasc.CorePropertiesLocator.discover Found 1 core definitions
   [junit4]   2> 2447257 T10873 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 2447257 T10873 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 2447689 T10873 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 5
   [junit4]   2> 2447689 T10873 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 2447695 T10873 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54015
   [junit4]   2> 2447695 T10873 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 2447695 T10873 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2447696 T10873 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.cloud.OverseerRolesTest-jetty5-1391262956441
   [junit4]   2> 2447696 T10873 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.OverseerRolesTest-jetty5-1391262956441\'
   [junit4]   2> 2447725 T10873 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty5-1391262956441\solr.xml
   [junit4]   2> 2447735 T10873 oasc.ConfigSolrXml.<init> Config-defined core root directory: 
   [junit4]   2> 2447736 T10873 oasc.CoreContainer.<init> New CoreContainer 21797625
   [junit4]   2> 2447736 T10873 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.cloud.OverseerRolesTest-jetty5-1391262956441\]
   [junit4]   2> 2447737 T10873 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 2447737 T10873 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 2447737 T10873 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 2447737 T10873 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 2447738 T10873 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 2447738 T10873 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 2447738 T10873 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 2447739 T10873 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 2447739 T10873 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 2447743 T10873 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2447743 T10873 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2447743 T10873 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 2447745 T10873 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53960/solr
   [junit4]   2> 2447745 T10873 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 2447748 T10873 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2447755 T10967 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1f0aae9 name:ZooKeeperConnection Watcher:127.0.0.1:53960 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2447755 T10873 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2447759 T10875 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x143edbbdfd6000d, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:744)
   [junit4]   2> 
   [junit4]   2> 2447761 T10873 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2447764 T10969 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1776d88 name:ZooKeeperConnection Watcher:127.0.0.1:53960/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2447764 T10873 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2447771 T10873 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 2448653 T10873 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54015_jy_p
   [junit4]   2> 2448654 T10873 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54015_jy_p
   [junit4]   2> 2448659 T10913 oascc.ZkStateReader$3.process Updating live nodes... (6)
   [junit4]   2> 2448659 T10895 oascc.ZkStateReader$3.process Updating live nodes... (6)
   [junit4]   2> 2448659 T10927 oascc.ZkStateReader$3.process Updating live nodes... (6)
   [junit4]   2> 2448659 T10969 oascc.ZkStateReader$3.process Updating live nodes... (6)
   [junit4]   2> 2448660 T10900 oascc.ZkStateReader$3.process Updating live nodes... (6)
   [junit4]   2> 2448659 T10941 oascc.ZkStateReader$3.process Updating live nodes... (6)
   [junit4]   2> 2448660 T10955 oascc.ZkStateReader$3.process Updating live nodes... (6)
   [junit4]   2> 2448665 T10873 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty5-1391262956441
   [junit4]   2> 2448877 T10873 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty5-1391262956441\conf\
   [junit4]   2> 2448886 T10873 oasc.CorePropertiesLocator.discover Found 1 core definitions
   [junit4]   2> 2448889 T10873 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 2448889 T10873 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 2449323 T10873 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 6
   [junit4]   2> 2449324 T10873 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 2449329 T10873 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54024
   [junit4]   2> 2449329 T10873 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 2449329 T10873 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2449329 T10873 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty6-1391262958073
   [junit4]   2> 2449331 T10873 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty6-1391262958073\'
   [junit4]   2> 2449355 T10873 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty6-1391262958073\solr.xml
   [junit4]   2> 2449368 T10873 oasc.ConfigSolrXml.<init> Config-defined core root directory: 
   [junit4]   2> 2449369 T10873 oasc.CoreContainer.<init> New CoreContainer 17349797
   [junit4]   2> 2449370 T10873 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty6-1391262958073\]
   [junit4]   2> 2449371 T10873 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 2449371 T10873 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 2449371 T10873 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 2449371 T10873 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 2449372 T10873 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 2449372 T10873 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 2449372 T10873 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 2449374 T10873 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 2449374 T10873 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 2449378 T10873 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2449381 T10873 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2449382 T10873 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 2449382 T10873 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53960/solr
   [junit4]   2> 2449382 T10873 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 2449386 T10873 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2449591 T10981 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1ea16bb name:ZooKeeperConnection Watcher:127.0.0.1:53960 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2449593 T10873 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2449623 T10875 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x143edbbdfd6000f, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:744)
   [junit4]   2> 
   [junit4]   2> 2449625 T10873 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2449628 T10983 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@167632e name:ZooKeeperConnection Watcher:127.0.0.1:53960/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2449628 T10873 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2449633 T10873 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 2450514 T10873 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54024_jy_p
   [junit4]   2> 2450515 T10873 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54024_jy_p
   [junit4]   2> 2450521 T10913 oascc.ZkStateReader$3.process Updating live nodes... (7)
   [junit4]   2> 2450521 T10895 oascc.ZkStateReader$3.process Updating live nodes... (7)
   [junit4]   2> 2450521 T10969 oascc.ZkStateReader$3.process Updating live nodes... (7)
   [junit4]   2> 2450521 T10941 oascc.ZkStateReader$3.process Updating live nodes... (7)
   [junit4]   2> 2450522 T10983 oascc.ZkStateReader$3.process Updating live nodes... (7)
   [junit4]   2> 2450524 T10900 oascc.ZkStateReader$3.process Updating live nodes... (7)
   [junit4]   2> 2450524 T10955 oascc.ZkStateReader$3.process Updating live nodes... (7)
   [junit4]   2> 2450524 T10927 oascc.ZkStateReader$3.process Updating live nodes... (7)
   [junit4]   2> 2450529 T10873 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty6-1391262958073
   [junit4]   2> 2450779 T10873 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty6-1391262958073\conf\
   [junit4]   2> 2450791 T10873 oasc.CorePropertiesLocator.discover Found 1 core definitions
   [junit4]   2> 2450791 T10873 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 2450792 T10873 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 2450794 T10873 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2450797 T10986 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@824f78 name:ZooKeeperConnection Watcher:127.0.0.1:53960/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2450797 T10873 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2450799 T10873 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 2450803 T10873 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2450805 T10988 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@753cd9 name:ZooKeeperConnection Watcher:127.0.0.1:53960/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2450805 T10873 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2450807 T10873 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 2450810 T10973 oasha.CollectionsHandler.handleCreateAction Creating Collection : replicationFactor=2&maxShardsPerNode=2&name=testOverseerCol&action=CREATE&numShards=4&wt=javabin&version=2
   [junit4]   2> 2450812 T10895 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/collection-queue-work state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2450814 T10895 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/collection-queue-work state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2450814 T10897 oasc.OverseerCollectionProcessor.run Overseer Collection Processor: Get the message id:/overseer/collection-queue-work/qn-0000000000 message:{
   [junit4]   2> 	  "operation":"createcollection",
   [junit4]   2> 	  "fromApi":"true",
   [junit4]   2> 	  "name":"testOverseerCol",
   [junit4]   2> 	  "replicationFactor":"2",
   [junit4]   2> 	  "numShards":"4",
   [junit4]   2> 	  "maxShardsPerNode":"2"}
   [junit4]   2> 2450814 T10897 oasc.OverseerCollectionProcessor.processMessage WARN OverseerCollectionProcessor.processMessage : createcollection , {
   [junit4]   2> 	  "operation":"createcollection",
   [junit4]   2> 	  "fromApi":"true",
   [junit4]   2> 	  "name":"testOverseerCol",
   [junit4]   2> 	  "replicationFactor":"2",
   [junit4]   2> 	  "numShards":"4",
   [junit4]   2> 	  "maxShardsPerNode":"2"}
   [junit4]   2> 2450815 T10897 oasc.OverseerCollectionProcessor.createConfNode Only one config set found in zk - using it:conf1
   [junit4]   2> 2450815 T10897 oasc.OverseerCollectionProcessor.createConfNode creating collections conf node /collections/testOverseerCol 
   [junit4]   2> 2450815 T10897 oascc.SolrZkClient.makePath makePath: /collections/testOverseerCol
   [junit4]   2> 2450821 T10895 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2450822 T10896 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 2450823 T10896 oasc.Overseer$ClusterStateUpdater.buildCollection building a new collection: testOverseerCol
   [junit4]   2> 2450823 T10896 oasc.Overseer$ClusterStateUpdater.createCollection Create collection testOverseerCol with shards [shard1, shard2, shard3, shard4]
   [junit4]   2> 2450827 T10895 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2450828 T10988 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
   [junit4]   2> 2450828 T10913 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
   [junit4]   2> 2450828 T10900 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
   [junit4]   2> 2450829 T10895 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
   [junit4]   2> 2450828 T10986 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
   [junit4]   2> 2450829 T10955 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
   [junit4]   2> 2450829 T10927 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
   [junit4]   2> 2450829 T10983 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
   [junit4]   2> 2450829 T10969 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
   [junit4]   2> 2450829 T10941 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
   [junit4]   2> 2450909 T10897 oasc.OverseerCollectionProcessor.createCollection going to create cores replicas shardNames [shard1, shard2, shard3, shard4] , repFactor : 2
   [junit4]   2> 2450909 T10897 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerCol_shard1_replica1 as part of slice shard1 of collection testOverseerCol on 127.0.0.1:53988_jy_p
   [junit4]   2> 2450910 T10897 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerCol_shard1_replica2 as part of slice shard1 of collection testOverseerCol on 127.0.0.1:53997_jy_p
   [junit4]   2> 2450910 T10897 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerCol_shard2_replica1 as part of slice shard2 of collection testOverseerCol on 127.0.0.1:54024_jy_p
   [junit4]   2> 2450913 T10897 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerCol_shard2_replica2 as part of slice shard2 of collection testOverseerCol on 127.0.0.1:54006_jy_p
   [junit4]   2> 2450915 T10897 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerCol_shard3_replica1 as part of slice shard3 of collection testOverseerCol on 127.0.0.1:54015_jy_p
   [junit4]   2> 2450916 T10897 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerCol_shard3_replica2 as part of slice shard3 of collection testOverseerCol on 127.0.0.1:53967_jy_p
   [junit4]   2> 2450916 T10897 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerCol_shard4_replica1 as part of slice shard4 of collection testOverseerCol on 127.0.0.1:53979_jy_p
   [junit4]   2> 2450917 T10897 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerCol_shard4_replica2 as part of slice shard4 of collection testOverseerCol on 127.0.0.1:53988_jy_p
   [junit4]   2> 2450917 T10977 oasc.ZkController.publish publishing core=testOverseerCol_shard2_replica1 state=down
   [junit4]   2> 2450919 T10946 oasc.ZkController.publish publishing core=testOverseerCol_shard2_replica2 state=down
   [junit4]   2> 2450922 T10964 oasc.ZkController.publish publishing core=testOverseerCol_shard3_replica1 state=down
   [junit4]   2> 2450922 T10923 oasc.ZkController.publish publishing core=testOverseerCol_shard1_replica1 state=down
   [junit4]   2> 2450923 T10895 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2450923 T10977 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 2450923 T10935 oasc.ZkController.publish publishing core=testOverseerCol_shard1_replica2 state=down
   [junit4]   2> 2450931 T10923 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 2450928 T10889 oasc.ZkController.publish publishing core=testOverseerCol_shard3_replica2 state=down
   [junit4]   2> 2450927 T10946 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 2450927 T10905 oasc.ZkController.publish publishing core=testOverseerCol_shard4_replica1 state=down
   [junit4]   2> 2450934 T10889 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 2450927 T10964 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 2450934 T10905 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 2450933 T10895 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2450933 T10935 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 2450933 T10896 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 2450931 T10919 oasc.ZkController.publish publishing core=testOverseerCol_shard4_replica2 state=down
   [junit4]   2> 2450936 T10896 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:54024/jy_p",
   [junit4]   2> 	  "core":"testOverseerCol_shard2_replica1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:54024_jy_p",
   [junit4]   2> 	  "shard":"shard2",
   [junit4]   2> 	  "shard_range":null,
   [junit4]   2> 	  "shard_state":"active",
   [junit4]   2> 	  "shard_parent":null,
   [junit4]   2> 	  "collection":"testOverseerCol",
   [junit4]   2> 	  "numShards":"4",
   [junit4]   2> 	  "core_node_name":null}
   [junit4]   2> 2450937 T10919 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 2450941 T10896 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:54006/jy_p",
   [junit4]   2> 	  "core":"testOverseerCol_shard2_replica2",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:54006_jy_p",
   [junit4]   2> 	  "shard":"shard2",
   [junit4]   2> 	  "shard_range":null,
   [junit4]   2> 	  "shard_state":"active",
   [junit4]   2> 	  "shard_parent":null,
   [junit4]   2> 	  "collection":"testOverseerCol",
   [junit4]   2> 	  "numShards":"4",
   [junit4]   2> 	  "core_node_name":null}
   [junit4]   2> 2450945 T10895 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2450946 T10896 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:54015/jy_p",
   [junit4]   2> 	  "core":"testOverseerCol_shard3_replica1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:54015_jy_p",
   [junit4]   2> 	  "shard":"shard3",
   [junit4]   2> 	  "shard_range":null,
   [junit4]   2> 	  "shard_state":"active",
   [junit4]   2> 	  "shard_parent":null,
   [junit4]   2> 	  "collection":"testOverseerCol",
   [junit4]   2> 	  "numShards":"4",
   [junit4]   2> 	  "core_node_name":null}
   [junit4]   2> 2450949 T10895 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2450950 T10896 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:53988/jy_p",
   [junit4]   2> 	  "core":"testOverseerCol_shard1_replica1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:53988_jy_p",
   [junit4]   2> 	  "shard":"shard1",
   [junit4]   2> 	  "shard_range":null,
   [junit4]   2> 	  "shard_state":"active",
   [junit4]   2> 	  "shard_parent":null,
   [junit4]   2> 	  "collection":"testOverseerCol",
   [junit4]   2> 	  "numShards":"4",
   [junit4]   2> 	  "core_node_name":null}
   [junit4]   2> 2450952 T10895 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2450954 T10896 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:53997/jy_p",
   [junit4]   2> 	  "core":"testOverseerCol_shard1_replica2",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:53997_jy_p",
   [junit4]   2> 	  "shard":"shard1",
   [junit4]   2> 	  "shard_range":null,
   [junit4]   2> 	  "shard_state":"active",
   [junit4]   2> 	  "shard_parent":null,
   [junit4]   2> 	  "collection":"testOverseerCol",
   [junit4]   2> 	  "numShards":"4",
   [junit4]   2> 	  "core_node_name":null}
   [junit4]   2> 2450957 T10895 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2450958 T10896 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:53967/jy_p",
   [junit4]   2> 	  "core":"testOverseerCol_shard3_replica2",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:53967_jy_p",
   [junit4]   2> 	  "shard":"shard3",
   [junit4]   2> 	  "shard_range":null,
   [junit4]   2> 	  "shard_state":"active",
   [junit4]   2> 	  "shard_parent":null,
   [junit4]   2> 	  "collection":"testOverseerCol",
   [junit4]   2> 	  "numShards":"4",
   [junit4]   2> 	  "core_node_name":null}
   [junit4]   2> 2450961 T10895 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2450962 T10896 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:53979/jy_p",
   [junit4]   2> 	  "core":"testOverseerCol_shard4_replica1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:53979_jy_p",
   [junit4]   2> 	  "shard":"shard4",
   [junit4]   2> 	  "shard_range":null,
   [junit4]   2> 	  "shard_state":"active",
   [junit4]   2> 	  "shard_parent":null,
   [junit4]   2> 	  "collection":"testOverseerCol",
   [junit4]   2> 	  "numShards":"4",
   [junit4]   2> 	  "core_node_name":null}
   [junit4]   2> 2450965 T10895 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2450967 T10896 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:53988/jy_p",
   [junit4]   2> 	  "core":"testOverseerCol_shard4_replica2",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:53988_jy_p",
   [junit4]   2> 	  "shard":"shard4",
   [junit4]   2> 	  "shard_range":null,
   [junit4]   2> 	  "shard_state":"active",
   [junit4]   2> 	  "shard_parent":null,
   [junit4]   2> 	  "collection":"testOverseerCol",
   [junit4]   2> 	  "numShards":"4",
   [junit4]   2> 	  "core_node_name":null}
   [junit4]   2> 2450970 T10895 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2451061 T10988 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
   [junit4]   2> 2451062 T10895 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
   [junit4]   2> 2451062 T10900 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
   [junit4]   2> 2451063 T10969 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
   [junit4]   2> 2451063 T10927 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
   [junit4]   2> 2451063 T10955 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
   [junit4]   2> 2451062 T10986 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
   [junit4]   2> 2451062 T10913 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
   [junit4]   2> 2451063 T10941 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
   [junit4]   2> 2451063 T10983 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
   [junit4]   2> 2451805 T10977 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerCol_shard2_replica1
   [junit4]   2> 2451808 T10977 oasc.CoreContainer.create Creating SolrCore 'testOverseerCol_shard2_replica1' using instanceDir: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty6-1391262958073\testOverseerCol_shard2_replica1
   [junit4]   2> 2451809 T10977 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerCol
   [junit4]   2> 2451809 T10923 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerCol_shard1_replica1
   [junit4]   2> 2451809 T10946 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerCol_shard2_replica2
   [junit4]   2> 2451812 T10946 oasc.CoreContainer.create Creating SolrCore 'testOverseerCol_shard2_replica2' using instanceDir: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty4-1391262954782\testOverseerCol_shard2_replica2
   [junit4]   2> 2451812 T10889 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerCol_shard3_replica2
   [junit4]   2> 2451813 T10935 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerCol_shard1_replica2
   [junit4]   2> 2451812 T10905 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerCol_shard4_replica1
   [junit4]   2> 2451812 T10964 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerCol_shard3_replica1
   [junit4]   2> 2451814 T10935 oasc.CoreContainer.create Creating SolrCore 'testOverseerCol_shard1_replica2' using instanceDir: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty3-1391262953250\testOverseerCol_shard1_replica2
   [junit4]   2> 2451814 T10935 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerCol
   [junit4]   2> 2451815 T10964 oasc.CoreContainer.create Creating SolrCore 'testOverseerCol_shard3_replica1' using instanceDir: .\org.apache.solr.cloud.OverseerRolesTest-jetty5-1391262956441\testOverseerCol_shard3_replica1
   [junit4]   2> 2451815 T10964 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerCol
   [junit4]   2> 2451816 T10905 oasc.CoreContainer.create Creating SolrCore 'testOverseerCol_shard4_replica1' using instanceDir: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty1-1391262950238\testOverseerCol_shard4_replica1
   [junit4]   2> 2451816 T10905 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerCol
   [junit4]   2> 2451816 T10935 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 2451817 T10935 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerCol
   [junit4]   2> 2451812 T10946 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerCol
   [junit4]   2> 2451812 T10977 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 2451818 T10977 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerCol
   [junit4]   2> 2451812 T10923 oasc.CoreContainer.create Creating SolrCore 'testOverseerCol_shard1_replica1' using instanceDir: .\org.apache.solr.cloud.OverseerRolesTest-jetty2-1391262951739\testOverseerCol_shard1_replica1
   [junit4]   2> 2451819 T10923 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerCol
   [junit4]   2> 2451817 T10964 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 2451819 T10905 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 2451820 T10946 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 2451820 T10946 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerCol
   [junit4]   2> 2451820 T10905 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerCol
   [junit4]   2> 2451821 T10923 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 2451821 T10923 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerCol
   [junit4]   2> 2451821 T10935 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty3-1391262953250\testOverseerCol_shard1_replica2\'
   [junit4]   2> 2451820 T10964 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerCol
   [junit4]   2> 2451825 T10977 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty6-1391262958073\testOverseerCol_shard2_replica1\'
   [junit4]   2> 2451827 T10946 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty4-1391262954782\testOverseerCol_shard2_replica2\'
   [junit4]   2> 2451831 T10905 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty1-1391262950238\testOverseerCol_shard4_replica1\'
   [junit4]   2> 2451833 T10923 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.OverseerRolesTest-jetty2-1391262951739\testOverseerCol_shard1_replica1\'
   [junit4]   2> 2451834 T10964 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.OverseerRolesTest-jetty5-1391262956441\testOverseerCol_shard3_replica1\'
   [junit4]   2> 2451869 T10889 oasc.CoreContainer.create Creating SolrCore 'testOverseerCol_shard3_replica2' using instanceDir: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-controljetty-1391262949494\testOverseerCol_shard3_replica2
   [junit4]   2> 2451869 T10889 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerCol
   [junit4]   2> 2451869 T10919 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerCol_shard4_replica2
   [junit4]   2> 2451871 T10919 oasc.CoreContainer.create Creating SolrCore 'testOverseerCol_shard4_replica2' using instanceDir: .\org.apache.solr.cloud.OverseerRolesTest-jetty2-1391262951739\testOverseerCol_shard4_replica2
   [junit4]   2> 2451872 T10919 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerCol
   [junit4]   2> 2451874 T10889 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 2451875 T10889 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerCol
   [junit4]   2> 2451875 T10919 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 2451875 T10919 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerCol
   [junit4]   2> 2451887 T10935 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 2451890 T10905 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 2451894 T10935 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2451899 T10905 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2451904 T10889 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-controljetty-1391262949494\testOverseerCol_shard3_replica2\'
   [junit4]   2> 2451924 T10905 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 2451929 T10905 oass.IndexSchema.readSchema [testOverseerCol_shard4_replica1] Schema name=test
   [junit4]   2> 2451932 T10935 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 2451938 T10935 oass.IndexSchema.readSchema [testOverseerCol_shard1_replica2] Schema name=test
   [junit4]   2> 2451943 T10964 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 2451953 T10964 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2451954 T10919 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.OverseerRolesTest-jetty2-1391262951739\testOverseerCol_shard4_replica2\'
   [junit4]   2> 2452055 T10935 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 2452062 T10935 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 2452064 T10935 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 2452082 T10946 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 2452090 T10946 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2452095 T10964 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 2452100 T10964 oass.IndexSchema.readSchema [testOverseerCol_shard3_replica1] Schema name=test
   [junit4]   2> 2452165 T10946 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 2452169 T10946 oass.IndexSchema.readSchema [testOverseerCol_shard2_replica2] Schema name=test
   [junit4]   2> 2452178 T10905 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 2452182 T10905 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 2452184 T10905 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 2452216 T10977 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 2452225 T10977 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2452228 T10923 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 2452248 T10923 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2452258 T10889 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 2452265 T10889 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2452311 T10946 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 2452314 T10946 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 2452315 T10946 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 2452338 T10977 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 2452339 T10919 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 2452342 T10977 oass.IndexSchema.readSchema [testOverseerCol_shard2_replica1] Schema name=test
   [junit4]   2> 2452346 T10919 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2452348 T10964 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 2452351 T10964 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 2452353 T10964 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 2452358 T10889 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 2452366 T10889 oass.IndexSchema.readSchema [testOverseerCol_shard3_replica2] Schema name=test
   [junit4]   2> 2452371 T10923 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 2452424 T10923 oass.IndexSchema.readSchema [testOverseerCol_shard1_replica1] Schema name=test
   [junit4]   2> 2452522 T10977 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 2452526 T10977 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 2452527 T10977 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 2452545 T10919 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 2452550 T10919 oass.IndexSchema.readSchema [testOverseerCol_shard4_replica2] Schema name=test
   [junit4]   2> 2452592 T10923 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 2452595 T10923 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 2452596 T10923 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 2452614 T10889 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 2452635 T10889 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 2452635 T10889 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 2452655 T10935 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 2452665 T10935 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 2452666 T10946 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 2452673 T10946 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 2452673 T10935 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 2452679 T10935 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 2452680 T10935 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 2452680 T10935 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 2452681 T10946 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 2452681 T10919 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 2452686 T10935 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 2452686 T10935 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 2452687 T10935 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 2452686 T10946 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 2452687 T10946 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 2452688 T10946 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 2452687 T10919

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

  2> 
   [junit4]   2> 2461543 T10927 oasc.LeaderElector$1.process WARN  org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/election
   [junit4]   2> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
   [junit4]   2> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
   [junit4]   2> 	at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1468)
   [junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:256)
   [junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:253)
   [junit4]   2> 	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
   [junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:253)
   [junit4]   2> 	at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:92)
   [junit4]   2> 	at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:55)
   [junit4]   2> 	at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:137)
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:519)
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:495)
   [junit4]   2> 
   [junit4]   2> 2461543 T10927 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
   [junit4]   2> NOTE: test params are: codec=CheapBastard, sim=DefaultSimilarity, locale=ar_BH, timezone=Africa/Ouagadougou
   [junit4]   2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0-ea (32-bit)/cpus=2,threads=1,free=76819896,total=280059904
   [junit4]   2> NOTE: All tests run in this JVM: [TestIndexingPerformance, TestWordDelimiterFilterFactory, TestDistributedGrouping, TestMultiCoreConfBootstrap, ConvertedLegacyTest, JSONWriterTest, CurrencyFieldOpenExchangeTest, TestStressLucene, PluginInfoTest, SolrTestCaseJ4Test, SolrIndexConfigTest, TestSolrDeletionPolicy2, TestAnalyzedSuggestions, TestCodecSupport, SuggesterFSTTest, TestPartialUpdateDeduplication, ChaosMonkeySafeLeaderTest, TestReversedWildcardFilterFactory, DirectUpdateHandlerOptimizeTest, TimeZoneUtilsTest, TestFieldResource, CustomCollectionTest, RequestHandlersTest, TestPHPSerializedResponseWriter, TestRandomMergePolicy, TermsComponentTest, FileUtilsTest, TestSystemIdResolver, TestUpdate, TestFastLRUCache, TestRecoveryHdfs, CachingDirectoryFactoryTest, SortByFunctionTest, SoftAutoCommitTest, HdfsBasicDistributedZkTest, PeerSyncTest, TestSolrDeletionPolicy1, SampleTest, SpellCheckComponentTest, DefaultValueUpdateProcessorTest, TestSurroundQueryParser, AddSchemaFieldsUpdateProcessorFactoryTest, OpenExchangeRatesOrgProviderTest, CoreAdminHandlerTest, CoreContainerCoreInitFailuresTest, TestNonNRTOpen, OverseerCollectionProcessorTest, TestWriterPerf, TestJmxIntegration, TestSolrXmlPersistor, TestUniqueKeyFieldResource, QueryParsingTest, DeleteShardTest, TestClassNameShortening, ZkSolrClientTest, HdfsBasicDistributedZk2Test, TestRecovery, TestFreeTextSuggestions, TestCloudManagedSchemaAddField, SearchHandlerTest, TestZkChroot, SolrXmlInZkTest, TestIndexSearcher, TestLMDirichletSimilarityFactory, XmlUpdateRequestHandlerTest, TestQuerySenderListener, TestGroupingSearch, TestDocumentBuilder, TestRemoteStreaming, TestBM25SimilarityFactory, TestArbitraryIndexDir, SliceStateTest, TestMergePolicyConfig, SynonymTokenizerTest, BJQParserTest, SpellingQueryConverterTest, BasicDistributedZkTest, AddBlockUpdateTest, TestStressUserVersions, ShardRoutingTest, AutoCommitTest, NoFacetTest, TestConfig, IndexSchemaRuntimeFieldTest, SolrCoreTest, DebugComponentTest, TestDistributedMissingSort, TestCursorMarkWithoutUniqueKey, SolrCmdDistributorTest, ShowFileRequestHandlerTest, TestCloudManagedSchema, EnumFieldTest, TestDistribDocBasedVersion, MigrateRouteKeyTest, NotRequiredUniqueKeyTest, ZkControllerTest, DateFieldTest, TestRandomFaceting, TestTrie, TermVectorComponentTest, EchoParamsTest, FieldMutatingUpdateProcessorTest, SyncSliceTest, CollectionsAPIDistributedZkTest, DistribCursorPagingTest, HdfsDirectoryTest, IndexSchemaTest, HardAutoCommitTest, DistributedQueryElevationComponentTest, TestSolrXmlPersistence, TestCoreDiscovery, TestBinaryField, TestBinaryResponseWriter, TestNoOpRegenerator, LukeRequestHandlerTest, TestManagedSchemaFieldResource, CopyFieldTest, DOMUtilTest, FieldAnalysisRequestHandlerTest, TestValueSourceCache, PathHierarchyTokenizerFactoryTest, TestCustomSort, StatelessScriptUpdateProcessorFactoryTest, CacheHeaderTest, TestSolrCoreProperties, BasicFunctionalityTest, DeleteReplicaTest, RequiredFieldsTest, TestFastWriter, TestRangeQuery, NoCacheHeaderTest, CSVRequestHandlerTest, ReturnFieldsTest, SliceStateUpdateTest, SuggesterWFSTTest, TestFunctionQuery, TestSolrQueryParser, ConnectionManagerTest, RAMDirectoryFactoryTest, AlternateDirectoryTest, TestPerFieldSimilarity, TestSimpleQParserPlugin, WordBreakSolrSpellCheckerTest, TestFiltering, BadComponentTest, ScriptEngineTest, TestSolrJ, MinimalSchemaTest, TestQueryUtils, CursorPagingTest, TestQuerySenderNoQuery, TestNumberUtils, TestReplicationHandler, DistributedQueryComponentCustomSortTest, TestNRTOpen, BadIndexSchemaTest, DocValuesMultiTest, HdfsRecoveryZkTest, JsonLoaderTest, TestFaceting, TestReloadAndDeleteDocs, TriLevelCompositeIdRoutingTest, IndexBasedSpellCheckerTest, AnalysisAfterCoreReloadTest, TestDynamicFieldResource, TestSchemaVersionResource, TestManagedSchema, ShardRoutingCustomTest, UpdateRequestProcessorFactoryTest, TestFoldingMultitermQuery, MoreLikeThisHandlerTest, TestSweetSpotSimilarityFactory, BasicDistributedZk2Test, UnloadDistributedZkTest, OpenCloseCoreStressTest, OverseerTest, LeaderElectionIntegrationTest, BasicZkTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest, ClusterStateUpdateTest, LeaderElectionTest, TestRandomDVFaceting, ZkCLITest, TestDistributedSearch, TestHashPartitioner, DistributedSpellCheckComponentTest, TermVectorComponentDistributedTest, TestRealTimeGet, TestStressReorder, TestJoin, TestReload, TestStressVersions, DistributedTermsComponentTest, TestCoreContainer, SimpleFacetsTest, TestSolr4Spatial, StatsComponentTest, QueryElevationComponentTest, TestSort, TestLazyCores, DirectUpdateHandlerTest, TestBadConfig, HighlighterTest, CurrencyFieldXmlFileTest, SolrIndexSplitterTest, SimplePostToolTest, SignatureUpdateProcessorFactoryTest, TestExtendedDismaxParser, SpellCheckCollatorTest, SolrRequestParserTest, DocValuesTest, SuggesterTSTTest, SuggesterTest, SpatialFilterTest, TestCSVLoader, PolyFieldTest, SchemaVersionSpecificBehaviorTest, SolrCoreCheckLockOnStartupTest, TestPseudoReturnFields, TestAtomicUpdateErrorCases, QueryEqualityTest, DistanceFunctionTest, SolrInfoMBeanTest, XsltUpdateRequestHandlerTest, DisMaxRequestHandlerTest, StandardRequestHandlerTest, DirectSolrSpellCheckerTest, DocumentAnalysisRequestHandlerTest, TestQueryTypes, PrimitiveFieldTypeTest, TestOmitPositions, FileBasedSpellCheckerTest, DocumentBuilderTest, FastVectorHighlighterTest, LoggingHandlerTest, RegexBoostProcessorTest, SolrPluginUtilsTest, TestCollationField, TestCSVResponseWriter, MBeansHandlerTest, UniqFieldsUpdateProcessorFactoryTest, BinaryUpdateRequestHandlerTest, PingRequestHandlerTest, TestComponentsName, TestLFUCache, HighlighterConfigTest, SOLR749Test, UpdateParamsTest, TestSolrIndexConfig, ResponseLogComponentTest, TestStressRecovery, MultiTermTest, TestDocSet, TestSearchPerf, NumericFieldsTest, TestElisionMultitermQuery, OutputWriterTest, TestFuzzyAnalyzedSuggestions, ExternalFileFieldSortTest, TestPostingsSolrHighlighter, DirectSolrConnectionTest, TestLuceneMatchVersion, TestPhraseSuggestions, SpellPossibilityIteratorTest, TestCharFilters, TestXIncludeConfig, TestDFRSimilarityFactory, TestLMJelinekMercerSimilarityFactory, TestIBSimilarityFactory, TestDefaultSimilarityFactory, LegacyHTMLStripCharFilterTest, TestJmxMonitoredMap, ResourceLoaderTest, TestFastOutputStream, URLClassifyProcessorTest, DateMathParserTest, ChaosMonkeyNothingIsSafeTest, TestSolrXMLSerializer, PreAnalyzedFieldTest, PrimUtilsTest, TestSuggestSpellingConverter, ClusterStateTest, TestLRUCache, TestUtils, ZkNodePropsTest, SystemInfoHandlerTest, UUIDFieldTest, CircularListTest, TestRTGBase, AbstractAnalyticsStatsTest, ExpressionTest, AbstractAnalyticsFacetTest, FieldFacetExtrasTest, QueryFacetTest, RangeFacetTest, FunctionTest, AliasIntegrationTest, AssignTest, OverseerRolesTest]
   [junit4] Completed in 22.39s, 1 test, 1 error <<< FAILURES!

[...truncated 160 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:453: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:433: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:37: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:189: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:491: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1268: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:901: There were test failures: 365 suites, 1590 tests, 1 error, 40 ignored (12 assumptions)

Total time: 68 minutes 23 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.8.0-ea-b124 -server -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure