lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.8.0_20-ea-b15) - Build # 4156 - Failure!
Date Tue, 01 Jul 2014 19:37:32 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/4156/
Java: 32bit/jdk1.8.0_20-ea-b15 -client -XX:+UseG1GC

1 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.solr.cloud.TestMiniSolrCloudCluster

Error Message:
Could not remove the following files (in the order of attempts):    C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\testSolrCloudCollection_shard1_replica1\data\tlog\tlog.0000000000000000000    C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\testSolrCloudCollection_shard1_replica1\data\tlog    C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\testSolrCloudCollection_shard1_replica1\data    C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\testSolrCloudCollection_shard1_replica1    C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001 

Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
   C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\testSolrCloudCollection_shard1_replica1\data\tlog\tlog.0000000000000000000
   C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\testSolrCloudCollection_shard1_replica1\data\tlog
   C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\testSolrCloudCollection_shard1_replica1\data
   C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\testSolrCloudCollection_shard1_replica1
   C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001

	at __randomizedtesting.SeedInfo.seed([EC3AF37AF40994F]:0)
	at org.apache.lucene.util.TestUtil.rm(TestUtil.java:118)
	at org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:125)
	at com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
	at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 11355 lines...]
   [junit4] Suite: org.apache.solr.cloud.TestMiniSolrCloudCluster
   [junit4]   2> 1675921 T5063 oasc.ZkTestServer.run STARTING ZK TEST SERVER
   [junit4]   1> client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1675925 T5064 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
   [junit4]   2> 1676076 T5063 oasc.ZkTestServer.run start zk server on port:57810
   [junit4]   2> 1676080 T5063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1676098 T5070 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@95e9ac name:ZooKeeperConnection Watcher:127.0.0.1:57810 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1676099 T5063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1676099 T5063 oascc.SolrZkClient.makePath makePath: /solr
   [junit4]   2> 1676115 T5065 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x146f3467bc90000, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 
   [junit4]   2> 1676115 T5063 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 1676129 T5063 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:57814
   [junit4]   2> 1676129 T5063 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 1676130 T5063 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1676130 T5063 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001
   [junit4]   2> 1676130 T5063 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\'
   [junit4]   2> 1676211 T5063 oass.SolrDispatchFilter.loadConfigSolr Trying to read solr.xml from 127.0.0.1:57810/solr
   [junit4]   2> 1676214 T5063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1676219 T5081 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1b87728 name:ZooKeeperConnection Watcher:127.0.0.1:57810/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1676219 T5063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1676262 T5063 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001
   [junit4]   2> 1676269 T5063 oasc.CoreContainer.<init> New CoreContainer 850164
   [junit4]   2> 1676271 T5063 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\]
   [junit4]   2> 1676272 T5063 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 1676272 T5063 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 1676274 T5063 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 1676274 T5063 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1676274 T5063 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 1676275 T5063 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1676277 T5063 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 1676278 T5063 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 1676279 T5063 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 1676281 T5063 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1676281 T5063 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1676281 T5063 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 1676281 T5063 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:57810/solr
   [junit4]   2> 1676284 T5063 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 1676288 T5063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1676294 T5083 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@19f95ad name:ZooKeeperConnection Watcher:127.0.0.1:57810 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1676296 T5063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1676312 T5063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1676319 T5085 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@f235c7 name:ZooKeeperConnection Watcher:127.0.0.1:57810/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1676320 T5063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1676328 T5063 oascc.SolrZkClient.makePath makePath: /overseer/queue
   [junit4]   2> 1676341 T5063 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
   [junit4]   2> 1676353 T5063 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
   [junit4]   2> 1676364 T5063 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
   [junit4]   2> 1676376 T5063 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
   [junit4]   2> 1676388 T5063 oascc.SolrZkClient.makePath makePath: /live_nodes
   [junit4]   2> 1676394 T5063 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57814_solr
   [junit4]   2> 1676398 T5063 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:57814_solr
   [junit4]   2> 1676407 T5063 oascc.SolrZkClient.makePath makePath: /collections
   [junit4]   2> 1676414 T5063 oascc.SolrZkClient.makePath makePath: /overseer_elect
   [junit4]   2> 1676422 T5063 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
   [junit4]   2> 1676438 T5063 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:57814_solr
   [junit4]   2> 1676438 T5063 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
   [junit4]   2> 1676448 T5063 oasc.Overseer.start Overseer (id=92028326457704451-127.0.0.1:57814_solr-n_0000000000) starting
   [junit4]   2> 1676460 T5063 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
   [junit4]   2> 1676502 T5087 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
   [junit4]   2> 1676504 T5063 oascc.SolrZkClient.makePath makePath: /clusterstate.json
   [junit4]   2> 1676512 T5063 oascc.SolrZkClient.makePath makePath: /aliases.json
   [junit4]   2> 1676517 T5063 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 1676527 T5086 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
   [junit4]   2> 1676534 T5063 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001
   [junit4]   2> 1676559 T5063 oasc.CorePropertiesLocator.discover Found 0 core definitions
   [junit4]   2> 1676562 T5063 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 1676562 T5063 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 1676564 T5063 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 1676571 T5063 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:57826
   [junit4]   2> 1676572 T5063 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 1676572 T5063 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1676572 T5063 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001
   [junit4]   2> 1676572 T5063 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\'
   [junit4]   2> 1676625 T5063 oass.SolrDispatchFilter.loadConfigSolr Trying to read solr.xml from 127.0.0.1:57810/solr
   [junit4]   2> 1676628 T5063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1676637 T5099 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1e37424 name:ZooKeeperConnection Watcher:127.0.0.1:57810/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1676637 T5063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1676666 T5063 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001
   [junit4]   2> 1676669 T5063 oasc.CoreContainer.<init> New CoreContainer 5500819
   [junit4]   2> 1676672 T5063 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\]
   [junit4]   2> 1676673 T5063 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 1676673 T5063 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 1676673 T5063 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 1676673 T5063 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1676673 T5063 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 1676673 T5063 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1676673 T5063 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 1676673 T5063 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 1676673 T5063 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 1676676 T5063 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1676676 T5063 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1676676 T5063 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 1676676 T5063 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:57810/solr
   [junit4]   2> 1676676 T5063 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 1676682 T5063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1676688 T5101 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1dc86e8 name:ZooKeeperConnection Watcher:127.0.0.1:57810 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1676690 T5063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1676698 T5063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1676703 T5103 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1df3ae5 name:ZooKeeperConnection Watcher:127.0.0.1:57810/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1676703 T5063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1676731 T5063 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 1678241 T5063 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57826_solr
   [junit4]   2> 1678244 T5063 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:57826_solr
   [junit4]   2> 1678266 T5063 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001
   [junit4]   2> 1678269 T5063 oasc.CorePropertiesLocator.discover Found 0 core definitions
   [junit4]   2> 1678272 T5063 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 1678272 T5063 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 1678273 T5063 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 1678279 T5063 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:57838
   [junit4]   2> 1678282 T5063 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 1678283 T5063 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1678283 T5063 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001
   [junit4]   2> 1678283 T5063 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\'
   [junit4]   2> 1678323 T5063 oass.SolrDispatchFilter.loadConfigSolr Trying to read solr.xml from 127.0.0.1:57810/solr
   [junit4]   2> 1678329 T5063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1678335 T5115 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@191023c name:ZooKeeperConnection Watcher:127.0.0.1:57810/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1678335 T5063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1678359 T5063 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001
   [junit4]   2> 1678364 T5063 oasc.CoreContainer.<init> New CoreContainer 27633307
   [junit4]   2> 1678367 T5063 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\]
   [junit4]   2> 1678368 T5063 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 1678368 T5063 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 1678370 T5063 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 1678370 T5063 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1678371 T5063 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 1678371 T5063 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1678371 T5063 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 1678373 T5063 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 1678373 T5063 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 1678374 T5063 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1678374 T5063 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1678376 T5063 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 1678376 T5063 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:57810/solr
   [junit4]   2> 1678376 T5063 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 1678380 T5063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1678384 T5117 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1b289ab name:ZooKeeperConnection Watcher:127.0.0.1:57810 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1678386 T5063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1678395 T5063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1678400 T5119 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1f55477 name:ZooKeeperConnection Watcher:127.0.0.1:57810/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1678400 T5063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1678425 T5063 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 1679934 T5063 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57838_solr
   [junit4]   2> 1679937 T5063 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:57838_solr
   [junit4]   2> 1679956 T5063 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001
   [junit4]   2> 1679959 T5063 oasc.CorePropertiesLocator.discover Found 0 core definitions
   [junit4]   2> 1679962 T5063 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 1679962 T5063 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 1679963 T5063 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 1679969 T5063 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:57850
   [junit4]   2> 1679969 T5063 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 1679969 T5063 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1679971 T5063 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001
   [junit4]   2> 1679971 T5063 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\'
   [junit4]   2> 1680009 T5063 oass.SolrDispatchFilter.loadConfigSolr Trying to read solr.xml from 127.0.0.1:57810/solr
   [junit4]   2> 1680013 T5063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1680018 T5131 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2ffdb5 name:ZooKeeperConnection Watcher:127.0.0.1:57810/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1680019 T5063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1680044 T5063 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001
   [junit4]   2> 1680048 T5063 oasc.CoreContainer.<init> New CoreContainer 31896002
   [junit4]   2> 1680050 T5063 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\]
   [junit4]   2> 1680050 T5063 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 1680053 T5063 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 1680053 T5063 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 1680054 T5063 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1680054 T5063 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 1680054 T5063 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1680056 T5063 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 1680056 T5063 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 1680056 T5063 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 1680056 T5063 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1680056 T5063 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1680056 T5063 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 1680060 T5063 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:57810/solr
   [junit4]   2> 1680062 T5063 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 1680065 T5063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1680070 T5133 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@b913f9 name:ZooKeeperConnection Watcher:127.0.0.1:57810 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1680072 T5063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1680078 T5065 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x146f3467bc9000b, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 
   [junit4]   2> 1680079 T5063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1680086 T5135 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@198856e name:ZooKeeperConnection Watcher:127.0.0.1:57810/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1680088 T5063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1680108 T5063 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 1681617 T5063 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57850_solr
   [junit4]   2> 1681622 T5063 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:57850_solr
   [junit4]   2> 1681645 T5063 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001
   [junit4]   2> 1681649 T5063 oasc.CorePropertiesLocator.discover Found 0 core definitions
   [junit4]   2> 1681652 T5063 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 1681652 T5063 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 1681654 T5063 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 1681661 T5063 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:57862
   [junit4]   2> 1681663 T5063 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 1681663 T5063 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1681663 T5063 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001
   [junit4]   2> 1681663 T5063 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\'
   [junit4]   2> 1681705 T5063 oass.SolrDispatchFilter.loadConfigSolr Trying to read solr.xml from 127.0.0.1:57810/solr
   [junit4]   2> 1681711 T5063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1681717 T5147 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@14058ab name:ZooKeeperConnection Watcher:127.0.0.1:57810/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1681718 T5063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1681746 T5063 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001
   [junit4]   2> 1681752 T5063 oasc.CoreContainer.<init> New CoreContainer 14072824
   [junit4]   2> 1681753 T5063 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\]
   [junit4]   2> 1681756 T5063 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 1681756 T5063 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 1681756 T5063 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 1681756 T5063 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1681756 T5063 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 1681761 T5063 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1681761 T5063 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 1681761 T5063 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 1681761 T5063 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 1681761 T5063 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1681761 T5063 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1681761 T5063 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 1681764 T5063 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:57810/solr
   [junit4]   2> 1681764 T5063 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 1681768 T5063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1681774 T5149 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1620519 name:ZooKeeperConnection Watcher:127.0.0.1:57810 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1681777 T5063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1681789 T5065 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x146f3467bc9000e, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 
   [junit4]   2> 1681792 T5063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1681797 T5151 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1fb3f31 name:ZooKeeperConnection Watcher:127.0.0.1:57810/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1681799 T5063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1681819 T5063 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 1683328 T5063 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57862_solr
   [junit4]   2> 1683331 T5063 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:57862_solr
   [junit4]   2> 1683356 T5063 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001
   [junit4]   2> 1683356 T5063 oasc.CorePropertiesLocator.discover Found 0 core definitions
   [junit4]   2> 1683360 T5063 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 1683362 T5063 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 1683434 T5063 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=850164
   [junit4]   2> 1683438 T5086 oasc.Overseer$ClusterStateUpdater.run Overseer Loop exiting : 127.0.0.1:57814_solr
   [junit4]   2> 1683445 T5063 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
   [junit4]   2> 1683447 T5153 oasc.Overseer$ClusterStateUpdater.checkIfIamStillLeader ERROR could not read the data org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
   [junit4]   2> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
   [junit4]   2> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
   [junit4]   2> 	at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
   [junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:277)
   [junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:274)
   [junit4]   2> 	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
   [junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:274)
   [junit4]   2> 	at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:317)
   [junit4]   2> 	at org.apache.solr.cloud.Overseer$ClusterStateUpdater.access$300(Overseer.java:85)
   [junit4]   2> 	at org.apache.solr.cloud.Overseer$ClusterStateUpdater$1.run(Overseer.java:289)
   [junit4]   2> 
   [junit4]   2> 1683456 T5103 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:57826_solr
   [junit4]   2> 1683456 T5103 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
   [junit4]   2> 1683464 T5103 oasc.Overseer.start Overseer (id=92028326457704454-127.0.0.1:57826_solr-n_0000000001) starting
   [junit4]   2> 1683501 T5063 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 1683519 T5063 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:57874
   [junit4]   2> 1683519 T5164 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
   [junit4]   2> 1683520 T5063 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 1683524 T5063 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1683524 T5063 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001
   [junit4]   2> 1683524 T5063 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\'
   [junit4]   2> 1683535 T5154 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
   [junit4]   2> 1683579 T5063 oass.SolrDispatchFilter.loadConfigSolr Trying to read solr.xml from 127.0.0.1:57810/solr
   [junit4]   2> 1683581 T5063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1683587 T5166 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@126e861 name:ZooKeeperConnection Watcher:127.0.0.1:57810/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1683589 T5063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1683617 T5063 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001
   [junit4]   2> 1683623 T5063 oasc.CoreContainer.<init> New CoreContainer 7934835
   [junit4]   2> 1683624 T5063 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\]
   [junit4]   2> 1683623 T5065 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x146f3467bc90010, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 
   [junit4]   2> 1683627 T5063 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 1683631 T5063 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 1683631 T5063 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 1683633 T5063 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1683633 T5063 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 1683634 T5063 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1683634 T5063 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 1683634 T5063 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 1683634 T5063 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 1683634 T5063 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1683634 T5063 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1683634 T5063 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 1683634 T5063 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:57810/solr
   [junit4]   2> 1683637 T5063 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 1683642 T5063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1683646 T5168 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@15432cc name:ZooKeeperConnection Watcher:127.0.0.1:57810 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1683647 T5063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1683655 T5063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1683661 T5170 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@a084b7 name:ZooKeeperConnection Watcher:127.0.0.1:57810/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1683662 T5063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1683680 T5063 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 1685187 T5063 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57874_solr
   [junit4]   2> 1685190 T5063 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:57874_solr
   [junit4]   2> 1685215 T5063 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001
   [junit4]   2> 1685219 T5063 oasc.CorePropertiesLocator.discover Found 0 core definitions
   [junit4]   2> 1685221 T5063 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 1685222 T5063 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 1685225 T5063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1685229 T5173 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@b30862 name:ZooKeeperConnection Watcher:127.0.0.1:57810/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1685231 T5063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1685235 T5063 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 1685246 T5063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1685253 T5175 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@11660c name:ZooKeeperConnection Watcher:127.0.0.1:57810/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1685254 T5063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1685259 T5063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 1685265 T5177 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@dff910 name:ZooKeeperConnection Watcher:127.0.0.1:57810/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1685266 T5063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 1685266 T5063 oascc.SolrZkClient.makePath makePath: /configs/solrCloudCollectionConfig/solrconfig.xml
   [junit4]   2> 1685281 T5063 oascc.SolrZkClient.makePath makePath: /configs/solrCloudCollectionConfig/schema.xml
   [junit4]   2> 1685291 T5063 oascc.SolrZkClient.makePath makePath: /configs/solrCloudCollectionConfig/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1685300 T5063 oascc.SolrZkClient.makePath makePath: /configs/solrCloudCollectionConfig/currency.xml
   [junit4]   2> 1685307 T5063 oascc.SolrZkClient.makePath makePath: /configs/solrCloudCollectionConfig/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1685316 T5063 oascc.SolrZkClient.makePath makePath: /configs/solrCloudCollectionConfig/old_synonyms.txt
   [junit4]   2> 1685323 T5063 oascc.SolrZkClient.makePath makePath: /configs/solrCloudCollectionConfig/open-exchange-rates.json
   [junit4]   2> 1685330 T5063 oascc.SolrZkClient.makePath makePath: /configs/solrCloudCollectionConfig/protwords.txt
   [junit4]   2> 1685339 T5063 oascc.SolrZkClient.makePath makePath: /configs/solrCloudCollectionConfig/stopwords.txt
   [junit4]   2> 1685348 T5063 oascc.SolrZkClient.makePath makePath: /configs/solrCloudCollectionConfig/synonyms.txt
   [junit4]   2> 1685377 T5125 oasha.CollectionsHandler.handleCreateAction Creating Collection : replicationFactor=2&collection.configName=solrCloudCollectionConfig&name=testSolrCloudCollection&action=CREATE&numShards=2&wt=javabin&version=2
   [junit4]   2> 1685383 T5103 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/collection-queue-work state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1685391 T5103 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/collection-queue-work state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1685392 T5164 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":"testSolrCloudCollection",
   [junit4]   2> 	  "replicationFactor":"2",
   [junit4]   2> 	  "collection.configName":"solrCloudCollectionConfig",
   [junit4]   2> 	  "numShards":"2"}
   [junit4]   2> 1685396 T5178 oasc.OverseerCollectionProcessor.processMessage WARN OverseerCollectionProcessor.processMessage : createcollection , {
   [junit4]   2> 	  "operation":"createcollection",
   [junit4]   2> 	  "fromApi":"true",
   [junit4]   2> 	  "name":"testSolrCloudCollection",
   [junit4]   2> 	  "replicationFactor":"2",
   [junit4]   2> 	  "collection.configName":"solrCloudCollectionConfig",
   [junit4]   2> 	  "numShards":"2"}
   [junit4]   2> 1685399 T5178 oasc.OverseerCollectionProcessor.createConfNode creating collections conf node /collections/testSolrCloudCollection 
   [junit4]   2> 1685399 T5178 oascc.SolrZkClient.makePath makePath: /collections/testSolrCloudCollection
   [junit4]   2> 1685412 T5103 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1685417 T5154 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 1685420 T5154 oasc.Overseer$ClusterStateUpdater.buildCollection building a new collection: testSolrCloudCollection
   [junit4]   2> 1685423 T5154 oasc.Overseer$ClusterStateUpdater.createCollection Create collection testSolrCloudCollection with shards [shard1, shard2]
   [junit4]   2> 1685432 T5103 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1685434 T5170 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
   [junit4]   2> 1685434 T5103 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
   [junit4]   2> 1685434 T5151 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
   [junit4]   2> 1685434 T5135 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
   [junit4]   2> 1685434 T5173 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
   [junit4]   2> 1685434 T5119 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
   [junit4]   2> 1685563 T5178 oasc.OverseerCollectionProcessor.createCollection Creating SolrCores for new collection testSolrCloudCollection, shardNames [shard1, shard2] , replicationFactor : 2
   [junit4]   2> 1685563 T5178 oasc.OverseerCollectionProcessor.createCollection Creating shard testSolrCloudCollection_shard1_replica1 as part of slice shard1 of collection testSolrCloudCollection on 127.0.0.1:57862_solr
   [junit4]   2> 1685566 T5178 oasc.OverseerCollectionProcessor.createCollection Creating shard testSolrCloudCollection_shard1_replica2 as part of slice shard1 of collection testSolrCloudCollection on 127.0.0.1:57874_solr
   [junit4]   2> 1685569 T5178 oasc.OverseerCollectionProcessor.createCollection Creating shard testSolrCloudCollection_shard2_replica1 as part of slice shard2 of collection testSolrCloudCollection on 127.0.0.1:57850_solr
   [junit4]   2> 1685571 T5178 oasc.OverseerCollectionProcessor.createCollection Creating shard testSolrCloudCollection_shard2_replica2 as part of slice shard2 of collection testSolrCloudCollection on 127.0.0.1:57838_solr
   [junit4]   2> 1685584 T5123 oasha.CoreAdminHandler.handleCreateAction core create command qt=/admin/cores&collection.configName=solrCloudCollectionConfig&name=testSolrCloudCollection_shard2_replica1&action=CREATE&numShards=2&collection=testSolrCloudCollection&shard=shard2&wt=javabin&version=2
   [junit4]   2> 1685590 T5163 oasha.CoreAdminHandler.handleCreateAction core create command qt=/admin/cores&collection.configName=solrCloudCollectionConfig&name=testSolrCloudCollection_shard1_replica2&action=CREATE&numShards=2&collection=testSolrCloudCollection&shard=shard1&wt=javabin&version=2
   [junit4]   2> 1685588 T5140 oasha.CoreAdminHandler.handleCreateAction core create command qt=/admin/cores&collection.configName=solrCloudCollectionConfig&name=testSolrCloudCollection_shard1_replica1&action=CREATE&numShards=2&collection=testSolrCloudCollection&shard=shard1&wt=javabin&version=2
   [junit4]   2> 1685584 T5112 oasha.CoreAdminHandler.handleCreateAction core create command qt=/admin/cores&collection.configName=solrCloudCollectionConfig&name=testSolrCloudCollection_shard2_replica2&action=CREATE&numShards=2&collection=testSolrCloudCollection&shard=shard2&wt=javabin&version=2
   [junit4]   2> 1685610 T5112 oasc.ZkController.publish publishing core=testSolrCloudCollection_shard2_replica2 state=down collection=testSolrCloudCollection
   [junit4]   2> 1685618 T5112 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 1685619 T5140 oasc.ZkController.publish publishing core=testSolrCloudCollection_shard1_replica1 state=down collection=testSolrCloudCollection
   [junit4]   2> 1685619 T5163 oasc.ZkController.publish publishing core=testSolrCloudCollection_shard1_replica2 state=down collection=testSolrCloudCollection
   [junit4]   2> 1685619 T5123 oasc.ZkController.publish publishing core=testSolrCloudCollection_shard2_replica1 state=down collection=testSolrCloudCollection
   [junit4]   2> 1685619 T5103 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1685622 T5140 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 1685623 T5163 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 1685626 T5103 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1685626 T5123 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 1685628 T5154 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 1685632 T5154 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:57838/solr",
   [junit4]   2> 	  "core":"testSolrCloudCollection_shard2_replica2",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:57838_solr",
   [junit4]   2> 	  "shard":"shard2",
   [junit4]   2> 	  "collection":"testSolrCloudCollection",
   [junit4]   2> 	  "numShards":"2",
   [junit4]   2> 	  "core_node_name":null}
   [junit4]   2> 1685644 T5154 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:57862/solr",
   [junit4]   2> 	  "core":"testSolrCloudCollection_shard1_replica1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:57862_solr",
   [junit4]   2> 	  "shard":"shard1",
   [junit4]   2> 	  "collection":"testSolrCloudCollection",
   [junit4]   2> 	  "numShards":"2",
   [junit4]   2> 	  "core_node_name":null}
   [junit4]   2> 1685653 T5103 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1685656 T5154 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:57874/solr",
   [junit4]   2> 	  "core":"testSolrCloudCollection_shard1_replica2",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:57874_solr",
   [junit4]   2> 	  "shard":"shard1",
   [junit4]   2> 	  "collection":"testSolrCloudCollection",
   [junit4]   2> 	  "numShards":"2",
   [junit4]   2> 	  "core_node_name":null}
   [junit4]   2> 1685664 T5103 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1685667 T5154 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:57850/solr",
   [junit4]   2> 	  "core":"testSolrCloudCollection_shard2_replica1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:57850_solr",
   [junit4]   2> 	  "shard":"shard2",
   [junit4]   2> 	  "collection":"testSolrCloudCollection",
   [junit4]   2> 	  "numShards":"2",
   [junit4]   2> 	  "core_node_name":null}
   [junit4]   2> 1685675 T5103 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1685694 T5085 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
   [junit4]   2> 1685831 T5170 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
   [junit4]   2> 1685833 T5151 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
   [junit4]   2> 1685833 T5119 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
   [junit4]   2> 1685834 T5103 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
   [junit4]   2> 1685834 T5135 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
   [junit4]   2> 1685834 T5173 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
   [junit4]   2> 1687119 T5112 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testSolrCloudCollection_shard2_replica2
   [junit4]   2> 1687120 T5112 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testSolrCloudCollection
   [junit4]   2> 1687122 T5112 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 1687123 T5163 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testSolrCloudCollection_shard1_replica2
   [junit4]   2> 1687125 T5163 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testSolrCloudCollection
   [junit4]   2> 1687123 T5140 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testSolrCloudCollection_shard1_replica1
   [junit4]   2> 1687126 T5140 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testSolrCloudCollection
   [junit4]   2> 1687125 T5112 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testSolrCloudCollection
   [junit4]   2> 1687128 T5163 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 1687129 T5163 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testSolrCloudCollection
   [junit4]   2> 1687128 T5123 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testSolrCloudCollection_shard2_replica1
   [junit4]   2> 1687131 T5112 oascc.ZkStateReader.readConfigName path=/collections/testSolrCloudCollection configName=solrCloudCollectionConfig specified config exists in ZooKeeper
   [junit4]   2> 1687131 T5112 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\testSolrCloudCollection_shard2_replica2\'
   [junit4]   2> 1687129 T5140 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 1687132 T5140 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testSolrCloudCollection
   [junit4]   2> 1687131 T5123 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testSolrCloudCollection
   [junit4]   2> 1687135 T5163 oascc.ZkStateReader.readConfigName path=/collections/testSolrCloudCollection configName=solrCloudCollectionConfig specified config exists in ZooKeeper
   [junit4]   2> 1687137 T5163 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\testSolrCloudCollection_shard1_replica2\'
   [junit4]   2> 1687150 T5123 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 1687151 T5123 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testSolrCloudCollection
   [junit4]   2> 1687163 T5140 oascc.ZkStateReader.readConfigName path=/collections/testSolrCloudCollection configName=solrCloudCollectionConfig specified config exists in ZooKeeper
   [junit4]   2> 1687164 T5140 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\testSolrCloudCollection_shard1_replica1\'
   [junit4]   2> 1687189 T5123 oascc.ZkStateReader.readConfigName path=/collections/testSolrCloudCollection configName=solrCloudCollectionConfig specified config exists in ZooKeeper
   [junit4]   2> 1687191 T5123 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\testSolrCloudCollection_shard2_replica1\'
   [junit4]   2> 1687283 T5112 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
   [junit4]   2> 1687358 T5163 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
   [junit4]   2> 1687365 T5112 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1687384 T5163 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1687397 T5140 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
   [junit4]   2> 1687397 T5163 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 1687424 T5123 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
   [junit4]   2> 1687437 T5163 oass.IndexSchema.readSchema [testSolrCloudCollection_shard1_replica2] Schema name=test
   [junit4]   2> 1687446 T5123 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1687447 T5112 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 1687478 T5112 oass.IndexSchema.readSchema [testSolrCloudCollection_shard2_replica2] Schema name=test
   [junit4]   2> 1687529 T5140 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1687717 T5123 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 1687730 T5140 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 1687746 T5140 oass.IndexSchema.readSchema [testSolrCloudCollection_shard1_replica1] Schema name=test
   [junit4]   2> 1687768 T5163 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1687799 T5123 oass.IndexSchema.readSchema [testSolrCloudCollection_shard2_replica1] Schema name=test
   [junit4]   2> 1687970 T5140 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1687983 T5140 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 1687986 T5140 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1687995 T5163 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 1687998 T5163 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1688020 T5112 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1688027 T5112 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 1688032 T5112 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1688102 T5123 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1688112 T5123 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 1688117 T5123 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1688140 T5112 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 1688144 T5140 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 1688153 T5163 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 1688159 T5140 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 1688153 T5112 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 1688169 T5163 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 1688171 T5140 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1688177 T5112 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1688177 T5140 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 1688178 T5140 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 1688178 T5140 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1688182 T5163 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1688187 T5112 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 1688187 T5112 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 1688188 T5112 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1688190 T5140 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 1688191 T5140 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 1688191 T5140 oasc.CoreContainer.create Creating SolrCore 'testSolrCloudCollection_shard1_replica1' using configuration from collection testSolrCloudCollection
   [junit4]   2> 1688193 T5140 oasc.SolrCore.initDirectoryFactory solr.RAMDirectoryFactory
   [junit4]   2> 1688191 T5163 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 1688197 T5163 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 1688197 T5163 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1688193 T5112 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 1688200 T5112 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 1688200 T5112 oasc.CoreContainer.create Creating SolrCore 'testSolrCloudCollection_shard2_replica2' using configuration from collection testSolrCloudCollection
   [junit4]   2> 1688200 T5112 oasc.SolrCore.initDirectoryFactory solr.RAMDirectoryFactory
   [junit4]   2> 1688206 T5163 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 1688206 T5163 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 1688206 T5163 oasc.CoreContainer.create Creating SolrCore 'testSolrCloudCollection_shard1_replica2' using configuration from collection testSolrCloudCollection
   [junit4]   2> 1688206 T5163 oasc.SolrCore.initDirectoryFactory solr.RAMDirectoryFactory
   [junit4]   2> 1688216 T5123 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 1688229 T5123 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 1688238 T5123 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1688244 T5123 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 1688245 T5123 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 1688245 T5123 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1688250 T5123 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 1688250 T5123 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 1688254 T5123 oasc.CoreContainer.create Creating SolrCore 'testSolrCloudCollection_shard2_replica1' using configuration from collection testSolrCloudCollection
   [junit4]   2> 1688254 T5123 oasc.SolrCore.initDirectoryFactory solr.RAMDirectoryFactory
   [junit4]   2> 1688257 T5112 oasc.SolrCore.<init> [testSolrCloudCollection_shard2_replica2] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\testSolrCloudCollection_shard2_replica2\, dataDir=data\
   [junit4]   2> 1688257 T5112 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5d4652
   [junit4]   2> 1688259 T5112 oasc.CachingDirectoryFactory.get return new directory for data\
   [junit4]   2> 1688260 T5112 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=data\index/
   [junit4]   2> 1688260 T5112 oasc.SolrCore.initIndex WARN [testSolrCloudCollection_shard2_replica2] Solr index directory 'data\index' doesn't exist. Creating new index...
   [junit4]   2> 1688260 T5112 oasc.CachingDirectoryFactory.get return new directory for data\index
   [junit4]   2> 1688264 T5112 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=RAMDirectory@15aea0f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@113ab09,segFN=segments_1,generation=1}
   [junit4]   2> 1688266 T5112 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1688267 T5140 oasc.SolrCore.<init> [testSolrCloudCollection_shard1_replica1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\testSolrCloudCollection_shard1_replica1\, dataDir=data\
   [junit4]   2> 1688267 T5140 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5d4652
   [junit4]   2> 1688267 T5140 oasc.CachingDirectoryFactory.get return new directory for data\
   [junit4]   2> 1688267 T5140 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=data\index/
   [junit4]   2> 1688267 T5140 oasc.SolrCore.initIndex WARN [testSolrCloudCollection_shard1_replica1] Solr index directory 'data\index' doesn't exist. Creating new index...
   [junit4]   2> 1688273 T5140 oasc.CachingDirectoryFactory.get return new directory for data\index
   [junit4]   2> 1688275 T5140 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=RAMDirectory@8d5122 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b71c3b,segFN=segments_1,generation=1}
   [junit4]   2> 1688275 T5112 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 1688276 T5140 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1688278 T5112 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 1688278 T5112 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1688279 T5112 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1688279 T5112 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1688281 T5112 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 1688281 T5112 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1688282 T5112 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1688282 T5112 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1688283 T5112 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 1688279 T5123 oasc.SolrCore.<init> [testSolrCloudCollection_shard2_replica1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\testSolrCloudCollection_shard2_replica1\, dataDir=data\
   [junit4]   2> 1688285 T5123 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5d4652
   [junit4]   2> 1688285 T5112 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 1688288 T5112 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 1688288 T5112 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 1688289 T5140 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 1688286 T5123 oasc.CachingDirectoryFactory.get return new directory for data\
   [junit4]   2> 1688291 T5140 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 1688289 T5112 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 1688288 T5163 oasc.SolrCore.<init> [testSolrCloudCollection_shard1_replica2] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\testSolrCloudCollection_shard1_replica2\, dataDir=data\
   [junit4]   2> 1688292 T5112 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 1688291 T5140 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1688294 T5140 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1688291 T5123 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=data\index/
   [junit4]   2> 1688297 T5123 oasc.SolrCore.initIndex WARN [testSolrCloudCollection_shard2_replica1] Solr index directory 'data\index' doesn't exist. Creating new index...
   [junit4]   2> 1688298 T5123 oasc.CachingDirectoryFactory.get return new directory for data\index
   [junit4]   2> 1688297 T5140 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1688294 T5112 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 1688292 T5163 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5d4652
   [junit4]   2> 1688300 T5112 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
   [junit4]   2> 1688300 T5140 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 1688303 T5140 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1688303 T5140 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1688303 T5140 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1688304 T5140 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 1688305 T5140 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 1688305 T5140 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 1688305 T5140 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 1688308 T5140 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 1688308 T5140 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 1688311 T5112 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1688298 T5123 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=RAMDirectory@13be99b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@143109,segFN=segments_1,generation=1}
   [junit4]   2> 1688311 T5123 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1688311 T5140 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 1688317 T5112 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 1688319 T5112 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 1688320 T5112 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=RAMDirectory@15aea0f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@113ab09,segFN=segments_1,generation=1}
   [junit4]   2> 1688322 T5112 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1688322 T5112 oass.SolrIndexSearcher.<init> Opening Searcher@e0d20d[testSolrCloudCollection_shard2_replica2] main
   [junit4]   2> 1688322 T5112 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testSolrCloudCollection
   [junit4]   2> 1688323 T5123 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 1688325 T5123 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 1688300 T5163 oasc.CachingDirectoryFactory.get return new directory for data\
   [junit4]   2> 1688326 T5163 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=data\index/
   [junit4]   2> 1688326 T5163 oasc.SolrCore.initIndex WARN [testSolrCloudCollection_shard1_replica2] Solr index directory 'data\index' doesn't exist. Creating new index...
   [junit4]   2> 1688327 T5163 oasc.CachingDirectoryFactory.get return new directory for data\index
   [junit4]   2> 1688326 T5123 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1688329 T5123 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1688325 T5140 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
   [junit4]   2> 1688330 T5123 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1688332 T5123 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 1688335 T5123 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1688329 T5163 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=RAMDirectory@d735cb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea678f,segFN=segments_1,generation=1}
   [junit4]   2> 1688336 T5163 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1688326 T5112 oascc.ZkStateReader.readConfigName path=/collections/testSolrCloudCollection configName=solrCloudCollectionConfig specified config exists in ZooKeeper
   [junit4]   2> 1688338 T5112 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/solrCloudCollectionConfig
   [junit4]   2> 1688336 T5123 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1688341 T5123 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1688342 T5123 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 1688344 T5123 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 1688345 T5123 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 1688345 T5123 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 1688346 T5112 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/solrCloudCollectionConfig
   [junit4]   2> 1688346 T5112 oasr.RestManager.init Initializing RestManager with initArgs: {}
   [junit4]   2> 1688349 T5112 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/solrCloudCollectionConfig
   [junit4]   2> 1688349 T5123 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 1688352 T5112 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/solrCloudCollectionConfig/_rest_managed.json
   [junit4]   2> 1688336 T5140 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1688354 T5112 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/solrCloudCollectionConfig
   [junit4]   2> 1688352 T5123 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 1688358 T5123 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 1688360 T5123 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
   [junit4]   2> 1688360 T5140 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 1688363 T5140 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 1688346 T5163 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 1688366 T5112 oascc.SolrZkClient.makePath makePath: /configs/solrCloudCollectionConfig/_rest_managed.json
   [junit4]   2> 1688367 T5140 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=RAMDirectory@8d5122 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b71c3b,segFN=segments_1,generation=1}
   [junit4]   2> 1688368 T5140 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1688370 T5140 oass.SolrIndexSearcher.<init> Opening Searcher@1dc6000[testSolrCloudCollection_shard1_replica1] main
   [junit4]   2> 1688364 T5123 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1688371 T5140 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testSolrCloudCollection
   [junit4]   2> 1688367 T5163 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 1688377 T5163 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1688377 T5163 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1688379 T5163 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1688380 T5163 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 1688379 T5123 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 1688386 T5123 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 1688385 T5140 oascc.ZkStateReader.readConfigName path=/collections/testSolrCloudCollection configName=solrCloudCollectionConfig specified config exists in ZooKeeper
   [junit4]   2> 1688387 T5140 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/solrCloudCollectionConfig
   [junit4]   2> 1688385 T5163 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1688383 T5112 oasr.ManagedResourceStorage$ZooKeeperStorageIO$1.close Wrote 38 bytes to new znode /configs/solrCloudCollectionConfig/_rest_managed.json
   [junit4]   2> 1688392 T5112 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using ZooKeeperStorageIO:path=/configs/solrCloudCollectionConfig
   [junit4]   2> 1688392 T5112 oasr.RestManager.init Initializing 0 registered ManagedResources
   [junit4]   2> 1688392 T5163 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1688395 T5163 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1688390 T5140 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/solrCloudCollectionConfig
   [junit4]   2> 1688398 T5140 oasr.RestManager.init Initializing RestManager with initArgs: {}
   [junit4]   2> 1688399 T5140 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/solrCloudCollectionConfig
   [junit4]   2> 1688389 T5123 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=RAMDirectory@13be99b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@143109,segFN=segments_1,generation=1}
   [junit4]   2> 1688401 T5123 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1688402 T5123 oass.SolrIndexSearcher.<init> Opening Searcher@1b3291a[testSolrCloudCollection_shard2_replica1] main
   [junit4]   2> 1688398 T5163 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 1688408 T5163 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 1688408 T5163 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 1688409 T5163 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 1688411 T5163 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 1688412 T5163 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 1688414 T5163 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 1688415 T5163 oasc.RequestHandlers.initHandlersFromConfig created /admin/filee

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

cess WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
   [junit4]   2> 1699472 T5119 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1699472 T5119 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1699472 T5119 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1699472 T5119 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 1698265 T5135 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
   [junit4]   2> 1699458 T5064 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:57810 57810
   [junit4]   2> 1699498 T5151 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=1,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=96,transaction_logs_total_number=1}
   [junit4]   2> 1699501 T5151 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
   [junit4]   2> 1699501 T5151 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 1699502 T5151 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
   [junit4]   2> 1699505 T5151 oasc.SolrCore.closeSearcher [testSolrCloudCollection_shard1_replica1] Closing main searcher on request.
   [junit4]   2> 1699508 T5151 oasc.CachingDirectoryFactory.close Closing RAMDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 1699508 T5151 oasc.CachingDirectoryFactory.closeCacheValue looking to close data\index [CachedDir<<refCount=0;path=data\index;done=false>>]
   [junit4]   2> 1699513 T5151 oasc.CachingDirectoryFactory.close Closing directory: data\index
   [junit4]   2> 1699513 T5151 oasc.CachingDirectoryFactory.closeCacheValue looking to close data\ [CachedDir<<refCount=0;path=data\;done=false>>]
   [junit4]   2> 1699513 T5151 oasc.CachingDirectoryFactory.close Closing directory: data\
   [junit4]   2> 1699513 T5151 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
   [junit4]   2> NOTE: test params are: codec=Lucene40, sim=DefaultSimilarity, locale=ar, timezone=Asia/Kashgar
   [junit4]   2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0_20-ea (32-bit)/cpus=2,threads=1,free=96639096,total=439353344
   [junit4]   2> NOTE: All tests run in this JVM: [TestManagedSchema, CoreAdminRequestStatusTest, TestOmitPositions, WordBreakSolrSpellCheckerTest, TestBinaryField, TestSolrXmlPersistor, ZkNodePropsTest, TestSurroundQueryParser, BinaryUpdateRequestHandlerTest, TestFieldTypeCollectionResource, MergeStrategyTest, AnalysisErrorHandlingTest, TestStressVersions, SolrCmdDistributorTest, TestReplicationHandlerBackup, TestAddFieldRealTimeGet, TestCollationField, BadIndexSchemaTest, TestManagedSynonymFilterFactory, TestFunctionQuery, StatsComponentTest, TestExtendedDismaxParser, TestFuzzyAnalyzedSuggestions, NoCacheHeaderTest, TestElisionMultitermQuery, DocValuesTest, UpdateRequestProcessorFactoryTest, SpatialFilterTest, TestSolrQueryParserDefaultOperatorResource, SynonymTokenizerTest, TestLMDirichletSimilarityFactory, TestJmxIntegration, ConnectionManagerTest, TestRandomDVFaceting, SuggesterTSTTest, StressHdfsTest, TestMultiCoreConfBootstrap, TestRangeQuery, TestRequestStatusCollectionAPI, DistributedQueryElevationComponentTest, CurrencyFieldOpenExchangeTest, FunctionTest, StatelessScriptUpdateProcessorFactoryTest, TestReload, InfoHandlerTest, TestShortCircuitedRequests, BasicDistributedZkTest, CustomCollectionTest, TestFreeTextSuggestions, DocValuesMissingTest, PreAnalyzedFieldTest, SignatureUpdateProcessorFactoryTest, TestPostingsSolrHighlighter, QueryParsingTest, PrimitiveFieldTypeTest, NotRequiredUniqueKeyTest, ResponseHeaderTest, TestInfoStreamLogging, UpdateParamsTest, TestExceedMaxTermLength, SystemInfoHandlerTest, HdfsChaosMonkeySafeLeaderTest, DateMathParserTest, HdfsCollectionsAPIDistributedZkTest, TestSuggestSpellingConverter, TestCloudManagedSchemaConcurrent, SOLR749Test, FileUtilsTest, PreAnalyzedUpdateProcessorTest, IndexBasedSpellCheckerTest, CSVRequestHandlerTest, PluginInfoTest, TestJmxMonitoredMap, DistributedSuggestComponentTest, AnalysisAfterCoreReloadTest, TestCodecSupport, SolrInfoMBeanTest, AsyncMigrateRouteKeyTest, TriLevelCompositeIdRoutingTest, TestReRankQParserPlugin, HdfsLockFactoryTest, TestIBSimilarityFactory, TestQueryTypes, HighlighterTest, TestFieldCollectionResource, CursorMarkTest, HdfsSyncSliceTest, DirectUpdateHandlerTest, TestSearchPerf, DistribDocExpirationUpdateProcessorTest, TestSchemaSimilarityResource, RemoteQueryErrorTest, TestDocSet, FileBasedSpellCheckerTest, MoreLikeThisHandlerTest, TestAnalyzedSuggestions, FieldFacetTest, CoreAdminHandlerTest, TestHighFrequencyDictionaryFactory, TestComplexPhraseQParserPlugin, DateRangeFieldTest, TestZkChroot, TestModifyConfFiles, TestRandomMergePolicy, TestNoOpRegenerator, TestConfig, DebugComponentTest, TestStressReorder, ReplicationFactorTest, MinimalSchemaTest, MBeansHandlerTest, TestFastLRUCache, CachingDirectoryFactoryTest, CoreAdminCreateDiscoverTest, TestSolr4Spatial, TestCollationFieldDocValues, TestSimpleQParserPlugin, TestSolrXml, TestPseudoReturnFields, TestFoldingMultitermQuery, LukeRequestHandlerTest, SyncSliceTest, DistanceFunctionTest, RangeFacetTest, CoreMergeIndexesAdminHandlerTest, TestWordDelimiterFilterFactory, TestNRTOpen, TestDocumentBuilder, OverseerCollectionProcessorTest, ChangedSchemaMergeTest, TestAtomicUpdateErrorCases, CoreContainerCoreInitFailuresTest, TestBM25SimilarityFactory, CollectionsAPIAsyncDistributedZkTest, TestDistribDocBasedVersion, ZkSolrClientTest, TestCustomSort, TestCollapseQParserPlugin, TestRemoteStreaming, PathHierarchyTokenizerFactoryTest, SliceStateTest, URLClassifyProcessorTest, TestDFRSimilarityFactory, TestSweetSpotSimilarityFactory, LoggingHandlerTest, TestExpandComponent, UniqFieldsUpdateProcessorFactoryTest, TestJoin, BasicDistributedZk2Test, SimpleFacetsTest, OpenCloseCoreStressTest, TestStressLucene, TestSort, TestFileDictionaryLookup, AssignTest, UnloadDistributedZkTest, BadCopyFieldTest, TestPHPSerializedResponseWriter, CopyFieldTest, BasicFunctionalityTest, TestMiniSolrCloudCluster]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestMiniSolrCloudCluster -Dtests.seed=EC3AF37AF40994F -Dtests.slow=true -Dtests.locale=ar -Dtests.timezone=Asia/Kashgar -Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR   0.00s | TestMiniSolrCloudCluster (suite) <<<
   [junit4]    > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts):
   [junit4]    >    C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\testSolrCloudCollection_shard1_replica1\data\tlog\tlog.0000000000000000000
   [junit4]    >    C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\testSolrCloudCollection_shard1_replica1\data\tlog
   [junit4]    >    C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\testSolrCloudCollection_shard1_replica1\data
   [junit4]    >    C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001\testSolrCloudCollection_shard1_replica1
   [junit4]    >    C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-EC3AF37AF40994F-001\tempDir-001
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([EC3AF37AF40994F]:0)
   [junit4]    > 	at org.apache.lucene.util.TestUtil.rm(TestUtil.java:118)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4] Completed in 23.77s, 1 test, 1 error <<< FAILURES!

[...truncated 771 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:467: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:447: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:45: 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:496: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1298: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:922: There were test failures: 415 suites, 1704 tests, 1 suite-level error, 42 ignored (21 assumptions)

Total time: 107 minutes 1 second
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.8.0_20-ea-b15 -client -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any



Mime
View raw message