You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2014/02/06 23:02:53 UTC
[JENKINS] Lucene-Solr-4.x-Windows (64bit/jdk1.7.0_51) - Build #
3677 - Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Windows/3677/
Java: 64bit/jdk1.7.0_51 -XX:-UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.cloud.OverseerRolesTest.testDistribSearch
Error Message:
Captured an uncaught exception in thread: Thread[id=1347, name=Overseer-91207854058635280-127.0.0.1:64822_oxtnv%2Fk-n_0000000006, state=RUNNABLE, group=Overseer collection creation process.]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=1347, name=Overseer-91207854058635280-127.0.0.1:64822_oxtnv%2Fk-n_0000000006, state=RUNNABLE, group=Overseer collection creation process.]
Caused by: org.apache.solr.common.cloud.ZooKeeperException:
at __randomizedtesting.SeedInfo.seed([B40CC1ABBFDA04F3]:0)
at org.apache.solr.cloud.OverseerCollectionProcessor.run(OverseerCollectionProcessor.java:209)
at java.lang.Thread.run(Thread.java:744)
Caused by: org.apache.zookeeper.KeeperException$NoNodeException: KeeperErrorCode = NoNode for /overseer/collection-queue-work/qn-0000000004
at org.apache.zookeeper.KeeperException.create(KeeperException.java:111)
at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
at org.apache.zookeeper.ZooKeeper.delete(ZooKeeper.java:873)
at org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:174)
at org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:171)
at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
at org.apache.solr.common.cloud.SolrZkClient.delete(SolrZkClient.java:171)
at org.apache.solr.cloud.DistributedQueue.remove(DistributedQueue.java:193)
at org.apache.solr.cloud.OverseerCollectionProcessor.run(OverseerCollectionProcessor.java:200)
... 1 more
Build Log:
[...truncated 10130 lines...]
[junit4] Suite: org.apache.solr.cloud.OverseerRolesTest
[junit4] 2> 557308 T1191 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /oxtnv/k
[junit4] 2> 557313 T1191 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-OverseerRolesTest-1391721405914
[junit4] 2> 557317 T1191 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 557318 T1192 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 557417 T1191 oasc.ZkTestServer.run start zk server on port:64758
[junit4] 2> 557419 T1191 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 557427 T1198 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1ed33a3e name:ZooKeeperConnection Watcher:127.0.0.1:64758 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 557428 T1191 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 557428 T1191 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 557436 T1191 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 557439 T1200 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@23bf8c13 name:ZooKeeperConnection Watcher:127.0.0.1:64758/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 557440 T1191 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 557440 T1191 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 557445 T1191 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 557451 T1191 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 557455 T1191 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 557461 T1191 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 557461 T1191 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 557470 T1191 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4] 2> 557470 T1191 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 557476 T1191 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 557476 T1191 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 557482 T1191 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 557482 T1191 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 557490 T1191 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 557490 T1191 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 557496 T1191 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4] 2> 557496 T1191 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 557502 T1191 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 557502 T1191 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 557508 T1191 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 557508 T1191 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 557515 T1191 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 557515 T1191 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 557523 T1191 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 557523 T1191 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 557529 T1191 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 557529 T1191 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 557537 T1193 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x144090f5de40001, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:744)
[junit4] 2>
[junit4] 2> 558029 T1191 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 558099 T1191 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:64765
[junit4] 2> 558100 T1191 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 558100 T1191 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 558101 T1191 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.cloud.OverseerRolesTest-controljetty-1391721406136
[junit4] 2> 558101 T1191 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.OverseerRolesTest-controljetty-1391721406136\'
[junit4] 2> 558140 T1191 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-controljetty-1391721406136\solr.xml
[junit4] 2> 558231 T1191 oasc.ConfigSolrXml.<init> Config-defined core root directory:
[junit4] 2> 558232 T1191 oasc.CoreContainer.<init> New CoreContainer 841397452
[junit4] 2> 558232 T1191 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.cloud.OverseerRolesTest-controljetty-1391721406136\]
[junit4] 2> 558234 T1191 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 558235 T1191 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 558235 T1191 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 558235 T1191 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 558236 T1191 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 558236 T1191 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 558236 T1191 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 558236 T1191 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 558237 T1191 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 558243 T1191 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 558243 T1191 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 558243 T1191 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 558244 T1191 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:64758/solr
[junit4] 2> 558244 T1191 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 558246 T1191 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 558250 T1211 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@b27d6cd name:ZooKeeperConnection Watcher:127.0.0.1:64758 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 558251 T1191 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 558255 T1191 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 558259 T1213 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@607d0b9 name:ZooKeeperConnection Watcher:127.0.0.1:64758/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 558260 T1191 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 558263 T1191 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 558272 T1191 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 558279 T1191 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 558283 T1191 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64765_oxtnv%2Fk
[junit4] 2> 558284 T1191 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:64765_oxtnv%2Fk
[junit4] 2> 558291 T1191 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 558295 T1191 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 558306 T1191 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:64765_oxtnv%2Fk
[junit4] 2> 558306 T1191 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 558312 T1191 oasc.Overseer.start Overseer (id=91207854058635267-127.0.0.1:64765_oxtnv%2Fk-n_0000000000) starting
[junit4] 2> 558318 T1191 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 558326 T1215 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 558327 T1191 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 558328 T1215 oasc.OverseerCollectionProcessor.prioritizeOverseerNodes prioritizing overseer nodes
[junit4] 2> 558335 T1191 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 558339 T1191 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 558344 T1214 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 558348 T1191 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-controljetty-1391721406136
[junit4] 2> 558592 T1191 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-controljetty-1391721406136\conf\
[junit4] 2> 558604 T1191 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 558605 T1191 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 558605 T1191 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 558607 T1191 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 558611 T1218 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@60e11314 name:ZooKeeperConnection Watcher:127.0.0.1:64758/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 558611 T1191 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 558614 T1191 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 558618 T1191 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 559118 T1191 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
[junit4] 2> 559119 T1191 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 559124 T1191 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:64777
[junit4] 2> 559125 T1191 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 559125 T1191 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 559125 T1191 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty1-1391721407218
[junit4] 2> 559125 T1191 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty1-1391721407218\'
[junit4] 2> 559156 T1191 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty1-1391721407218\solr.xml
[junit4] 2> 559251 T1191 oasc.ConfigSolrXml.<init> Config-defined core root directory:
[junit4] 2> 559251 T1191 oasc.CoreContainer.<init> New CoreContainer 554625751
[junit4] 2> 559251 T1191 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty1-1391721407218\]
[junit4] 2> 559254 T1191 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 559254 T1191 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 559254 T1191 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 559254 T1191 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 559254 T1191 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 559254 T1191 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 559254 T1191 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 559254 T1191 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 559254 T1191 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 559260 T1191 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 559260 T1191 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 559260 T1191 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 559260 T1191 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:64758/solr
[junit4] 2> 559260 T1191 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 559264 T1191 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 559276 T1229 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5a5bda35 name:ZooKeeperConnection Watcher:127.0.0.1:64758 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 559277 T1191 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 559280 T1193 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x144090f5de40005, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:744)
[junit4] 2>
[junit4] 2> 559282 T1191 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 559286 T1231 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2eca3938 name:ZooKeeperConnection Watcher:127.0.0.1:64758/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 559286 T1191 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 559296 T1191 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 560292 T1191 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64777_oxtnv%2Fk
[junit4] 2> 560293 T1191 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:64777_oxtnv%2Fk
[junit4] 2> 560300 T1213 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 560302 T1231 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 560302 T1218 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 560313 T1191 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty1-1391721407218
[junit4] 2> 560545 T1191 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty1-1391721407218\conf\
[junit4] 2> 560559 T1191 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 560561 T1191 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 560561 T1191 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 561147 T1191 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
[junit4] 2> 561148 T1191 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 561153 T1191 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:64786
[junit4] 2> 561155 T1191 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 561155 T1191 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 561155 T1191 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.cloud.OverseerRolesTest-jetty2-1391721409161
[junit4] 2> 561155 T1191 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.OverseerRolesTest-jetty2-1391721409161\'
[junit4] 2> 561187 T1191 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty2-1391721409161\solr.xml
[junit4] 2> 561281 T1191 oasc.ConfigSolrXml.<init> Config-defined core root directory:
[junit4] 2> 561282 T1191 oasc.CoreContainer.<init> New CoreContainer 151486053
[junit4] 2> 561282 T1191 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.cloud.OverseerRolesTest-jetty2-1391721409161\]
[junit4] 2> 561284 T1191 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 561284 T1191 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 561285 T1191 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 561285 T1191 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 561285 T1191 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 561286 T1191 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 561286 T1191 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 561286 T1191 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 561287 T1191 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 561294 T1191 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 561295 T1191 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 561295 T1191 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 561296 T1191 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:64758/solr
[junit4] 2> 561297 T1191 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 561299 T1191 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 561308 T1243 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4afb1d84 name:ZooKeeperConnection Watcher:127.0.0.1:64758 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 561309 T1191 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 561313 T1193 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x144090f5de40007, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:744)
[junit4] 2>
[junit4] 2> 561313 T1191 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 561316 T1245 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@495f21ca name:ZooKeeperConnection Watcher:127.0.0.1:64758/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 561317 T1191 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 561328 T1191 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 562324 T1191 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64786_oxtnv%2Fk
[junit4] 2> 562326 T1191 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:64786_oxtnv%2Fk
[junit4] 2> 562332 T1213 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 562333 T1218 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 562333 T1245 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 562334 T1231 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 562348 T1191 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty2-1391721409161
[junit4] 2> 562613 T1191 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty2-1391721409161\conf\
[junit4] 2> 562632 T1191 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 562633 T1191 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 562634 T1191 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 563148 T1191 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3
[junit4] 2> 563148 T1191 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 563153 T1191 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:64795
[junit4] 2> 563156 T1191 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 563156 T1191 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 563156 T1191 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.cloud.OverseerRolesTest-jetty3-1391721411233
[junit4] 2> 563157 T1191 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.OverseerRolesTest-jetty3-1391721411233\'
[junit4] 2> 563190 T1191 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty3-1391721411233\solr.xml
[junit4] 2> 563286 T1191 oasc.ConfigSolrXml.<init> Config-defined core root directory:
[junit4] 2> 563286 T1191 oasc.CoreContainer.<init> New CoreContainer 586642660
[junit4] 2> 563286 T1191 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.cloud.OverseerRolesTest-jetty3-1391721411233\]
[junit4] 2> 563289 T1191 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 563289 T1191 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 563289 T1191 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 563289 T1191 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 563289 T1191 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 563289 T1191 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 563289 T1191 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 563289 T1191 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 563289 T1191 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 563295 T1191 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 563295 T1191 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 563295 T1191 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 563295 T1191 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:64758/solr
[junit4] 2> 563295 T1191 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 563299 T1191 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 563315 T1257 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@241b36ab name:ZooKeeperConnection Watcher:127.0.0.1:64758 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 563316 T1191 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 563320 T1193 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x144090f5de40009, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:744)
[junit4] 2>
[junit4] 2> 563321 T1191 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 563324 T1259 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6100957a name:ZooKeeperConnection Watcher:127.0.0.1:64758/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 563325 T1191 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 563334 T1191 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 564332 T1191 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64795_oxtnv%2Fk
[junit4] 2> 564334 T1191 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:64795_oxtnv%2Fk
[junit4] 2> 564341 T1213 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 564342 T1231 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 564342 T1245 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 564343 T1259 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 564343 T1218 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 564355 T1191 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty3-1391721411233
[junit4] 2> 564632 T1191 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty3-1391721411233\conf\
[junit4] 2> 564644 T1191 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 564645 T1191 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 564646 T1191 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 565150 T1191 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 4
[junit4] 2> 565151 T1191 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 565156 T1191 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:64804
[junit4] 2> 565158 T1191 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 565159 T1191 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 565159 T1191 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.cloud.OverseerRolesTest-jetty4-1391721413245
[junit4] 2> 565159 T1191 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.OverseerRolesTest-jetty4-1391721413245\'
[junit4] 2> 565192 T1191 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty4-1391721413245\solr.xml
[junit4] 2> 565284 T1191 oasc.ConfigSolrXml.<init> Config-defined core root directory:
[junit4] 2> 565284 T1191 oasc.CoreContainer.<init> New CoreContainer 999314408
[junit4] 2> 565284 T1191 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.cloud.OverseerRolesTest-jetty4-1391721413245\]
[junit4] 2> 565286 T1191 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 565287 T1191 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 565287 T1191 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 565287 T1191 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 565288 T1191 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 565288 T1191 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 565288 T1191 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 565288 T1191 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 565289 T1191 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 565294 T1191 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 565295 T1191 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 565295 T1191 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 565296 T1191 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:64758/solr
[junit4] 2> 565296 T1191 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 565298 T1191 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 565310 T1271 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@74c2e5 name:ZooKeeperConnection Watcher:127.0.0.1:64758 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 565311 T1191 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 565315 T1191 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 565319 T1273 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7c055db5 name:ZooKeeperConnection Watcher:127.0.0.1:64758/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 565320 T1191 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 565328 T1191 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 566334 T1191 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64804_oxtnv%2Fk
[junit4] 2> 566336 T1191 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:64804_oxtnv%2Fk
[junit4] 2> 566346 T1213 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 566347 T1259 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 566349 T1273 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 566350 T1218 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 566350 T1245 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 566351 T1231 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 566361 T1191 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty4-1391721413245
[junit4] 2> 566635 T1191 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty4-1391721413245\conf\
[junit4] 2> 566647 T1191 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 566648 T1191 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 566648 T1191 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 567177 T1191 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 5
[junit4] 2> 567178 T1191 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 567185 T1191 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:64813
[junit4] 2> 567187 T1191 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 567187 T1191 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 567187 T1191 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.cloud.OverseerRolesTest-jetty5-1391721415248
[junit4] 2> 567187 T1191 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.OverseerRolesTest-jetty5-1391721415248\'
[junit4] 2> 567230 T1191 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty5-1391721415248\solr.xml
[junit4] 2> 567319 T1191 oasc.ConfigSolrXml.<init> Config-defined core root directory:
[junit4] 2> 567319 T1191 oasc.CoreContainer.<init> New CoreContainer 1567531334
[junit4] 2> 567319 T1191 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.cloud.OverseerRolesTest-jetty5-1391721415248\]
[junit4] 2> 567322 T1191 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 567322 T1191 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 567322 T1191 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 567322 T1191 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 567322 T1191 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 567322 T1191 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 567322 T1191 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 567322 T1191 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 567322 T1191 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 567328 T1191 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 567329 T1191 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 567329 T1191 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 567329 T1191 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:64758/solr
[junit4] 2> 567329 T1191 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 567332 T1191 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 567336 T1285 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@a1e2a96 name:ZooKeeperConnection Watcher:127.0.0.1:64758 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 567337 T1191 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 567341 T1191 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 567344 T1287 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6f82ce0 name:ZooKeeperConnection Watcher:127.0.0.1:64758/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 567345 T1191 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 567352 T1191 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 568356 T1191 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64813_oxtnv%2Fk
[junit4] 2> 568358 T1191 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:64813_oxtnv%2Fk
[junit4] 2> 568364 T1259 oascc.ZkStateReader$3.process Updating live nodes... (6)
[junit4] 2> 568364 T1213 oascc.ZkStateReader$3.process Updating live nodes... (6)
[junit4] 2> 568365 T1273 oascc.ZkStateReader$3.process Updating live nodes... (6)
[junit4] 2> 568365 T1245 oascc.ZkStateReader$3.process Updating live nodes... (6)
[junit4] 2> 568365 T1231 oascc.ZkStateReader$3.process Updating live nodes... (6)
[junit4] 2> 568366 T1287 oascc.ZkStateReader$3.process Updating live nodes... (6)
[junit4] 2> 568366 T1218 oascc.ZkStateReader$3.process Updating live nodes... (6)
[junit4] 2> 568379 T1191 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty5-1391721415248
[junit4] 2> 568623 T1191 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty5-1391721415248\conf\
[junit4] 2> 568643 T1191 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 568644 T1191 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 568645 T1191 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 569144 T1191 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 6
[junit4] 2> 569145 T1191 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 569149 T1191 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:64822
[junit4] 2> 569151 T1191 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 569151 T1191 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 569151 T1191 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.cloud.OverseerRolesTest-jetty6-1391721417244
[junit4] 2> 569152 T1191 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.OverseerRolesTest-jetty6-1391721417244\'
[junit4] 2> 569198 T1191 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty6-1391721417244\solr.xml
[junit4] 2> 569281 T1191 oasc.ConfigSolrXml.<init> Config-defined core root directory:
[junit4] 2> 569281 T1191 oasc.CoreContainer.<init> New CoreContainer 329608395
[junit4] 2> 569281 T1191 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.cloud.OverseerRolesTest-jetty6-1391721417244\]
[junit4] 2> 569284 T1191 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 569284 T1191 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 569284 T1191 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 569284 T1191 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 569284 T1191 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 569284 T1191 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 569284 T1191 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 569284 T1191 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 569284 T1191 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 569291 T1191 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 569291 T1191 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 569291 T1191 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 569291 T1191 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:64758/solr
[junit4] 2> 569291 T1191 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 569294 T1191 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 569303 T1299 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@51898d80 name:ZooKeeperConnection Watcher:127.0.0.1:64758 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 569304 T1191 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 569308 T1193 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x144090f5de4000f, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:744)
[junit4] 2>
[junit4] 2> 569309 T1191 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 569313 T1301 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1fdddcd0 name:ZooKeeperConnection Watcher:127.0.0.1:64758/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 569313 T1191 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 569319 T1191 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 570325 T1191 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64822_oxtnv%2Fk
[junit4] 2> 570327 T1191 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:64822_oxtnv%2Fk
[junit4] 2> 570335 T1218 oascc.ZkStateReader$3.process Updating live nodes... (7)
[junit4] 2> 570336 T1301 oascc.ZkStateReader$3.process Updating live nodes... (7)
[junit4] 2> 570336 T1259 oascc.ZkStateReader$3.process Updating live nodes... (7)
[junit4] 2> 570337 T1213 oascc.ZkStateReader$3.process Updating live nodes... (7)
[junit4] 2> 570340 T1287 oascc.ZkStateReader$3.process Updating live nodes... (7)
[junit4] 2> 570341 T1231 oascc.ZkStateReader$3.process Updating live nodes... (7)
[junit4] 2> 570341 T1245 oascc.ZkStateReader$3.process Updating live nodes... (7)
[junit4] 2> 570341 T1273 oascc.ZkStateReader$3.process Updating live nodes... (7)
[junit4] 2> 570355 T1191 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty6-1391721417244
[junit4] 2> 570642 T1191 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty6-1391721417244\conf\
[junit4] 2> 570655 T1191 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 570656 T1191 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 570656 T1191 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 570659 T1191 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 570662 T1304 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@546a265b name:ZooKeeperConnection Watcher:127.0.0.1:64758/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 570664 T1191 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 570666 T1191 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 570672 T1191 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 570676 T1306 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7cfe5797 name:ZooKeeperConnection Watcher:127.0.0.1:64758/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 570677 T1191 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 570679 T1191 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 570685 T1255 oasha.CollectionsHandler.handleCreateAction Creating Collection : version=2&action=CREATE&wt=javabin&name=testOverseerCol&replicationFactor=2&maxShardsPerNode=2&numShards=4
[junit4] 2> 570687 T1213 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/collection-queue-work state: SyncConnected type NodeChildrenChanged
[junit4] 2> 570689 T1215 oasc.OverseerCollectionProcessor.run Overseer Collection Processor: Get the message id:/overseer/collection-queue-work/qn-0000000000 message:{
[junit4] 2> "operation":"createcollection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "name":"testOverseerCol",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "numShards":"4",
[junit4] 2> "maxShardsPerNode":"2"}
[junit4] 2> 570689 T1215 oasc.OverseerCollectionProcessor.processMessage WARN OverseerCollectionProcessor.processMessage : createcollection , {
[junit4] 2> "operation":"createcollection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "name":"testOverseerCol",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "numShards":"4",
[junit4] 2> "maxShardsPerNode":"2"}
[junit4] 2> 570694 T1215 oasc.OverseerCollectionProcessor.createConfNode Only one config set found in zk - using it:conf1
[junit4] 2> 570694 T1215 oasc.OverseerCollectionProcessor.createConfNode creating collections conf node /collections/testOverseerCol
[junit4] 2> 570694 T1215 oascc.SolrZkClient.makePath makePath: /collections/testOverseerCol
[junit4] 2> 570703 T1213 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 570707 T1214 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 570708 T1214 oasc.Overseer$ClusterStateUpdater.buildCollection building a new collection: testOverseerCol
[junit4] 2> 570708 T1214 oasc.Overseer$ClusterStateUpdater.createCollection Create collection testOverseerCol with shards [shard1, shard2, shard3, shard4]
[junit4] 2> 570713 T1213 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 570715 T1306 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 570717 T1218 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 570717 T1301 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 570717 T1259 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 570719 T1304 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 570718 T1213 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 570723 T1287 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 570722 T1273 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 570722 T1231 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 570722 T1245 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 570805 T1215 oasc.OverseerCollectionProcessor.createCollection going to create cores replicas shardNames [shard1, shard2, shard3, shard4] , repFactor : 2
[junit4] 2> 570805 T1215 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerCol_shard1_replica1 as part of slice shard1 of collection testOverseerCol on 127.0.0.1:64777_oxtnv%2Fk
[junit4] 2> 570806 T1215 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerCol_shard1_replica2 as part of slice shard1 of collection testOverseerCol on 127.0.0.1:64813_oxtnv%2Fk
[junit4] 2> 570806 T1215 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerCol_shard2_replica1 as part of slice shard2 of collection testOverseerCol on 127.0.0.1:64765_oxtnv%2Fk
[junit4] 2> 570809 T1215 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerCol_shard2_replica2 as part of slice shard2 of collection testOverseerCol on 127.0.0.1:64804_oxtnv%2Fk
[junit4] 2> 570810 T1215 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerCol_shard3_replica1 as part of slice shard3 of collection testOverseerCol on 127.0.0.1:64822_oxtnv%2Fk
[junit4] 2> 570811 T1215 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerCol_shard3_replica2 as part of slice shard3 of collection testOverseerCol on 127.0.0.1:64795_oxtnv%2Fk
[junit4] 2> 570814 T1215 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerCol_shard4_replica1 as part of slice shard4 of collection testOverseerCol on 127.0.0.1:64786_oxtnv%2Fk
[junit4] 2> 570814 T1215 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerCol_shard4_replica2 as part of slice shard4 of collection testOverseerCol on 127.0.0.1:64777_oxtnv%2Fk
[junit4] 2> 570817 T1209 oasc.ZkController.publish publishing core=testOverseerCol_shard2_replica1 state=down
[junit4] 2> 570817 T1278 oasc.ZkController.publish publishing core=testOverseerCol_shard1_replica2 state=down
[junit4] 2> 570820 T1226 oasc.ZkController.publish publishing core=testOverseerCol_shard1_replica1 state=down
[junit4] 2> 570830 T1227 oasc.ZkController.publish publishing core=testOverseerCol_shard4_replica2 state=down
[junit4] 2> 570824 T1269 oasc.ZkController.publish publishing core=testOverseerCol_shard2_replica2 state=down
[junit4] 2> 570834 T1294 oasc.ZkController.publish publishing core=testOverseerCol_shard3_replica1 state=down
[junit4] 2> 570837 T1250 oasc.ZkController.publish publishing core=testOverseerCol_shard3_replica2 state=down
[junit4] 2> 570837 T1237 oasc.ZkController.publish publishing core=testOverseerCol_shard4_replica1 state=down
[junit4] 2> 570838 T1209 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 570839 T1278 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 570839 T1213 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 570840 T1226 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 570842 T1294 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 570842 T1227 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 570842 T1269 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 570843 T1250 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 570844 T1237 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 570846 T1214 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 570848 T1214 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64765/oxtnv/k",
[junit4] 2> "core":"testOverseerCol_shard2_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:64765_oxtnv%2Fk",
[junit4] 2> "shard":"shard2",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"testOverseerCol",
[junit4] 2> "numShards":"4",
[junit4] 2> "core_node_name":null}
[junit4] 2> 570853 T1213 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 570855 T1214 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64813/oxtnv/k",
[junit4] 2> "core":"testOverseerCol_shard1_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:64813_oxtnv%2Fk",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"testOverseerCol",
[junit4] 2> "numShards":"4",
[junit4] 2> "core_node_name":null}
[junit4] 2> 570860 T1213 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 570863 T1214 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64777/oxtnv/k",
[junit4] 2> "core":"testOverseerCol_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:64777_oxtnv%2Fk",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"testOverseerCol",
[junit4] 2> "numShards":"4",
[junit4] 2> "core_node_name":null}
[junit4] 2> 570868 T1213 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 570870 T1214 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64777/oxtnv/k",
[junit4] 2> "core":"testOverseerCol_shard4_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:64777_oxtnv%2Fk",
[junit4] 2> "shard":"shard4",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"testOverseerCol",
[junit4] 2> "numShards":"4",
[junit4] 2> "core_node_name":null}
[junit4] 2> 570876 T1213 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 570878 T1214 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64804/oxtnv/k",
[junit4] 2> "core":"testOverseerCol_shard2_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:64804_oxtnv%2Fk",
[junit4] 2> "shard":"shard2",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"testOverseerCol",
[junit4] 2> "numShards":"4",
[junit4] 2> "core_node_name":null}
[junit4] 2> 570883 T1213 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 570886 T1214 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64822/oxtnv/k",
[junit4] 2> "core":"testOverseerCol_shard3_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:64822_oxtnv%2Fk",
[junit4] 2> "shard":"shard3",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"testOverseerCol",
[junit4] 2> "numShards":"4",
[junit4] 2> "core_node_name":null}
[junit4] 2> 570891 T1213 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 570893 T1214 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64795/oxtnv/k",
[junit4] 2> "core":"testOverseerCol_shard3_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:64795_oxtnv%2Fk",
[junit4] 2> "shard":"shard3",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"testOverseerCol",
[junit4] 2> "numShards":"4",
[junit4] 2> "core_node_name":null}
[junit4] 2> 570898 T1213 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 570900 T1214 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64786/oxtnv/k",
[junit4] 2> "core":"testOverseerCol_shard4_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:64786_oxtnv%2Fk",
[junit4] 2> "shard":"shard4",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"testOverseerCol",
[junit4] 2> "numShards":"4",
[junit4] 2> "core_node_name":null}
[junit4] 2> 570906 T1213 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 571012 T1287 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 571012 T1231 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 571012 T1245 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 571013 T1273 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 571013 T1213 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 571013 T1304 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 571013 T1306 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 571013 T1218 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 571013 T1259 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 571013 T1301 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 571839 T1278 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerCol_shard1_replica2
[junit4] 2> 571839 T1209 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerCol_shard2_replica1
[junit4] 2> 571841 T1226 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerCol_shard1_replica1
[junit4] 2> 571843 T1209 oasc.CoreContainer.create Creating SolrCore 'testOverseerCol_shard2_replica1' using instanceDir: .\org.apache.solr.cloud.OverseerRolesTest-controljetty-1391721406136\testOverseerCol_shard2_replica1
[junit4] 2> 571843 T1209 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerCol
[junit4] 2> 571844 T1227 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerCol_shard4_replica2
[junit4] 2> 571844 T1226 oasc.CoreContainer.create Creating SolrCore 'testOverseerCol_shard1_replica1' using instanceDir: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty1-1391721407218\testOverseerCol_shard1_replica1
[junit4] 2> 571844 T1226 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerCol
[junit4] 2> 571845 T1294 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerCol_shard3_replica1
[junit4] 2> 571845 T1209 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 571845 T1226 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 571846 T1209 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerCol
[junit4] 2> 571846 T1226 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerCol
[junit4] 2> 571849 T1226 oascc.ZkStateReader.readConfigName path=/collections/testOverseerCol configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 571849 T1226 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty1-1391721407218\testOverseerCol_shard1_replica1\'
[junit4] 2> 571850 T1269 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerCol_shard2_replica2
[junit4] 2> 571851 T1269 oasc.CoreContainer.create Creating SolrCore 'testOverseerCol_shard2_replica2' using instanceDir: .\org.apache.solr.cloud.OverseerRolesTest-jetty4-1391721413245\testOverseerCol_shard2_replica2
[junit4] 2> 571853 T1269 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerCol
[junit4] 2> 571854 T1237 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerCol_shard4_replica1
[junit4] 2> 571856 T1237 oasc.CoreContainer.create Creating SolrCore 'testOverseerCol_shard4_replica1' using instanceDir: .\org.apache.solr.cloud.OverseerRolesTest-jetty2-1391721409161\testOverseerCol_shard4_replica1
[junit4] 2> 571857 T1237 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerCol
[junit4] 2> 571849 T1227 oasc.CoreContainer.create Creating SolrCore 'testOverseerCol_shard4_replica2' using instanceDir: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty1-1391721407218\testOverseerCol_shard4_replica2
[junit4] 2> 571857 T1227 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerCol
[junit4] 2> 571858 T1209 oascc.ZkStateReader.readConfigName path=/collections/testOverseerCol configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 571860 T1250 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerCol_shard3_replica2
[junit4] 2> 571860 T1278 oasc.CoreContainer.create Creating SolrCore 'testOverseerCol_shard1_replica2' using instanceDir: .\org.apache.solr.cloud.OverseerRolesTest-jetty5-1391721415248\testOverseerCol_shard1_replica2
[junit4] 2> 571860 T1278 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerCol
[junit4] 2> 571863 T1209 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.OverseerRolesTest-controljetty-1391721406136\testOverseerCol_shard2_replica1\'
[junit4] 2> 571865 T1250 oasc.CoreContainer.create Creating SolrCore 'testOverseerCol_shard3_replica2' using instanceDir: .\org.apache.solr.cloud.OverseerRolesTest-jetty3-1391721411233\testOverseerCol_shard3_replica2
[junit4] 2> 571865 T1250 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerCol
[junit4] 2> 571868 T1294 oasc.CoreContainer.create Creating SolrCore 'testOverseerCol_shard3_replica1' using instanceDir: .\org.apache.solr.cloud.OverseerRolesTest-jetty6-1391721417244\testOverseerCol_shard3_replica1
[junit4] 2> 571869 T1294 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerCol
[junit4] 2> 571873 T1237 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 571873 T1237 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerCol
[junit4] 2> 571874 T1227 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 571874 T1227 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerCol
[junit4] 2> 571875 T1269 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 571875 T1269 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerCol
[junit4] 2> 571879 T1250 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 571879 T1250 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerCol
[junit4] 2> 571880 T1278 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 571880 T1278 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerCol
[junit4] 2> 571885 T1294 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 571885 T1294 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerCol
[junit4] 2> 571891 T1237 oascc.ZkStateReader.readConfigName path=/collections/testOverseerCol configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 571892 T1278 oascc.ZkStateReader.readConfigName path=/collections/testOverseerCol configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 571892 T1278 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.OverseerRolesTest-jetty5-1391721415248\testOverseerCol_shard1_replica2\'
[junit4] 2> 571892 T1269 oascc.ZkStateReader.readConfigName path=/collections/testOverseerCol configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 571893 T1269 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.OverseerRolesTest-jetty4-1391721413245\testOverseerCol_shard2_replica2\'
[junit4] 2> 571892 T1227 oascc.ZkStateReader.readConfigName path=/collections/testOverseerCol configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 571892 T1250 oascc.ZkStateReader.readConfigName path=/collections/testOverseerCol configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 571892 T1237 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.OverseerRolesTest-jetty2-1391721409161\testOverseerCol_shard4_replica1\'
[junit4] 2> 571894 T1250 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.OverseerRolesTest-jetty3-1391721411233\testOverseerCol_shard3_replica2\'
[junit4] 2> 571894 T1227 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty1-1391721407218\testOverseerCol_shard4_replica2\'
[junit4] 2> 571893 T1294 oascc.ZkStateReader.readConfigName path=/collections/testOverseerCol configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 571895 T1294 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.OverseerRolesTest-jetty6-1391721417244\testOverseerCol_shard3_replica1\'
[junit4] 2> 572042 T1227 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 572054 T1226 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 572130 T1227 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 572158 T1227 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 572159 T1226 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 572175 T1209 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 572186 T1227 oass.IndexSchema.readSchema [testOverseerCol_shard4_replica2] Schema name=test
[junit4] 2> 572243 T1226 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 572257 T1226 oass.IndexSchema.readSchema [testOverseerCol_shard1_replica1] Schema name=test
[junit4] 2> 572290 T1250 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 572469 T1209 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 572632 T1278 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 572650 T1269 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 572666 T1209 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 572680 T1250 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 572686 T1294 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 572730 T1209 oass.IndexSchema.readSchema [testOverseerCol_shard2_replica1] Schema name=test
[junit4] 2> 572818 T1237 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 572918 T1237 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 572919 T1269 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 572924 T1278 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 572949 T1294 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 572950 T1250 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 572996 T1250 oass.IndexSchema.readSchema [testOverseerCol_shard3_replica2] Schema name=test
[junit4] 2> 573117 T1237 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 573130 T1237 oass.IndexSchema.readSchema [testOverseerCol_shard4_replica1] Schema name=test
[junit4] 2> 573192 T1278 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 573193 T1269 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 573203 T1278 oass.IndexSchema.readSchema [testOverseerCol_shard1_replica2] Schema name=test
[junit4] 2> 573204 T1269 oass.IndexSchema.readSchema [testOverseerCol_shard2_replica2] Schema name=test
[junit4] 2> 573306 T1294 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 573319 T1294 oass.IndexSchema.readSchema [testOverseerCol_shard3_replica1] Schema name=test
[junit4] 2> 574710 T1226 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 574710 T1226 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 574711 T1226 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 574721 T1226 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 574726 T1226 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 575064 T1226 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 575077 T1226 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 575093 T1226 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 575101 T1226 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 575101 T1226 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 575101 T1226 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 575106 T1226 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 575107 T1226 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 575107 T1226 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 575108 T1226 oasc.SolrCore.<init> [testOverseerCol_shard1_replica1] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty1-1391721407218\testOverseerCol_shard1_replica1\, dataDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty1-1391721407218\testOverseerCol_shard1_replica1\data\
[junit4] 2> 575108 T1226 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@92572fe
[junit4] 2> 575110 T1226 oasc.CachingDirectoryFactory.get return new directory for ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty1-1391721407218\testOverseerCol_shard1_replica1\data\
[junit4] 2> 575110 T1226 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty1-1391721407218\testOverseerCol_shard1_replica1\data\index/
[junit4] 2> 575110 T1226 oasc.SolrCore.initIndex WARN [testOverseerCol_shard1_replica1] Solr index directory '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty1-1391721407218\testOverseerCol_shard1_replica1\data\index' doesn't exist. Creating new index...
[junit4] 2> 575111 T1226 oasc.CachingDirectoryFactory.get return new directory for ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.OverseerRolesTest-jetty1-1391721407218\testOverseerCol_shard1_replica1\data\index
[junit4] 2> 575112 T1226 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=24, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.29553880261377896]
[junit4] 2> 575112 T1226 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7a8fbc23 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6fdea9c5),segFN=segments_1,generation=1}
[junit4] 2> 575113 T1226 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 575123 T1226 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 575124 T1226 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 575128 T1226 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 575128 T1226 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 575129 T1226 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 575129 T1226 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 575130 T1226 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 575130 T1226 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 575130 T1226 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 575132 T1226 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 575132 T1226 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 575132 T1226 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 575133 T1226 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 575135 T1226 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 575136 T1226 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 575137 T1226 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 575356 T1226 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 575377 T1226 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 575378 T1226 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 575533 T1227 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 575534 T1227 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 575535 T1227 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 575545 T1227 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 575550 T1227 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 575688 T1226 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=393461406, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 575899 T1226 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapp
[...truncated too long message...]
number=0}
[junit4] 2> 584651 T1301 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 584651 T1301 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 584651 T1301 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 584652 T1301 oasc.SolrCore.closeSearcher [testOverseerCol_shard3_replica1] Closing main searcher on request.
[junit4] 2> 584653 T1301 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 584653 T1301 oasc.CachingDirectoryFactory.closeCacheValue looking to close .\org.apache.solr.cloud.OverseerRolesTest-jetty6-1391721417244\testOverseerCol_shard3_replica1\data\index [CachedDir<<refCount=0;path=.\org.apache.solr.cloud.OverseerRolesTest-jetty6-1391721417244\testOverseerCol_shard3_replica1\data\index;done=false>>]
[junit4] 2> 584653 T1301 oasc.CachingDirectoryFactory.close Closing directory: .\org.apache.solr.cloud.OverseerRolesTest-jetty6-1391721417244\testOverseerCol_shard3_replica1\data\index
[junit4] 2> 584653 T1301 oasc.CachingDirectoryFactory.closeCacheValue looking to close .\org.apache.solr.cloud.OverseerRolesTest-jetty6-1391721417244\testOverseerCol_shard3_replica1\data\ [CachedDir<<refCount=0;path=.\org.apache.solr.cloud.OverseerRolesTest-jetty6-1391721417244\testOverseerCol_shard3_replica1\data\;done=false>>]
[junit4] 2> 584653 T1301 oasc.CachingDirectoryFactory.close Closing directory: .\org.apache.solr.cloud.OverseerRolesTest-jetty6-1391721417244\testOverseerCol_shard3_replica1\data\
[junit4] 2> 584654 T1301 oascc.SolrZkClient.makePath makePath: /collections/testOverseerCol/leaders/shard3
[junit4] 2> 584654 T1301 oasc.SolrException.log ERROR There was a problem trying to register as the leader:org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections
[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.exists(ZooKeeper.java:1041)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.execute(SolrZkClient.java:206)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.execute(SolrZkClient.java:203)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:203)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:414)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:383)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:370)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContextBase.runLeaderProcess(ElectionContext.java:112)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:273)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:164)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:108)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:55)
[junit4] 2> at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:137)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:519)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:495)
[junit4] 2>
[junit4] 2> 584655 T1301 oasc.LeaderElector$1.process WARN org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/testOverseerCol/leader_elect/shard3/election/91207854058635280-core_node6-n_0000000001
[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.delete(ZooKeeper.java:873)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:174)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:171)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.delete(SolrZkClient.java:171)
[junit4] 2> at org.apache.solr.cloud.ElectionContext.cancelElection(ElectionContext.java:67)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:293)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:164)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:108)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:55)
[junit4] 2> at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:137)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:519)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:495)
[junit4] 2>
[junit4] 2> 584655 T1301 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 584656 T1301 oasc.LeaderElector$1.process WARN org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/election
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1468)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:257)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:254)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:254)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:92)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:55)
[junit4] 2> at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:137)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:519)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:495)
[junit4] 2>
[junit4] 2> NOTE: test params are: codec=Lucene40, sim=DefaultSimilarity, locale=ar_SY, timezone=Asia/Yekaterinburg
[junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.7.0_51 (64-bit)/cpus=2,threads=10,free=223348384,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestLMJelinekMercerSimilarityFactory, DefaultValueUpdateProcessorTest, TestAddFieldRealTimeGet, TestZkChroot, TestUpdate, CircularListTest, TestUniqueKeyFieldResource, TestIndexSearcher, TestRangeQuery, TestSolrIndexConfig, TestRandomMergePolicy, TestTrie, MoreLikeThisHandlerTest, SoftAutoCommitTest, TestJmxIntegration, TestRTGBase, ChangedSchemaMergeTest, SpellCheckComponentTest, TestFunctionQuery, SliceStateUpdateTest, TestPhraseSuggestions, TestStandardQParsers, TestSolr4Spatial, TestCSVResponseWriter, FileUtilsTest, DocumentBuilderTest, TestCursorMarkWithoutUniqueKey, SpellPossibilityIteratorTest, TestDocumentBuilder, ClusterStateTest, TestCharFilters, JSONWriterTest, TestReloadAndDeleteDocs, ZkCLITest, DisMaxRequestHandlerTest, DistribCursorPagingTest, TestSolrJ, MultiTermTest, HdfsBasicDistributedZk2Test, TestStressUserVersions, ZkNodePropsTest, TestCoreDiscovery, TestDefaultSimilarityFactory, SolrIndexSplitterTest, ZkControllerTest, TestStressRecovery, CollectionsAPIDistributedZkTest, CoreMergeIndexesAdminHandlerTest, BlockDirectoryTest, NumericFieldsTest, TestArbitraryIndexDir, StandardRequestHandlerTest, TestSearchPerf, TestRemoteStreaming, MinimalSchemaTest, OverseerRolesTest]
[junit4] Completed in 28.45s, 1 test, 1 error <<< FAILURES!
[...truncated 926 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:459: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:439: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\extra-targets.xml:37: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build.xml:189: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\common-build.xml:491: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1308: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:941: There were test failures: 358 suites, 1554 tests, 1 error, 40 ignored (12 assumptions)
Total time: 84 minutes 29 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.7.0_51 -XX:-UseCompressedOops -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure