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/07 10:14:41 UTC

[JENKINS] Lucene-Solr-trunk-Windows (64bit/jdk1.7.0_51) - Build # 3754 - Failure!

Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/3754/
Java: 64bit/jdk1.7.0_51 -XX:+UseCompressedOops -XX:+UseSerialGC

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

Error Message:
Captured an uncaught exception in thread: Thread[id=9890, name=Overseer-91210648925110288-127.0.0.1:55387_-n_0000000013, state=RUNNABLE, group=Overseer collection creation process.]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=9890, name=Overseer-91210648925110288-127.0.0.1:55387_-n_0000000013, state=RUNNABLE, group=Overseer collection creation process.]
Caused by: org.apache.solr.common.cloud.ZooKeeperException: 
	at __randomizedtesting.SeedInfo.seed([BEF14944A72DA038]:0)
	at org.apache.solr.cloud.OverseerCollectionProcessor.run(OverseerCollectionProcessor.java:209)
	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:174)
	at org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:171)
	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
	at org.apache.solr.common.cloud.SolrZkClient.delete(SolrZkClient.java:171)
	at org.apache.solr.cloud.DistributedQueue.remove(DistributedQueue.java:193)
	at org.apache.solr.cloud.OverseerCollectionProcessor.run(OverseerCollectionProcessor.java:200)
	... 1 more




Build Log:
[...truncated 10920 lines...]
   [junit4] Suite: org.apache.solr.cloud.OverseerRolesTest
   [junit4]   2> 2983400 T9721 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
   [junit4]   2> 2983407 T9721 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-1391764052192
   [junit4]   2> 2983411 T9721 oasc.ZkTestServer.run STARTING ZK TEST SERVER
   [junit4]   2> 2983412 T9722 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
   [junit4]   2> 2983511 T9721 oasc.ZkTestServer.run start zk server on port:55323
   [junit4]   2> 2983515 T9721 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2983524 T9728 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@24043785 name:ZooKeeperConnection Watcher:127.0.0.1:55323 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2983525 T9721 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2983525 T9721 oascc.SolrZkClient.makePath makePath: /solr
   [junit4]   2> 2983537 T9721 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2983540 T9730 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@338abcd9 name:ZooKeeperConnection Watcher:127.0.0.1:55323/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2983541 T9721 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2983541 T9721 oascc.SolrZkClient.makePath makePath: /collections/collection1
   [junit4]   2> 2983549 T9721 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
   [junit4]   2> 2983556 T9721 oascc.SolrZkClient.makePath makePath: /collections/control_collection
   [junit4]   2> 2983562 T9721 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
   [junit4]   2> 2983568 T9721 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> 2983569 T9721 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 2983580 T9721 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> 2983581 T9721 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
   [junit4]   2> 2983587 T9721 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> 2983587 T9721 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 2983595 T9721 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> 2983597 T9721 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 2983604 T9721 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> 2983604 T9721 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
   [junit4]   2> 2983612 T9721 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> 2983612 T9721 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
   [junit4]   2> 2983618 T9721 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> 2983618 T9721 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 2983626 T9721 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> 2983627 T9721 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 2983633 T9721 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> 2983633 T9721 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 2983641 T9721 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> 2983641 T9721 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 2983648 T9721 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> 2983649 T9721 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 2983658 T9723 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1440b9a18ea0001, 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> 2984333 T9721 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 2984340 T9721 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:55330
   [junit4]   2> 2984340 T9721 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 2984340 T9721 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2984340 T9721 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.cloud.OverseerRolesTest-controljetty-1391764052440
   [junit4]   2> 2984340 T9721 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.OverseerRolesTest-controljetty-1391764052440\'
   [junit4]   2> 2984377 T9721 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-controljetty-1391764052440\solr.xml
   [junit4]   2> 2984503 T9721 oasc.ConfigSolrXml.<init> Config-defined core root directory: 
   [junit4]   2> 2984503 T9721 oasc.CoreContainer.<init> New CoreContainer 1021877616
   [junit4]   2> 2984503 T9721 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.cloud.OverseerRolesTest-controljetty-1391764052440\]
   [junit4]   2> 2984508 T9721 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 2984508 T9721 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 2984508 T9721 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 2984508 T9721 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 2984508 T9721 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 2984508 T9721 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 2984508 T9721 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 2984508 T9721 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 2984511 T9721 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 2984512 T9721 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2984512 T9721 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2984512 T9721 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 2984512 T9721 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:55323/solr
   [junit4]   2> 2984512 T9721 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 2984523 T9721 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2984525 T9741 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4bb8434d name:ZooKeeperConnection Watcher:127.0.0.1:55323 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2984525 T9721 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2984530 T9721 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2984534 T9743 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6078c62f name:ZooKeeperConnection Watcher:127.0.0.1:55323/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2984535 T9721 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2984539 T9721 oascc.SolrZkClient.makePath makePath: /overseer/queue
   [junit4]   2> 2984548 T9721 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
   [junit4]   2> 2984567 T9721 oascc.SolrZkClient.makePath makePath: /live_nodes
   [junit4]   2> 2984574 T9721 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55330_
   [junit4]   2> 2984575 T9721 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55330_
   [junit4]   2> 2984579 T9721 oascc.SolrZkClient.makePath makePath: /overseer_elect
   [junit4]   2> 2984583 T9721 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
   [junit4]   2> 2984592 T9721 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:55330_
   [junit4]   2> 2984593 T9721 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
   [junit4]   2> 2984598 T9721 oasc.Overseer.start Overseer (id=91210648925110275-127.0.0.1:55330_-n_0000000000) starting
   [junit4]   2> 2984608 T9721 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
   [junit4]   2> 2984622 T9744 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
   [junit4]   2> 2984623 T9745 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
   [junit4]   2> 2984624 T9721 oascc.SolrZkClient.makePath makePath: /clusterstate.json
   [junit4]   2> 2984625 T9745 oasc.OverseerCollectionProcessor.prioritizeOverseerNodes prioritizing overseer nodes
   [junit4]   2> 2984630 T9721 oascc.SolrZkClient.makePath makePath: /aliases.json
   [junit4]   2> 2984634 T9721 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 2984646 T9721 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-controljetty-1391764052440
   [junit4]   2> 2984956 T9721 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-controljetty-1391764052440\conf\
   [junit4]   2> 2984965 T9721 oasc.CorePropertiesLocator.discover Found 1 core definitions
   [junit4]   2> 2984968 T9721 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 2984968 T9721 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 2984970 T9721 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2984974 T9748 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@67cff2dc name:ZooKeeperConnection Watcher:127.0.0.1:55323/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2984975 T9721 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2984977 T9721 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 2984982 T9721 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 2985553 T9721 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
   [junit4]   2> 2985554 T9721 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 2985557 T9721 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:55342
   [junit4]   2> 2985558 T9721 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 2985558 T9721 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2985558 T9721 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.cloud.OverseerRolesTest-jetty1-1391764053764
   [junit4]   2> 2985559 T9721 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.OverseerRolesTest-jetty1-1391764053764\'
   [junit4]   2> 2985585 T9721 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-jetty1-1391764053764\solr.xml
   [junit4]   2> 2985701 T9721 oasc.ConfigSolrXml.<init> Config-defined core root directory: 
   [junit4]   2> 2985701 T9721 oasc.CoreContainer.<init> New CoreContainer 728105884
   [junit4]   2> 2985701 T9721 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.cloud.OverseerRolesTest-jetty1-1391764053764\]
   [junit4]   2> 2985705 T9721 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 2985705 T9721 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 2985705 T9721 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 2985705 T9721 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 2985705 T9721 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 2985707 T9721 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 2985707 T9721 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 2985707 T9721 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 2985707 T9721 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 2985713 T9721 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2985717 T9721 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2985717 T9721 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 2985718 T9721 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:55323/solr
   [junit4]   2> 2985718 T9721 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 2985721 T9721 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2985735 T9759 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@36770d06 name:ZooKeeperConnection Watcher:127.0.0.1:55323 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2985736 T9721 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2985741 T9721 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2985745 T9761 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@8eb93f2 name:ZooKeeperConnection Watcher:127.0.0.1:55323/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2985746 T9721 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2985755 T9721 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 2986646 T9721 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55342_
   [junit4]   2> 2986648 T9721 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55342_
   [junit4]   2> 2986653 T9761 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 2986654 T9743 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 2986654 T9748 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 2986666 T9721 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-jetty1-1391764053764
   [junit4]   2> 2986974 T9721 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-jetty1-1391764053764\conf\
   [junit4]   2> 2986990 T9721 oasc.CorePropertiesLocator.discover Found 1 core definitions
   [junit4]   2> 2986991 T9721 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 2986992 T9721 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 2987612 T9721 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
   [junit4]   2> 2987612 T9721 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 2987619 T9721 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:55351
   [junit4]   2> 2987620 T9721 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 2987621 T9721 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2987621 T9721 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-jetty2-1391764055774
   [junit4]   2> 2987622 T9721 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-jetty2-1391764055774\'
   [junit4]   2> 2987661 T9721 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-jetty2-1391764055774\solr.xml
   [junit4]   2> 2987764 T9721 oasc.ConfigSolrXml.<init> Config-defined core root directory: 
   [junit4]   2> 2987765 T9721 oasc.CoreContainer.<init> New CoreContainer 753508976
   [junit4]   2> 2987765 T9721 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-jetty2-1391764055774\]
   [junit4]   2> 2987768 T9721 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 2987768 T9721 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 2987769 T9721 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 2987769 T9721 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 2987769 T9721 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 2987770 T9721 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 2987770 T9721 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 2987770 T9721 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 2987770 T9721 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 2987776 T9721 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2987777 T9721 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2987777 T9721 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 2987777 T9721 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:55323/solr
   [junit4]   2> 2987778 T9721 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 2987780 T9721 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2987787 T9773 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1ea77e19 name:ZooKeeperConnection Watcher:127.0.0.1:55323 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2987788 T9721 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2987792 T9721 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2987795 T9775 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@14ca6093 name:ZooKeeperConnection Watcher:127.0.0.1:55323/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2987795 T9721 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2987802 T9721 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 2988692 T9721 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55351_
   [junit4]   2> 2988699 T9721 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55351_
   [junit4]   2> 2988705 T9743 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 2988705 T9761 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 2988705 T9748 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 2988705 T9775 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 2988716 T9721 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-jetty2-1391764055774
   [junit4]   2> 2988933 T9721 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-jetty2-1391764055774\conf\
   [junit4]   2> 2988943 T9721 oasc.CorePropertiesLocator.discover Found 1 core definitions
   [junit4]   2> 2988944 T9721 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 2988944 T9721 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 2989447 T9721 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3
   [junit4]   2> 2989448 T9721 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 2989454 T9721 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:55360
   [junit4]   2> 2989455 T9721 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 2989455 T9721 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2989455 T9721 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.cloud.OverseerRolesTest-jetty3-1391764057727
   [junit4]   2> 2989455 T9721 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.OverseerRolesTest-jetty3-1391764057727\'
   [junit4]   2> 2989488 T9721 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-jetty3-1391764057727\solr.xml
   [junit4]   2> 2989577 T9721 oasc.ConfigSolrXml.<init> Config-defined core root directory: 
   [junit4]   2> 2989577 T9721 oasc.CoreContainer.<init> New CoreContainer 171251919
   [junit4]   2> 2989577 T9721 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.cloud.OverseerRolesTest-jetty3-1391764057727\]
   [junit4]   2> 2989579 T9721 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 2989579 T9721 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 2989579 T9721 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 2989579 T9721 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 2989579 T9721 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 2989579 T9721 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 2989579 T9721 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 2989580 T9721 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 2989581 T9721 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 2989583 T9721 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2989583 T9721 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2989583 T9721 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 2989585 T9721 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:55323/solr
   [junit4]   2> 2989586 T9721 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 2989588 T9721 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2989599 T9787 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@23efc345 name:ZooKeeperConnection Watcher:127.0.0.1:55323 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2989599 T9721 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2989603 T9721 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2989607 T9789 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@8df906a name:ZooKeeperConnection Watcher:127.0.0.1:55323/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2989608 T9721 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2989614 T9721 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 2990506 T9721 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55360_
   [junit4]   2> 2990507 T9721 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55360_
   [junit4]   2> 2990512 T9761 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 2990513 T9748 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 2990513 T9789 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 2990513 T9743 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 2990514 T9775 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 2990525 T9721 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-jetty3-1391764057727
   [junit4]   2> 2990734 T9721 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-jetty3-1391764057727\conf\
   [junit4]   2> 2990743 T9721 oasc.CorePropertiesLocator.discover Found 1 core definitions
   [junit4]   2> 2990745 T9721 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 2990745 T9721 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 2991228 T9721 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 4
   [junit4]   2> 2991229 T9721 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 2991232 T9721 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:55369
   [junit4]   2> 2991233 T9721 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 2991233 T9721 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2991233 T9721 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.cloud.OverseerRolesTest-jetty4-1391764059528
   [junit4]   2> 2991233 T9721 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.OverseerRolesTest-jetty4-1391764059528\'
   [junit4]   2> 2991256 T9721 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-jetty4-1391764059528\solr.xml
   [junit4]   2> 2991326 T9721 oasc.ConfigSolrXml.<init> Config-defined core root directory: 
   [junit4]   2> 2991326 T9721 oasc.CoreContainer.<init> New CoreContainer 1285202291
   [junit4]   2> 2991326 T9721 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.cloud.OverseerRolesTest-jetty4-1391764059528\]
   [junit4]   2> 2991329 T9721 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 2991329 T9721 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 2991329 T9721 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 2991329 T9721 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 2991329 T9721 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 2991329 T9721 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 2991329 T9721 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 2991329 T9721 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 2991329 T9721 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 2991334 T9721 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2991334 T9721 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2991334 T9721 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 2991334 T9721 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:55323/solr
   [junit4]   2> 2991336 T9721 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 2991338 T9721 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2991346 T9801 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3bddac4f name:ZooKeeperConnection Watcher:127.0.0.1:55323 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2991346 T9721 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2991349 T9723 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1440b9a18ea000b, 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> 2991350 T9721 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2991353 T9803 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1f4e4662 name:ZooKeeperConnection Watcher:127.0.0.1:55323/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2991353 T9721 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2991358 T9721 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 2992249 T9721 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55369_
   [junit4]   2> 2992259 T9721 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55369_
   [junit4]   2> 2992264 T9803 oascc.ZkStateReader$3.process Updating live nodes... (5)
   [junit4]   2> 2992264 T9775 oascc.ZkStateReader$3.process Updating live nodes... (5)
   [junit4]   2> 2992265 T9761 oascc.ZkStateReader$3.process Updating live nodes... (5)
   [junit4]   2> 2992265 T9789 oascc.ZkStateReader$3.process Updating live nodes... (5)
   [junit4]   2> 2992265 T9743 oascc.ZkStateReader$3.process Updating live nodes... (5)
   [junit4]   2> 2992266 T9748 oascc.ZkStateReader$3.process Updating live nodes... (5)
   [junit4]   2> 2992275 T9721 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-jetty4-1391764059528
   [junit4]   2> 2992578 T9721 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-jetty4-1391764059528\conf\
   [junit4]   2> 2992588 T9721 oasc.CorePropertiesLocator.discover Found 1 core definitions
   [junit4]   2> 2992590 T9721 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 2992590 T9721 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 2993114 T9721 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 5
   [junit4]   2> 2993115 T9721 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 2993121 T9721 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:55378
   [junit4]   2> 2993122 T9721 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 2993122 T9721 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2993123 T9721 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-jetty5-1391764061373
   [junit4]   2> 2993123 T9721 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-jetty5-1391764061373\'
   [junit4]   2> 2993163 T9721 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-jetty5-1391764061373\solr.xml
   [junit4]   2> 2993297 T9721 oasc.ConfigSolrXml.<init> Config-defined core root directory: 
   [junit4]   2> 2993298 T9721 oasc.CoreContainer.<init> New CoreContainer 19067384
   [junit4]   2> 2993298 T9721 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-jetty5-1391764061373\]
   [junit4]   2> 2993300 T9721 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 2993301 T9721 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 2993301 T9721 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 2993301 T9721 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 2993301 T9721 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 2993302 T9721 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 2993302 T9721 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 2993302 T9721 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 2993303 T9721 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 2993309 T9721 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2993310 T9721 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2993310 T9721 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 2993311 T9721 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:55323/solr
   [junit4]   2> 2993311 T9721 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 2993313 T9721 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2993338 T9815 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1d3bd15d name:ZooKeeperConnection Watcher:127.0.0.1:55323 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2993338 T9721 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2993344 T9721 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2993348 T9817 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@35bf7f3d name:ZooKeeperConnection Watcher:127.0.0.1:55323/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2993349 T9721 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2993359 T9721 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 2994251 T9721 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55378_
   [junit4]   2> 2994254 T9721 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55378_
   [junit4]   2> 2994260 T9748 oascc.ZkStateReader$3.process Updating live nodes... (6)
   [junit4]   2> 2994261 T9761 oascc.ZkStateReader$3.process Updating live nodes... (6)
   [junit4]   2> 2994261 T9789 oascc.ZkStateReader$3.process Updating live nodes... (6)
   [junit4]   2> 2994261 T9803 oascc.ZkStateReader$3.process Updating live nodes... (6)
   [junit4]   2> 2994261 T9775 oascc.ZkStateReader$3.process Updating live nodes... (6)
   [junit4]   2> 2994262 T9743 oascc.ZkStateReader$3.process Updating live nodes... (6)
   [junit4]   2> 2994262 T9817 oascc.ZkStateReader$3.process Updating live nodes... (6)
   [junit4]   2> 2994273 T9721 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-jetty5-1391764061373
   [junit4]   2> 2994537 T9721 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-jetty5-1391764061373\conf\
   [junit4]   2> 2994546 T9721 oasc.CorePropertiesLocator.discover Found 1 core definitions
   [junit4]   2> 2994548 T9721 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 2994548 T9721 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 2995041 T9721 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 6
   [junit4]   2> 2995042 T9721 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 2995046 T9721 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:55387
   [junit4]   2> 2995046 T9721 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 2995046 T9721 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2995046 T9721 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-1391764063330
   [junit4]   2> 2995047 T9721 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-1391764063330\'
   [junit4]   2> 2995074 T9721 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-1391764063330\solr.xml
   [junit4]   2> 2995169 T9721 oasc.ConfigSolrXml.<init> Config-defined core root directory: 
   [junit4]   2> 2995169 T9721 oasc.CoreContainer.<init> New CoreContainer 1565702328
   [junit4]   2> 2995169 T9721 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-1391764063330\]
   [junit4]   2> 2995172 T9721 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 2995172 T9721 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 2995172 T9721 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 2995172 T9721 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 2995173 T9721 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 2995173 T9721 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 2995173 T9721 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 2995174 T9721 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 2995174 T9721 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 2995179 T9721 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2995180 T9721 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2995180 T9721 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 2995181 T9721 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:55323/solr
   [junit4]   2> 2995181 T9721 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 2995183 T9721 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2995196 T9829 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@61dd67e5 name:ZooKeeperConnection Watcher:127.0.0.1:55323 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2995197 T9721 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2995203 T9723 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1440b9a18ea000f, 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> 2995207 T9721 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2995208 T9831 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@29d01c4 name:ZooKeeperConnection Watcher:127.0.0.1:55323/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2995208 T9721 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2995213 T9721 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 2996015 T9721 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55387_
   [junit4]   2> 2996016 T9721 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55387_
   [junit4]   2> 2996022 T9789 oascc.ZkStateReader$3.process Updating live nodes... (7)
   [junit4]   2> 2996023 T9831 oascc.ZkStateReader$3.process Updating live nodes... (7)
   [junit4]   2> 2996023 T9817 oascc.ZkStateReader$3.process Updating live nodes... (7)
   [junit4]   2> 2996023 T9761 oascc.ZkStateReader$3.process Updating live nodes... (7)
   [junit4]   2> 2996023 T9775 oascc.ZkStateReader$3.process Updating live nodes... (7)
   [junit4]   2> 2996023 T9803 oascc.ZkStateReader$3.process Updating live nodes... (7)
   [junit4]   2> 2996024 T9748 oascc.ZkStateReader$3.process Updating live nodes... (7)
   [junit4]   2> 2996023 T9743 oascc.ZkStateReader$3.process Updating live nodes... (7)
   [junit4]   2> 2996033 T9721 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-1391764063330
   [junit4]   2> 2996246 T9721 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-1391764063330\conf\
   [junit4]   2> 2996261 T9721 oasc.CorePropertiesLocator.discover Found 1 core definitions
   [junit4]   2> 2996263 T9721 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 2996263 T9721 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 2996265 T9721 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2996268 T9834 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6de55c0c name:ZooKeeperConnection Watcher:127.0.0.1:55323/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2996269 T9721 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2996270 T9721 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 2996276 T9721 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 2996283 T9836 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4e02facb name:ZooKeeperConnection Watcher:127.0.0.1:55323/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2996283 T9721 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 2996285 T9721 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 2996290 T9824 oasha.CollectionsHandler.handleCreateAction Creating Collection : numShards=4&maxShardsPerNode=2&replicationFactor=2&version=2&action=CREATE&wt=javabin&name=testOverseerCol
   [junit4]   2> 2996294 T9743 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/collection-queue-work state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2996297 T9745 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> 2996297 T9745 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> 2996297 T9745 oasc.OverseerCollectionProcessor.createConfNode Only one config set found in zk - using it:conf1
   [junit4]   2> 2996298 T9745 oasc.OverseerCollectionProcessor.createConfNode creating collections conf node /collections/testOverseerCol 
   [junit4]   2> 2996298 T9745 oascc.SolrZkClient.makePath makePath: /collections/testOverseerCol
   [junit4]   2> 2996307 T9743 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2996309 T9744 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 2996310 T9744 oasc.Overseer$ClusterStateUpdater.buildCollection building a new collection: testOverseerCol
   [junit4]   2> 2996310 T9744 oasc.Overseer$ClusterStateUpdater.createCollection Create collection testOverseerCol with shards [shard1, shard2, shard3, shard4]
   [junit4]   2> 2996315 T9743 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2996317 T9836 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> 2996318 T9789 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> 2996318 T9834 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> 2996318 T9761 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> 2996319 T9743 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> 2996318 T9748 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> 2996318 T9817 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> 2996318 T9831 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> 2996321 T9803 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> 2996321 T9775 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> 2996387 T9745 oasc.OverseerCollectionProcessor.createCollection going to create cores replicas shardNames [shard1, shard2, shard3, shard4] , repFactor : 2
   [junit4]   2> 2996387 T9745 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerCol_shard1_replica1 as part of slice shard1 of collection testOverseerCol on 127.0.0.1:55360_
   [junit4]   2> 2996388 T9745 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerCol_shard1_replica2 as part of slice shard1 of collection testOverseerCol on 127.0.0.1:55369_
   [junit4]   2> 2996388 T9745 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerCol_shard2_replica1 as part of slice shard2 of collection testOverseerCol on 127.0.0.1:55387_
   [junit4]   2> 2996391 T9745 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerCol_shard2_replica2 as part of slice shard2 of collection testOverseerCol on 127.0.0.1:55330_
   [junit4]   2> 2996395 T9784 oasc.ZkController.publish publishing core=testOverseerCol_shard1_replica1 state=down
   [junit4]   2> 2996397 T9745 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerCol_shard3_replica1 as part of slice shard3 of collection testOverseerCol on 127.0.0.1:55378_
   [junit4]   2> 2996398 T9825 oasc.ZkController.publish publishing core=testOverseerCol_shard2_replica1 state=down
   [junit4]   2> 2996398 T9745 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerCol_shard3_replica2 as part of slice shard3 of collection testOverseerCol on 127.0.0.1:55351_
   [junit4]   2> 2996397 T9794 oasc.ZkController.publish publishing core=testOverseerCol_shard1_replica2 state=down
   [junit4]   2> 2996400 T9745 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerCol_shard4_replica1 as part of slice shard4 of collection testOverseerCol on 127.0.0.1:55342_
   [junit4]   2> 2996401 T9784 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 2996401 T9743 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2996403 T9745 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerCol_shard4_replica2 as part of slice shard4 of collection testOverseerCol on 127.0.0.1:55360_
   [junit4]   2> 2996406 T9794 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 2996407 T9739 oasc.ZkController.publish publishing core=testOverseerCol_shard2_replica2 state=down
   [junit4]   2> 2996406 T9825 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 2996413 T9812 oasc.ZkController.publish publishing core=testOverseerCol_shard3_replica1 state=down
   [junit4]   2> 2996417 T9756 oasc.ZkController.publish publishing core=testOverseerCol_shard4_replica1 state=down
   [junit4]   2> 2996417 T9766 oasc.ZkController.publish publishing core=testOverseerCol_shard3_replica2 state=down
   [junit4]   2> 2996414 T9744 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 2996420 T9785 oasc.ZkController.publish publishing core=testOverseerCol_shard4_replica2 state=down
   [junit4]   2> 2996413 T9739 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 2996413 T9743 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2996427 T9812 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 2996428 T9756 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 2996428 T9766 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 2996428 T9744 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:55360",
   [junit4]   2> 	  "core":"testOverseerCol_shard1_replica1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:55360_",
   [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> 2996429 T9785 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 2996434 T9744 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:55387",
   [junit4]   2> 	  "core":"testOverseerCol_shard2_replica1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:55387_",
   [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> 2996438 T9743 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2996440 T9744 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:55369",
   [junit4]   2> 	  "core":"testOverseerCol_shard1_replica2",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:55369_",
   [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> 2996444 T9743 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2996445 T9744 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:55330",
   [junit4]   2> 	  "core":"testOverseerCol_shard2_replica2",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:55330_",
   [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> 2996449 T9743 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2996451 T9744 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:55378",
   [junit4]   2> 	  "core":"testOverseerCol_shard3_replica1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:55378_",
   [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> 2996455 T9743 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2996456 T9744 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:55342",
   [junit4]   2> 	  "core":"testOverseerCol_shard4_replica1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:55342_",
   [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> 2996460 T9743 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2996462 T9744 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:55351",
   [junit4]   2> 	  "core":"testOverseerCol_shard3_replica2",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:55351_",
   [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> 2996466 T9743 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2996467 T9744 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:55360",
   [junit4]   2> 	  "core":"testOverseerCol_shard4_replica2",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:55360_",
   [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> 2996471 T9743 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 2996554 T9775 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> 2996555 T9803 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> 2996556 T9743 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> 2996556 T9789 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> 2996556 T9834 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> 2996557 T9761 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> 2996557 T9817 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> 2996557 T9831 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> 2996557 T9836 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> 2996557 T9748 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> 2997199 T9784 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerCol_shard1_replica1
   [junit4]   2> 2997202 T9784 oasc.CoreContainer.create Creating SolrCore 'testOverseerCol_shard1_replica1' using instanceDir: .\org.apache.solr.cloud.OverseerRolesTest-jetty3-1391764057727\testOverseerCol_shard1_replica1
   [junit4]   2> 2997202 T9784 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerCol
   [junit4]   2> 2997204 T9794 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerCol_shard1_replica2
   [junit4]   2> 2997204 T9784 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 2997205 T9784 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerCol
   [junit4]   2> 2997206 T9825 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerCol_shard2_replica1
   [junit4]   2> 2997207 T9784 oascc.ZkStateReader.readConfigName path=/collections/testOverseerCol configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 2997207 T9784 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.OverseerRolesTest-jetty3-1391764057727\testOverseerCol_shard1_replica1\'
   [junit4]   2> 2997207 T9825 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-1391764063330\testOverseerCol_shard2_replica1
   [junit4]   2> 2997207 T9825 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerCol
   [junit4]   2> 2997211 T9825 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 2997211 T9825 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerCol
   [junit4]   2> 2997213 T9825 oascc.ZkStateReader.readConfigName path=/collections/testOverseerCol configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 2997213 T9825 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-1391764063330\testOverseerCol_shard2_replica1\'
   [junit4]   2> 2997214 T9794 oasc.CoreContainer.create Creating SolrCore 'testOverseerCol_shard1_replica2' using instanceDir: .\org.apache.solr.cloud.OverseerRolesTest-jetty4-1391764059528\testOverseerCol_shard1_replica2
   [junit4]   2> 2997214 T9794 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerCol
   [junit4]   2> 2997217 T9794 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 2997217 T9794 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerCol
   [junit4]   2> 2997218 T9739 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerCol_shard2_replica2
   [junit4]   2> 2997221 T9739 oasc.CoreContainer.create Creating SolrCore 'testOverseerCol_shard2_replica2' using instanceDir: .\org.apache.solr.cloud.OverseerRolesTest-controljetty-1391764052440\testOverseerCol_shard2_replica2
   [junit4]   2> 2997221 T9739 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerCol
   [junit4]   2> 2997227 T9785 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerCol_shard4_replica2
   [junit4]   2> 2997236 T9739 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 2997236 T9739 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerCol
   [junit4]   2> 2997237 T9785 oasc.CoreContainer.create Creating SolrCore 'testOverseerCol_shard4_replica2' using instanceDir: .\org.apache.solr.cloud.OverseerRolesTest-jetty3-1391764057727\testOverseerCol_shard4_replica2
   [junit4]   2> 2997237 T9785 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerCol
   [junit4]   2> 2997238 T9812 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerCol_shard3_replica1
   [junit4]   2> 2997239 T9812 oasc.CoreContainer.create Creating SolrCore 'testOverseerCol_shard3_replica1' using instanceDir: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty5-1391764061373\testOverseerCol_shard3_replica1
   [junit4]   2> 2997239 T9812 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerCol
   [junit4]   2> 2997240 T9756 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerCol_shard4_replica1
   [junit4]   2> 2997241 T9766 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerCol_shard3_replica2
   [junit4]   2> 2997242 T9794 oascc.ZkStateReader.readConfigName path=/collections/testOverseerCol configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 2997243 T9794 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.OverseerRolesTest-jetty4-1391764059528\testOverseerCol_shard1_replica2\'
   [junit4]   2> 2997246 T9756 oasc.CoreContainer.create Creating SolrCore 'testOverseerCol_shard4_replica1' using instanceDir: .\org.apache.solr.cloud.OverseerRolesTest-jetty1-1391764053764\testOverseerCol_shard4_replica1
   [junit4]   2> 2997246 T9756 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerCol
   [junit4]   2> 2997253 T9785 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 2997254 T9785 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerCol
   [junit4]   2> 2997255 T9812 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 2997255 T9812 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerCol
   [junit4]   2> 2997257 T9756 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 2997257 T9756 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerCol
   [junit4]   2> 2997263 T9739 oascc.ZkStateReader.readConfigName path=/collections/testOverseerCol configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 2997264 T9739 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.OverseerRolesTest-controljetty-1391764052440\testOverseerCol_shard2_replica2\'
   [junit4]   2> 2997276 T9812 oascc.ZkStateReader.readConfigName path=/collections/testOverseerCol configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 2997277 T9812 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-jetty5-1391764061373\testOverseerCol_shard3_replica1\'
   [junit4]   2> 2997280 T9785 oascc.ZkStateReader.readConfigName path=/collections/testOverseerCol configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 2997281 T9785 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.OverseerRolesTest-jetty3-1391764057727\testOverseerCol_shard4_replica2\'
   [junit4]   2> 2997281 T9756 oascc.ZkStateReader.readConfigName path=/collections/testOverseerCol configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 2997281 T9756 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.OverseerRolesTest-jetty1-1391764053764\testOverseerCol_shard4_replica1\'
   [junit4]   2> 2997358 T9825 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 2997376 T9766 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-jetty2-1391764055774\testOverseerCol_shard3_replica2
   [junit4]   2> 2997376 T9766 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerCol
   [junit4]   2> 2997395 T9784 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 2997431 T9825 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2997479 T9739 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 2997496 T9784 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2997504 T9766 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 2997504 T9766 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerCol
   [junit4]   2> 2997542 T9739 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2997546 T9825 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 2997555 T9825 oass.IndexSchema.readSchema [testOverseerCol_shard2_replica1] Schema name=test
   [junit4]   2> 2997557 T9784 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 2997559 T9766 oascc.ZkStateReader.readConfigName path=/collections/testOverseerCol configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 2997559 T9766 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-jetty2-1391764055774\testOverseerCol_shard3_replica2\'
   [junit4]   2> 2997559 T9739 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 2997563 T9794 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 2997568 T9739 oass.IndexSchema.readSchema [testOverseerCol_shard2_replica2] Schema name=test
   [junit4]   2> 2997615 T9784 oass.IndexSchema.readSchema [testOverseerCol_shard1_replica1] Schema name=test
   [junit4]   2> 2997847 T9785 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 2997853 T9756 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 2997884 T9812 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 2998081 T9812 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2998153 T9766 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 2998158 T9812 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 2998161 T9794 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2998167 T9785 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2998168 T9812 oass.IndexSchema.readSchema [testOverseerCol_shard3_replica1] Schema name=test
   [junit4]   2> 2998225 T9794 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 2998227 T9785 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 2998251 T9785 oass.IndexSchema.readSchema [testOverseerCol_shard4_replica2] Schema name=test
   [junit4]   2> 2998257 T9794 oass.IndexSchema.readSchema [testOverseerCol_shard1_replica2] Schema name=test
   [junit4]   2> 2998497 T9766 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2998504 T9766 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 2998514 T9766 oass.IndexSchema.readSchema [testOverseerCol_shard3_replica2] Schema name=test
   [junit4]   2> 2998805 T9756 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2998811 T9756 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 2998832 T9756 oass.IndexSchema.readSchema [testOverseerCol_shard4_replica1] Schema name=test
   [junit4]   2> 2999718 T9739 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 2999732 T9739 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 2999738 T9739 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 2999928 T9825 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 2999984 T9739 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 2999987 T9825 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 2999992 T9825 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 3000029 T9739 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 3000049 T9825 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 3000059 T9739 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 3000064 T9825 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 3000065 T9739 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 3000065 T9739 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 3000065 T9739 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 3000072 T9739 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 3000072 T9739 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 3000072 T9739 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 3000074 T9739 oasc.SolrCore.<init> [testOverseerCol_shard2_replica2] Opening new SolrCore at .\org.apache.solr.cloud.OverseerRolesTest-controljetty-1391764052440\testOverseerCol_shard2_replica2\, dataDir=.\org.apache.solr.cloud.OverseerRolesTest-controljetty-1391764052440\testOverseerCol_shard2_replica2\data\
   [junit4]   2> 3000074 T9739 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@191f74ef
   [junit4]   2> 3000075 T9739 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.OverseerRolesTest-controljetty-1391764052440\testOverseerCol_shard2_replica2\data\
   [junit4]   2> 3000075 T9739 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.OverseerRolesTest-controljetty-1391764052440\testOverseerCol_shard2_replica2\data\index/
   [junit4]   2> 3000075 T9739 oasc.SolrCore.initIndex WARN [testOverseerCol_shard2_replica2] Solr index directory '.\org.apache.solr.cloud.OverseerRolesTest-controljetty-1391764052440\testOverseerCol_shard2_replica2\data\index' doesn't exist. Creating new index...
   [junit4]   2> 3000077 T9825 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 3000080 T9739 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.OverseerRolesTest-controljetty-1391764052440\testOverseerCol_shard2_replica2\data\index
   [junit4]   2> 3000081 T9739 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=41, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.593700766039534]
   [junit4]   2> 3000082 T9739 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@492e92ca lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@38cc16ba),segFN=segments_1,generation=1}
   [junit4]   2> 3000083 T9739 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 3000084 T9825 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 3000084 T9825 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 3000085 T9825 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 3000089 T9825 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 3000089 T9825 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 3000090 T9825 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 3000091 T9825 oasc.SolrCore.<init> [testOverseerCol_shard2_replica1] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty6-1391764063330\testOverseerCol_shard2_replica1\, dataDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty6-1391764063330\testOverseerCol_shard2_replica1\data\
   [junit4]   2> 3000091 T9825 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@191f74ef
   [junit4]   2> 3000093 T9739 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 3000095 T9739 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 3000095 T9739 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 3000096 T9739 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 3000096 T9739 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 3000096 T9739 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 3000097 T9739 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 3000099 T9739 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 3000099 T9739 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 3000100 T9739 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 3000100 T9739 oasc.RequestHandlers.initHandler

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

rException$NoNodeException: KeeperErrorCode = NoNode for /overseer/collection-queue-work/qn-0000000004
   [junit4]    > 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:111)
   [junit4]    > 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
   [junit4]    > 	at org.apache.zookeeper.ZooKeeper.delete(ZooKeeper.java:873)
   [junit4]    > 	at org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:174)
   [junit4]    > 	at org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:171)
   [junit4]    > 	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
   [junit4]    > 	at org.apache.solr.common.cloud.SolrZkClient.delete(SolrZkClient.java:171)
   [junit4]    > 	at org.apache.solr.cloud.DistributedQueue.remove(DistributedQueue.java:193)
   [junit4]    > 	at org.apache.solr.cloud.OverseerCollectionProcessor.run(OverseerCollectionProcessor.java:200)
   [junit4]    > 	... 1 more
   [junit4]   2> 3017744 T9721 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> 34347 T9720 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s).
   [junit4]   2> NOTE: test params are: codec=CheapBastard, sim=DefaultSimilarity, locale=ca, timezone=Pacific/Norfolk
   [junit4]   2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.7.0_51 (64-bit)/cpus=2,threads=2,free=151832232,total=292757504
   [junit4]   2> NOTE: All tests run in this JVM: [DistributedTermsComponentTest, DocValuesMultiTest, TestDFRSimilarityFactory, TestMaxScoreQueryParser, RequestHandlersTest, EnumFieldTest, TestAnalyzeInfixSuggestions, BJQParserTest, TestCursorMarkWithoutUniqueKey, BasicDistributedZkTest, TestSearchPerf, TestCoreContainer, SchemaVersionSpecificBehaviorTest, DirectSolrConnectionTest, CursorPagingTest, TestStressUserVersions, SimpleFacetsTest, HighlighterConfigTest, TestWriterPerf, ZkControllerTest, BinaryUpdateRequestHandlerTest, TestCSVLoader, PreAnalyzedFieldTest, CSVRequestHandlerTest, DistributedDebugComponentTest, ZkNodePropsTest, TestSolrDeletionPolicy2, TestLuceneMatchVersion, TestPerFieldSimilarity, AssignTest, ResponseLogComponentTest, TestElisionMultitermQuery, TestSolrIndexConfig, TestPseudoReturnFields, DistributedQueryComponentCustomSortTest, TestSolr4Spatial, InfoHandlerTest, TestCSVResponseWriter, SuggestComponentTest, PluginInfoTest, DocValuesMissingTest, TestDocSet, TestStressRecovery, TestStressReorder, MoreLikeThisHandlerTest, SpatialFilterTest, StandardRequestHandlerTest, TestCharFilters, TestCloudManagedSchemaAddField, XsltUpdateRequestHandlerTest, TestGroupingSearch, SuggesterWFSTTest, UniqFieldsUpdateProcessorFactoryTest, TestSolrXml, QueryEqualityTest, TestManagedSchemaFieldResource, TestPhraseSuggestions, ScriptEngineTest, ShowFileRequestHandlerTest, OverseerCollectionProcessorTest, TestManagedSchema, TestComponentsName, SpellPossibilityIteratorTest, DirectUpdateHandlerOptimizeTest, DistributedSuggestComponentTest, SliceStateUpdateTest, PrimitiveFieldTypeTest, HdfsUnloadDistributedZkTest, TestSuggestSpellingConverter, TestIndexingPerformance, SimplePostToolTest, TestSchemaSimilarityResource, HdfsLockFactoryTest, ChaosMonkeyNothingIsSafeTest, TestDynamicFieldResource, DistanceFunctionTest, ZkSolrClientTest, TestRangeQuery, ExpressionTest, DocumentAnalysisRequestHandlerTest, CursorMarkTest, TestQueryTypes, TestNumberUtils, RequiredFieldsTest, SpellCheckCollatorTest, SynonymTokenizerTest, TestUniqueKeyFieldResource, ShardRoutingTest, SpellCheckComponentTest, PeerSyncTest, ModifyConfFileTest, TestAtomicUpdateErrorCases, TestUtils, TestLMDirichletSimilarityFactory, TestFreeTextSuggestions, TestLFUCache, HdfsRecoveryZkTest, TestFieldTypeResource, TestFiltering, ExternalFileFieldSortTest, TestLazyCores, TestFileDictionaryLookup, DateMathParserTest, TestDynamicFieldCollectionResource, DeleteReplicaTest, AliasIntegrationTest, TestSolrXMLSerializer, FullSolrCloudDistribCmdsTest, MBeansHandlerTest, TestPartialUpdateDeduplication, TestFastWriter, TestInfoStreamLogging, TestSerializedLuceneMatchVersion, ReturnFieldsTest, TestJmxMonitoredMap, TestCopyFieldCollectionResource, TestRandomFaceting, TestQuerySenderListener, TestDistributedGrouping, OverseerTest, TestCloudManagedSchema, TestFieldResource, RAMDirectoryFactoryTest, SolrInfoMBeanTest, AutoCommitTest, CoreMergeIndexesAdminHandlerTest, TestFaceting, PrimUtilsTest, DirectUpdateHandlerTest, TestOmitPositions, SyncSliceTest, SolrRequestParserTest, HighlighterTest, MinimalSchemaTest, SolrCoreCheckLockOnStartupTest, DocValuesTest, TestIndexSearcher, IndexBasedSpellCheckerTest, ChaosMonkeySafeLeaderTest, TestSimpleQParserPlugin, CurrencyFieldXmlFileTest, PolyFieldTest, SuggesterTSTTest, TestHashPartitioner, TestAddFieldRealTimeGet, DOMUtilTest, TestConfig, DeleteShardTest, BasicFunctionalityTest, TestDocumentBuilder, TestDistribDocBasedVersion, TestFuzzyAnalyzedSuggestions, TestDocBasedVersionConstraints, TestFieldCollectionResource, ClusterStateUpdateTest, TestIBSimilarityFactory, TestXIncludeConfig, TestReloadAndDeleteDocs, TestSweetSpotSimilarityFactory, StatelessScriptUpdateProcessorFactoryTest, TestStressLucene, TestRandomMergePolicy, ConnectionManagerTest, FileBasedSpellCheckerTest, ConvertedLegacyTest, UnloadDistributedZkTest, TestBinaryField, TestCollationFieldDocValues, SolrCmdDistributorTest, TestFieldTypeCollectionResource, SystemInfoHandlerTest, SOLR749Test, BasicDistributedZk2Test, CollectionsAPIDistributedZkTest, OpenCloseCoreStressTest, LeaderElectionIntegrationTest, BasicZkTest, RecoveryZkTest, TestReplicationHandler, LeaderElectionTest, TestZkChroot, TestRandomDVFaceting, ZkCLITest, ShardRoutingCustomTest, TestDistributedSearch, TestRecovery, DistributedSpellCheckComponentTest, TermVectorComponentDistributedTest, TestRealTimeGet, TestJoin, TestMultiCoreConfBootstrap, TestReload, TestStressVersions, HardAutoCommitTest, SolrCoreTest, StatsComponentTest, QueryElevationComponentTest, BadIndexSchemaTest, TestSort, TestFunctionQuery, TestBadConfig, SoftAutoCommitTest, CurrencyFieldOpenExchangeTest, DistributedQueryElevationComponentTest, SolrIndexSplitterTest, TestCoreDiscovery, AnalysisAfterCoreReloadTest, SignatureUpdateProcessorFactoryTest, TestExtendedDismaxParser, SuggesterFSTTest, CoreAdminHandlerTest, TestFoldingMultitermQuery, SuggesterTest, TestTrie, NoCacheHeaderTest, WordBreakSolrSpellCheckerTest, TestUpdate, FieldMutatingUpdateProcessorTest, TestWordDelimiterFilterFactory, DefaultValueUpdateProcessorTest, SortByFunctionTest, TestRemoteStreaming, TestSolrDeletionPolicy1, DebugComponentTest, CacheHeaderTest, TestSurroundQueryParser, LukeRequestHandlerTest, DisMaxRequestHandlerTest, TestQueryUtils, DirectSolrSpellCheckerTest, TestReversedWildcardFilterFactory, TermsComponentTest, XmlUpdateRequestHandlerTest, DocumentBuilderTest, TestValueSourceCache, PathHierarchyTokenizerFactoryTest, TermVectorComponentTest, FieldAnalysisRequestHandlerTest, TestSolrQueryParser, TestArbitraryIndexDir, FastVectorHighlighterTest, IndexSchemaRuntimeFieldTest, LoggingHandlerTest, RegexBoostProcessorTest, SolrPluginUtilsTest, IndexSchemaTest, TestJmxIntegration, TestCollationField, UpdateRequestProcessorFactoryTest, JSONWriterTest, QueryParsingTest, TestAnalyzedSuggestions, JsonLoaderTest, TestPHPSerializedResponseWriter, PingRequestHandlerTest, SearchHandlerTest, CoreContainerCoreInitFailuresTest, TestBinaryResponseWriter, UpdateParamsTest, AlternateDirectoryTest, TestQuerySenderNoQuery, CopyFieldTest, SolrIndexConfigTest, BadComponentTest, TestMergePolicyConfig, MultiTermTest, SampleTest, NumericFieldsTest, OutputWriterTest, TestSolrCoreProperties, TestPostingsSolrHighlighter, NotRequiredUniqueKeyTest, TestCodecSupport, EchoParamsTest, TestLMJelinekMercerSimilarityFactory, TestBM25SimilarityFactory, TestDefaultSimilarityFactory, LegacyHTMLStripCharFilterTest, TimeZoneUtilsTest, ResourceLoaderTest, TestFastOutputStream, OpenExchangeRatesOrgProviderTest, URLClassifyProcessorTest, TestFastLRUCache, TestSystemIdResolver, DateFieldTest, SpellingQueryConverterTest, ClusterStateTest, TestSolrJ, TestLRUCache, SliceStateTest, UUIDFieldTest, FileUtilsTest, CircularListTest, TestRTGBase, SolrTestCaseJ4Test, TestDistributedMissingSort, AbstractAnalyticsStatsTest, NoFacetTest, AbstractAnalyticsFacetTest, FieldFacetExtrasTest, QueryFacetTest, RangeFacetTest, FunctionTest, CustomCollectionTest, DistribCursorPagingTest, MigrateRouteKeyTest, OverseerRolesTest]
   [junit4] Completed in 35.09s, 1 test, 1 error <<< FAILURES!

[...truncated 143 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: 368 suites, 1595 tests, 1 error, 40 ignored (12 assumptions)

Total time: 82 minutes 51 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.7.0_51 -XX:+UseCompressedOops -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure