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/06/14 19:15:39 UTC
[JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.7.0_60) - Build #
4115 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/4115/
Java: 32bit/jdk1.7.0_60 -server -XX:+UseParallelGC
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.TestMiniSolrCloudCluster
Error Message:
Could not remove the following files (in the order of attempts): C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\testSolrCloudCollection_shard1_replica1\data\tlog\tlog.0000000000000000000 C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\testSolrCloudCollection_shard1_replica1\data\tlog C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\testSolrCloudCollection_shard1_replica1\data C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\testSolrCloudCollection_shard1_replica1 C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\testSolrCloudCollection_shard1_replica1\data\tlog\tlog.0000000000000000000
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\testSolrCloudCollection_shard1_replica1\data\tlog
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\testSolrCloudCollection_shard1_replica1\data
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\testSolrCloudCollection_shard1_replica1
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001
at __randomizedtesting.SeedInfo.seed([766FE4C41ED2E910]:0)
at org.apache.lucene.util.TestUtil.rm(TestUtil.java:118)
at org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:125)
at com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:360)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11895 lines...]
[junit4] Suite: org.apache.solr.cloud.TestMiniSolrCloudCluster
[junit4] 2> 3772323 T12892 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 3772324 T12893 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 3772423 T12892 oasc.ZkTestServer.run start zk server on port:64213
[junit4] 2> 3772430 T12892 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3772435 T12899 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@67be30 name:ZooKeeperConnection Watcher:127.0.0.1:64213 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3772435 T12892 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3772436 T12892 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 3772443 T12892 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 3772443 T12894 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1469b53de5d0000, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 3772450 T12892 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:64217
[junit4] 2> 3772450 T12892 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 3772450 T12892 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3772451 T12892 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001
[junit4] 2> 3772451 T12892 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\'
[junit4] 2> 3772483 T12892 oass.SolrDispatchFilter.loadConfigSolr Trying to read solr.xml from 127.0.0.1:64213/solr
[junit4] 2> 3772485 T12892 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3772487 T12910 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@188312 name:ZooKeeperConnection Watcher:127.0.0.1:64213/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3772488 T12892 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3772546 T12892 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001
[junit4] 2> 3772548 T12892 oasc.CoreContainer.<init> New CoreContainer 4049592
[junit4] 2> 3772548 T12894 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1469b53de5d0001, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 3772548 T12892 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\]
[junit4] 2> 3772551 T12892 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 3772551 T12892 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 3772551 T12892 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 3772551 T12892 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 3772552 T12892 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 3772552 T12892 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 3772552 T12892 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 3772552 T12892 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 3772553 T12892 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 3772553 T12892 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3772554 T12892 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3772554 T12892 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 3772554 T12892 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:64213/solr
[junit4] 2> 3772554 T12892 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 3772556 T12892 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3772558 T12912 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@41fe14 name:ZooKeeperConnection Watcher:127.0.0.1:64213 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3772559 T12892 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3772561 T12894 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1469b53de5d0002, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 3772563 T12892 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3772565 T12914 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@b81c9a name:ZooKeeperConnection Watcher:127.0.0.1:64213/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3772565 T12892 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3772568 T12892 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 3772573 T12892 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 3772581 T12892 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
[junit4] 2> 3772588 T12892 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
[junit4] 2> 3772593 T12892 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
[junit4] 2> 3772600 T12892 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 3772603 T12892 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64217_solr
[junit4] 2> 3772605 T12892 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:64217_solr
[junit4] 2> 3772609 T12892 oascc.SolrZkClient.makePath makePath: /collections
[junit4] 2> 3772613 T12892 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 3772617 T12892 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 3772624 T12892 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:64217_solr
[junit4] 2> 3772625 T12892 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 3772629 T12892 oasc.Overseer.start Overseer (id=91931626922901507-127.0.0.1:64217_solr-n_0000000000) starting
[junit4] 2> 3772635 T12892 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 3772657 T12916 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 3772658 T12892 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 3772663 T12892 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 3772666 T12892 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 3772670 T12915 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 3772674 T12892 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001
[junit4] 2> 3772682 T12892 oasc.CorePropertiesLocator.discover Found 0 core definitions
[junit4] 2> 3772684 T12892 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 3772684 T12892 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 3772684 T12892 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 3772690 T12892 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:64229
[junit4] 2> 3772690 T12892 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 3772690 T12892 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3772691 T12892 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001
[junit4] 2> 3772691 T12892 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\'
[junit4] 2> 3772706 T12892 oass.SolrDispatchFilter.loadConfigSolr Trying to read solr.xml from 127.0.0.1:64213/solr
[junit4] 2> 3772708 T12892 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3772710 T12928 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@304383 name:ZooKeeperConnection Watcher:127.0.0.1:64213/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3772710 T12892 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3772758 T12892 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001
[junit4] 2> 3772760 T12892 oasc.CoreContainer.<init> New CoreContainer 13275162
[junit4] 2> 3772761 T12892 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\]
[junit4] 2> 3772762 T12892 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 3772762 T12892 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 3772762 T12892 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 3772762 T12892 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 3772762 T12892 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 3772762 T12892 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 3772762 T12892 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 3772764 T12892 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 3772765 T12892 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 3772765 T12892 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3772765 T12892 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3772765 T12892 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 3772765 T12892 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:64213/solr
[junit4] 2> 3772765 T12892 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 3772768 T12892 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3772770 T12930 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@bab3fc name:ZooKeeperConnection Watcher:127.0.0.1:64213 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3772770 T12892 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3772773 T12892 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3772775 T12932 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@14b4831 name:ZooKeeperConnection Watcher:127.0.0.1:64213/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3772775 T12892 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3772787 T12892 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 3773777 T12892 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64229_solr
[junit4] 2> 3773782 T12892 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:64229_solr
[junit4] 2> 3773804 T12892 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001
[junit4] 2> 3773804 T12892 oasc.CorePropertiesLocator.discover Found 0 core definitions
[junit4] 2> 3773808 T12892 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 3773809 T12892 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 3773810 T12892 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 3773828 T12892 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:64241
[junit4] 2> 3773829 T12892 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 3773829 T12892 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3773830 T12892 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001
[junit4] 2> 3773831 T12892 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\'
[junit4] 2> 3773851 T12892 oass.SolrDispatchFilter.loadConfigSolr Trying to read solr.xml from 127.0.0.1:64213/solr
[junit4] 2> 3773853 T12892 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3773855 T12944 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@c0c2e7 name:ZooKeeperConnection Watcher:127.0.0.1:64213/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3773856 T12892 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3773909 T12892 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001
[junit4] 2> 3773911 T12892 oasc.CoreContainer.<init> New CoreContainer 23516416
[junit4] 2> 3773911 T12892 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\]
[junit4] 2> 3773912 T12892 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 3773912 T12892 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 3773912 T12892 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 3773912 T12892 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 3773912 T12892 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 3773912 T12892 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 3773912 T12892 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 3773914 T12892 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 3773914 T12892 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 3773914 T12892 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3773914 T12892 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3773914 T12892 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 3773914 T12892 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:64213/solr
[junit4] 2> 3773916 T12892 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 3773918 T12892 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3773921 T12946 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2a290f name:ZooKeeperConnection Watcher:127.0.0.1:64213 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3773921 T12892 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3773925 T12892 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3773927 T12948 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@19e163f name:ZooKeeperConnection Watcher:127.0.0.1:64213/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3773927 T12892 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3773936 T12892 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 3774921 T12892 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64241_solr
[junit4] 2> 3774925 T12892 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:64241_solr
[junit4] 2> 3774948 T12892 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001
[junit4] 2> 3774948 T12892 oasc.CorePropertiesLocator.discover Found 0 core definitions
[junit4] 2> 3774952 T12892 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 3774952 T12892 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 3774953 T12892 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 3774962 T12892 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:64253
[junit4] 2> 3774963 T12892 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 3774964 T12892 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3774964 T12892 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001
[junit4] 2> 3774965 T12892 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\'
[junit4] 2> 3774986 T12892 oass.SolrDispatchFilter.loadConfigSolr Trying to read solr.xml from 127.0.0.1:64213/solr
[junit4] 2> 3774988 T12892 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3774990 T12960 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@186518a name:ZooKeeperConnection Watcher:127.0.0.1:64213/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3774991 T12892 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3775045 T12892 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001
[junit4] 2> 3775046 T12892 oasc.CoreContainer.<init> New CoreContainer 26702574
[junit4] 2> 3775047 T12892 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\]
[junit4] 2> 3775048 T12892 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 3775048 T12892 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 3775048 T12892 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 3775048 T12892 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 3775048 T12892 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 3775048 T12892 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 3775048 T12892 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 3775048 T12892 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 3775048 T12892 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 3775051 T12892 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3775051 T12892 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3775051 T12892 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 3775052 T12892 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:64213/solr
[junit4] 2> 3775052 T12892 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 3775054 T12892 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3775057 T12962 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1fa82be name:ZooKeeperConnection Watcher:127.0.0.1:64213 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3775058 T12892 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3775062 T12894 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1469b53de5d000b, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 3775063 T12892 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3775065 T12964 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@10e58d0 name:ZooKeeperConnection Watcher:127.0.0.1:64213/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3775065 T12892 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3775079 T12892 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 3776064 T12892 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64253_solr
[junit4] 2> 3776068 T12892 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:64253_solr
[junit4] 2> 3776093 T12892 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001
[junit4] 2> 3776096 T12892 oasc.CorePropertiesLocator.discover Found 0 core definitions
[junit4] 2> 3776099 T12892 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 3776099 T12892 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 3776101 T12892 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 3776110 T12892 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:64265
[junit4] 2> 3776110 T12892 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 3776110 T12892 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3776110 T12892 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001
[junit4] 2> 3776110 T12892 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\'
[junit4] 2> 3776135 T12892 oass.SolrDispatchFilter.loadConfigSolr Trying to read solr.xml from 127.0.0.1:64213/solr
[junit4] 2> 3776137 T12892 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3776138 T12976 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@189b8a7 name:ZooKeeperConnection Watcher:127.0.0.1:64213/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3776139 T12892 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3776197 T12892 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001
[junit4] 2> 3776199 T12892 oasc.CoreContainer.<init> New CoreContainer 2710324
[junit4] 2> 3776199 T12892 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\]
[junit4] 2> 3776200 T12892 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 3776200 T12892 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 3776200 T12892 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 3776201 T12892 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 3776201 T12892 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 3776201 T12892 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 3776201 T12892 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 3776201 T12892 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 3776201 T12892 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 3776204 T12892 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3776204 T12892 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3776204 T12892 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 3776204 T12892 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:64213/solr
[junit4] 2> 3776204 T12892 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 3776207 T12892 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3776208 T12978 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@122d4db name:ZooKeeperConnection Watcher:127.0.0.1:64213 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3776209 T12892 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3776212 T12892 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3776214 T12980 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1dee6d4 name:ZooKeeperConnection Watcher:127.0.0.1:64213/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3776214 T12892 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3776225 T12892 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 3777209 T12892 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64265_solr
[junit4] 2> 3777213 T12892 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:64265_solr
[junit4] 2> 3777239 T12892 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001
[junit4] 2> 3777242 T12892 oasc.CorePropertiesLocator.discover Found 0 core definitions
[junit4] 2> 3777245 T12892 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 3777245 T12892 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 3777317 T12892 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=4049592
[junit4] 2> 3777321 T12915 oasc.Overseer$ClusterStateUpdater.run Overseer Loop exiting : 127.0.0.1:64217_solr
[junit4] 2> 3777327 T12892 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 3777328 T12894 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1469b53de5d0003, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 3777335 T12932 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:64229_solr
[junit4] 2> 3777336 T12932 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 3777343 T12932 oasc.Overseer.start Overseer (id=91931626922901510-127.0.0.1:64229_solr-n_0000000001) starting
[junit4] 2> 3777382 T12892 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 3777388 T12985 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 3777392 T12983 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 3777397 T12892 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:64277
[junit4] 2> 3777399 T12892 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 3777399 T12892 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3777400 T12892 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001
[junit4] 2> 3777400 T12892 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\'
[junit4] 2> 3777415 T12892 oass.SolrDispatchFilter.loadConfigSolr Trying to read solr.xml from 127.0.0.1:64213/solr
[junit4] 2> 3777417 T12892 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3777419 T12995 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@161b80f name:ZooKeeperConnection Watcher:127.0.0.1:64213/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3777420 T12892 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3777469 T12892 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001
[junit4] 2> 3777472 T12892 oasc.CoreContainer.<init> New CoreContainer 1672937
[junit4] 2> 3777472 T12894 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1469b53de5d0010, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 3777472 T12892 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\]
[junit4] 2> 3777475 T12892 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 3777475 T12892 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 3777476 T12892 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 3777477 T12892 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 3777477 T12892 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 3777477 T12892 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 3777477 T12892 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 3777477 T12892 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 3777477 T12892 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 3777478 T12892 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3777478 T12892 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3777479 T12892 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 3777479 T12892 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:64213/solr
[junit4] 2> 3777480 T12892 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 3777481 T12892 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3777485 T12997 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1d9a82 name:ZooKeeperConnection Watcher:127.0.0.1:64213 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3777485 T12892 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3777487 T12894 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1469b53de5d0011, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 3777488 T12892 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3777490 T12999 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@d497fb name:ZooKeeperConnection Watcher:127.0.0.1:64213/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3777491 T12892 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3777501 T12892 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 3778485 T12892 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64277_solr
[junit4] 2> 3778490 T12892 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:64277_solr
[junit4] 2> 3778522 T12892 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001
[junit4] 2> 3778522 T12892 oasc.CorePropertiesLocator.discover Found 0 core definitions
[junit4] 2> 3778526 T12892 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 3778526 T12892 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 3778529 T12892 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3778534 T13002 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1e55f83 name:ZooKeeperConnection Watcher:127.0.0.1:64213/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3778534 T12892 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3778537 T12892 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 3778547 T12892 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3778550 T13004 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c4fe03 name:ZooKeeperConnection Watcher:127.0.0.1:64213/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3778552 T12892 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3778556 T12892 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3778559 T13006 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@44a735 name:ZooKeeperConnection Watcher:127.0.0.1:64213/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3778560 T12892 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3778562 T12892 oascc.SolrZkClient.makePath makePath: /configs/solrCloudCollectionConfig/solrconfig.xml
[junit4] 2> 3778574 T12892 oascc.SolrZkClient.makePath makePath: /configs/solrCloudCollectionConfig/schema.xml
[junit4] 2> 3778585 T12892 oascc.SolrZkClient.makePath makePath: /configs/solrCloudCollectionConfig/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 3778593 T12892 oascc.SolrZkClient.makePath makePath: /configs/solrCloudCollectionConfig/currency.xml
[junit4] 2> 3778602 T12892 oascc.SolrZkClient.makePath makePath: /configs/solrCloudCollectionConfig/mapping-ISOLatin1Accent.txt
[junit4] 2> 3778612 T12892 oascc.SolrZkClient.makePath makePath: /configs/solrCloudCollectionConfig/old_synonyms.txt
[junit4] 2> 3778620 T12892 oascc.SolrZkClient.makePath makePath: /configs/solrCloudCollectionConfig/open-exchange-rates.json
[junit4] 2> 3778630 T12892 oascc.SolrZkClient.makePath makePath: /configs/solrCloudCollectionConfig/protwords.txt
[junit4] 2> 3778637 T12892 oascc.SolrZkClient.makePath makePath: /configs/solrCloudCollectionConfig/stopwords.txt
[junit4] 2> 3778644 T12892 oascc.SolrZkClient.makePath makePath: /configs/solrCloudCollectionConfig/synonyms.txt
[junit4] 2> 3778657 T12894 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1469b53de5d0015, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 3778665 T12972 oasha.CollectionsHandler.handleCreateAction Creating Collection : numShards=2&replicationFactor=2&wt=javabin&collection.configName=solrCloudCollectionConfig&version=2&name=testSolrCloudCollection&action=CREATE
[junit4] 2> 3778669 T12932 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/collection-queue-work state: SyncConnected type NodeChildrenChanged
[junit4] 2> 3778673 T12985 oasc.OverseerCollectionProcessor.run Overseer Collection Processor: Get the message id:/overseer/collection-queue-work/qn-0000000000 message:{
[junit4] 2> "operation":"createcollection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "name":"testSolrCloudCollection",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"solrCloudCollectionConfig",
[junit4] 2> "numShards":"2"}
[junit4] 2> 3778676 T13007 oasc.OverseerCollectionProcessor.processMessage WARN OverseerCollectionProcessor.processMessage : createcollection , {
[junit4] 2> "operation":"createcollection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "name":"testSolrCloudCollection",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"solrCloudCollectionConfig",
[junit4] 2> "numShards":"2"}
[junit4] 2> 3778679 T13007 oasc.OverseerCollectionProcessor.createConfNode creating collections conf node /collections/testSolrCloudCollection
[junit4] 2> 3778679 T13007 oascc.SolrZkClient.makePath makePath: /collections/testSolrCloudCollection
[junit4] 2> 3778693 T12932 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 3778701 T12983 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 3778703 T12983 oasc.Overseer$ClusterStateUpdater.buildCollection building a new collection: testSolrCloudCollection
[junit4] 2> 3778703 T12983 oasc.Overseer$ClusterStateUpdater.createCollection Create collection testSolrCloudCollection with shards [shard1, shard2]
[junit4] 2> 3778711 T12932 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 3778716 T12999 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 3778716 T12948 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 3778717 T13002 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 3778717 T12980 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 3778716 T12932 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 3778717 T12964 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 3778792 T13007 oasc.OverseerCollectionProcessor.createCollection Creating SolrCores for new collection testSolrCloudCollection, shardNames [shard1, shard2] , replicationFactor : 2
[junit4] 2> 3778792 T13007 oasc.OverseerCollectionProcessor.createCollection Creating shard testSolrCloudCollection_shard1_replica1 as part of slice shard1 of collection testSolrCloudCollection on 127.0.0.1:64253_solr
[junit4] 2> 3778794 T13007 oasc.OverseerCollectionProcessor.createCollection Creating shard testSolrCloudCollection_shard1_replica2 as part of slice shard1 of collection testSolrCloudCollection on 127.0.0.1:64277_solr
[junit4] 2> 3778797 T12982 oasc.Overseer$ClusterStateUpdater.checkIfIamStillLeader ERROR could not read the data org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:277)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:274)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:274)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:306)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.access$300(Overseer.java:85)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater$1.run(Overseer.java:289)
[junit4] 2>
[junit4] 2> 3778798 T13007 oasc.OverseerCollectionProcessor.createCollection Creating shard testSolrCloudCollection_shard2_replica1 as part of slice shard2 of collection testSolrCloudCollection on 127.0.0.1:64229_solr
[junit4] 2> 3778799 T12914 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 3778803 T12957 oasha.CoreAdminHandler.handleCreateAction core create command numShards=2&qt=/admin/cores&shard=shard1&wt=javabin&collection.configName=solrCloudCollectionConfig&version=2&name=testSolrCloudCollection_shard1_replica1&collection=testSolrCloudCollection&action=CREATE
[junit4] 2> 3778804 T12988 oasha.CoreAdminHandler.handleCreateAction core create command numShards=2&qt=/admin/cores&shard=shard1&wt=javabin&collection.configName=solrCloudCollectionConfig&version=2&name=testSolrCloudCollection_shard1_replica2&collection=testSolrCloudCollection&action=CREATE
[junit4] 2> 3778807 T13007 oasc.OverseerCollectionProcessor.createCollection Creating shard testSolrCloudCollection_shard2_replica2 as part of slice shard2 of collection testSolrCloudCollection on 127.0.0.1:64241_solr
[junit4] 2> 3778808 T12957 oasc.ZkController.publish publishing core=testSolrCloudCollection_shard1_replica1 state=down collection=testSolrCloudCollection
[junit4] 2> 3778810 T12922 oasha.CoreAdminHandler.handleCreateAction core create command numShards=2&qt=/admin/cores&shard=shard2&wt=javabin&collection.configName=solrCloudCollectionConfig&version=2&name=testSolrCloudCollection_shard2_replica1&collection=testSolrCloudCollection&action=CREATE
[junit4] 2> 3778811 T12957 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 3778811 T12988 oasc.ZkController.publish publishing core=testSolrCloudCollection_shard1_replica2 state=down collection=testSolrCloudCollection
[junit4] 2> 3778811 T12937 oasha.CoreAdminHandler.handleCreateAction core create command numShards=2&qt=/admin/cores&shard=shard2&wt=javabin&collection.configName=solrCloudCollectionConfig&version=2&name=testSolrCloudCollection_shard2_replica2&collection=testSolrCloudCollection&action=CREATE
[junit4] 2> 3778811 T12932 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 3778815 T12988 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 3778816 T12922 oasc.ZkController.publish publishing core=testSolrCloudCollection_shard2_replica1 state=down collection=testSolrCloudCollection
[junit4] 2> 3778816 T12937 oasc.ZkController.publish publishing core=testSolrCloudCollection_shard2_replica2 state=down collection=testSolrCloudCollection
[junit4] 2> 3778818 T12932 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 3778818 T12922 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 3778818 T12983 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 3778819 T12937 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 3778820 T12983 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64253/solr",
[junit4] 2> "core":"testSolrCloudCollection_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:64253_solr",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testSolrCloudCollection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 3778828 T12983 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64277/solr",
[junit4] 2> "core":"testSolrCloudCollection_shard1_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:64277_solr",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testSolrCloudCollection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 3778832 T12932 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 3778833 T12983 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64229/solr",
[junit4] 2> "core":"testSolrCloudCollection_shard2_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:64229_solr",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testSolrCloudCollection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 3778837 T12932 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 3778838 T12983 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64241/solr",
[junit4] 2> "core":"testSolrCloudCollection_shard2_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:64241_solr",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testSolrCloudCollection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 3778841 T12932 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 3778946 T12980 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 3778947 T12999 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 3778948 T12948 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 3778948 T12932 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 3778947 T13002 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 3778948 T12964 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 3779792 T12957 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testSolrCloudCollection_shard1_replica1
[junit4] 2> 3779797 T12988 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testSolrCloudCollection_shard1_replica2
[junit4] 2> 3779797 T12957 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testSolrCloudCollection
[junit4] 2> 3779798 T12957 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 3779798 T12957 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testSolrCloudCollection
[junit4] 2> 3779800 T12937 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testSolrCloudCollection_shard2_replica2
[junit4] 2> 3779801 T12957 oascc.ZkStateReader.readConfigName path=/collections/testSolrCloudCollection configName=solrCloudCollectionConfig specified config exists in ZooKeeper
[junit4] 2> 3779802 T12957 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\testSolrCloudCollection_shard1_replica1\'
[junit4] 2> 3779804 T12922 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testSolrCloudCollection_shard2_replica1
[junit4] 2> 3779806 T12922 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testSolrCloudCollection
[junit4] 2> 3779807 T12988 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testSolrCloudCollection
[junit4] 2> 3779809 T12937 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testSolrCloudCollection
[junit4] 2> 3779809 T12922 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 3779810 T12922 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testSolrCloudCollection
[junit4] 2> 3779811 T12988 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 3779811 T12988 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testSolrCloudCollection
[junit4] 2> 3779814 T12937 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 3779814 T12937 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testSolrCloudCollection
[junit4] 2> 3779815 T12922 oascc.ZkStateReader.readConfigName path=/collections/testSolrCloudCollection configName=solrCloudCollectionConfig specified config exists in ZooKeeper
[junit4] 2> 3779815 T12922 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\testSolrCloudCollection_shard2_replica1\'
[junit4] 2> 3779823 T12988 oascc.ZkStateReader.readConfigName path=/collections/testSolrCloudCollection configName=solrCloudCollectionConfig specified config exists in ZooKeeper
[junit4] 2> 3779823 T12988 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\testSolrCloudCollection_shard1_replica2\'
[junit4] 2> 3779834 T12937 oascc.ZkStateReader.readConfigName path=/collections/testSolrCloudCollection configName=solrCloudCollectionConfig specified config exists in ZooKeeper
[junit4] 2> 3779834 T12937 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\testSolrCloudCollection_shard2_replica2\'
[junit4] 2> 3779870 T12957 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 3779877 T12922 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 3779912 T12957 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 3779915 T12957 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 3779923 T12957 oass.IndexSchema.readSchema [testSolrCloudCollection_shard1_replica1] Schema name=test
[junit4] 2> 3779951 T12988 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 3779993 T12988 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 3779999 T12922 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 3780005 T12988 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 3780013 T12988 oass.IndexSchema.readSchema [testSolrCloudCollection_shard1_replica2] Schema name=test
[junit4] 2> 3780085 T12922 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 3780092 T12922 oass.IndexSchema.readSchema [testSolrCloudCollection_shard2_replica1] Schema name=test
[junit4] 2> 3780316 T12957 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 3780331 T12937 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 3780376 T12937 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 3780378 T12957 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 3780381 T12957 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 3780501 T12988 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 3780506 T12988 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 3780510 T12988 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 3780512 T12937 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 3780521 T12937 oass.IndexSchema.readSchema [testSolrCloudCollection_shard2_replica2] Schema name=test
[junit4] 2> 3780576 T12922 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 3780580 T12922 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 3780583 T12922 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 3780612 T12988 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 3780617 T12988 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 3780622 T12988 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3780623 T12922 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 3780625 T12988 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 3780625 T12957 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 3780625 T12988 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 3780626 T12988 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3780629 T12922 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 3780631 T12988 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 3780631 T12988 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 3780631 T12988 oasc.CoreContainer.create Creating SolrCore 'testSolrCloudCollection_shard1_replica2' using configuration from collection testSolrCloudCollection
[junit4] 2> 3780631 T12988 oasc.SolrCore.initDirectoryFactory solr.RAMDirectoryFactory
[junit4] 2> 3780634 T12957 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 3780638 T12922 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3780645 T12922 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 3780647 T12922 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 3780647 T12922 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3780649 T12957 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3780664 T12957 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 3780664 T12957 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 3780664 T12957 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3780667 T12922 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 3780667 T12922 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 3780668 T12922 oasc.CoreContainer.create Creating SolrCore 'testSolrCloudCollection_shard2_replica1' using configuration from collection testSolrCloudCollection
[junit4] 2> 3780668 T12922 oasc.SolrCore.initDirectoryFactory solr.RAMDirectoryFactory
[junit4] 2> 3780675 T12957 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 3780675 T12957 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 3780676 T12957 oasc.CoreContainer.create Creating SolrCore 'testSolrCloudCollection_shard1_replica1' using configuration from collection testSolrCloudCollection
[junit4] 2> 3780676 T12957 oasc.SolrCore.initDirectoryFactory solr.RAMDirectoryFactory
[junit4] 2> 3780686 T12988 oasc.SolrCore.<init> [testSolrCloudCollection_shard1_replica2] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\testSolrCloudCollection_shard1_replica2\, dataDir=data\
[junit4] 2> 3780686 T12988 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@142f76f
[junit4] 2> 3780686 T12957 oasc.SolrCore.<init> [testSolrCloudCollection_shard1_replica1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\testSolrCloudCollection_shard1_replica1\, dataDir=data\
[junit4] 2> 3780687 T12988 oasc.CachingDirectoryFactory.get return new directory for data\
[junit4] 2> 3780687 T12957 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@142f76f
[junit4] 2> 3780687 T12988 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=data\index/
[junit4] 2> 3780688 T12957 oasc.CachingDirectoryFactory.get return new directory for data\
[junit4] 2> 3780688 T12988 oasc.SolrCore.initIndex WARN [testSolrCloudCollection_shard1_replica2] Solr index directory 'data\index' doesn't exist. Creating new index...
[junit4] 2> 3780689 T12957 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=data\index/
[junit4] 2> 3780689 T12988 oasc.CachingDirectoryFactory.get return new directory for data\index
[junit4] 2> 3780689 T12957 oasc.SolrCore.initIndex WARN [testSolrCloudCollection_shard1_replica1] Solr index directory 'data\index' doesn't exist. Creating new index...
[junit4] 2> 3780690 T12957 oasc.CachingDirectoryFactory.get return new directory for data\index
[junit4] 2> 3780690 T12988 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RAMDirectory@1b816c2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@51bcfd,segFN=segments_1,generation=1}
[junit4] 2> 3780690 T12988 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3780690 T12957 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RAMDirectory@1e84139 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4e917d,segFN=segments_1,generation=1}
[junit4] 2> 3780690 T12957 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3780693 T12988 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 3780693 T12957 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 3780693 T12922 oasc.SolrCore.<init> [testSolrCloudCollection_shard2_replica1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\testSolrCloudCollection_shard2_replica1\, dataDir=data\
[junit4] 2> 3780694 T12957 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 3780693 T12988 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 3780694 T12957 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 3780695 T12957 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 3780695 T12957 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 3780695 T12957 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 3780697 T12957 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 3780697 T12957 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 3780697 T12957 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 3780698 T12957 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 3780698 T12957 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 3780698 T12957 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 3780698 T12957 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 3780699 T12957 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 3780700 T12957 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 3780700 T12957 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 3780701 T12957 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 3780694 T12922 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@142f76f
[junit4] 2> 3780695 T12988 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 3780703 T12988 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 3780703 T12988 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 3780703 T12988 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 3780704 T12988 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 3780704 T12988 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 3780704 T12988 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 3780705 T12988 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 3780705 T12988 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 3780706 T12988 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 3780706 T12988 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 3780706 T12988 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 3780707 T12988 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 3780708 T12988 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 3780708 T12988 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 3780709 T12922 oasc.CachingDirectoryFactory.get return new directory for data\
[junit4] 2> 3780709 T12922 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=data\index/
[junit4] 2> 3780709 T12922 oasc.SolrCore.initIndex WARN [testSolrCloudCollection_shard2_replica1] Solr index directory 'data\index' doesn't exist. Creating new index...
[junit4] 2> 3780710 T12922 oasc.CachingDirectoryFactory.get return new directory for data\index
[junit4] 2> 3780711 T12922 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RAMDirectory@1d2a9cd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f89046,segFN=segments_1,generation=1}
[junit4] 2> 3780711 T12922 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3780714 T12922 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 3780714 T12922 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 3780715 T12922 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 3780715 T12922 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 3780715 T12922 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 3780716 T12922 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 3780716 T12922 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 3780716 T12922 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 3780716 T12922 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 3780717 T12922 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 3780717 T12922 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 3780717 T12922 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 3780717 T12922 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 3780734 T12922 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 3780734 T12922 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 3780734 T12922 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 3780734 T12922 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 3780742 T12957 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3780742 T12922 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3780747 T12922 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 3780747 T12922 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 3780748 T12922 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RAMDirectory@1d2a9cd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f89046,segFN=segments_1,generation=1}
[junit4] 2> 3780748 T12922 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3780749 T12922 oass.SolrIndexSearcher.<init> Opening Searcher@3e08[testSolrCloudCollection_shard2_replica1] main
[junit4] 2> 3780749 T12922 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testSolrCloudCollection
[junit4] 2> 3780742 T12988 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3780755 T12988 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 3780755 T12988 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 3780756 T12988 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RAMDirectory@1b816c2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@51bcfd,segFN=segments_1,generation=1}
[junit4] 2> 3780756 T12988 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3780756 T12988 oass.SolrIndexSearcher.<init> Opening Searcher@781b44[testSolrCloudCollection_shard1_replica2] main
[junit4] 2> 3780756 T12988 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testSolrCloudCollection
[junit4] 2> 3780758 T12957 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 3780758 T12957 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 3780759 T12957 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RAMDirectory@1e84139 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4e917d,segFN=segments_1,generation=1}
[junit4] 2> 3780760 T12957 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3780760 T12957 oass.SolrIndexSearcher.<init> Opening Searcher@118aec1[testSolrCloudCollection_shard1_replica1] main
[junit4] 2> 3780760 T12957 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testSolrCloudCollection
[junit4] 2> 3780762 T12922 oascc.ZkStateReader.readConfigName path=/collections/testSolrCloudCollection configName=solrCloudCollectionConfig specified config exists in ZooKeeper
[junit4] 2> 3780762 T12922 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/solrCloudCollectionConfig
[junit4] 2> 3780762 T12957 oascc.ZkStateReader.readConfigName path=/collections/testSolrCloudCollection configName=solrCloudCollectionConfig specified config exists in ZooKeeper
[junit4] 2> 3780762 T12988 oascc.ZkStateReader.readConfigName path=/collections/testSolrCloudCollection configName=solrCloudCollectionConfig specified config exists in ZooKeeper
[junit4] 2> 3780764 T12988 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/solrCloudCollectionConfig
[junit4] 2> 3780764 T12922 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/solrCloudCollectionConfig
[junit4] 2> 3780764 T12922 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 3780765 T12922 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/solrCloudCollectionConfig
[junit4] 2> 3780763 T12957 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/solrCloudCollectionConfig
[junit4] 2> 3780767 T12988 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/solrCloudCollectionConfig
[junit4] 2> 3780767 T12988 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 3780767 T12988 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/solrCloudCollectionConfig
[junit4] 2> 3780768 T12922 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/solrCloudCollectionConfig/_rest_managed.json
[junit4] 2> 3780768 T12922 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/solrCloudCollectionConfig
[junit4] 2> 3780768 T12922 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 3780769 T12922 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 3780774 T12922 oasc.CoreContainer.registerCore registering core: testSolrCloudCollection_shard2_replica1
[junit4] 2> 3780774 T12922 oasc.ZkController.register Register replica - core:testSolrCloudCollection_shard2_replica1 address:http://127.0.0.1:64229/solr collection:testSolrCloudCollection shard:shard2
[junit4] 2> 3780775 T12988 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/solrCloudCollectionConfig/_rest_managed.json
[junit4] 2> 3780775 T12988 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/solrCloudCollectionConfig
[junit4] 2> 3780775 T12988 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 3780775 T12988 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 3780777 T12957 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/solrCloudCollectionConfig
[junit4] 2> 3780780 T12957 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 3780780 T12957 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/solrCloudCollectionConfig
[junit4] 2> 3780779 T12988 oasc.CoreContainer.registerCore registering core: testSolrCloudCollection_shard1_replica2
[junit4] 2> 3780782 T12988 oasc.ZkController.register Register replica - core:testSolrCloudCollection_shard1_replica2 address:http://127.0.0.1:64277/solr collection:testSolrCloudCollection shard:shard1
[junit4] 2> 3780783 T12922 oascc.SolrZkClient.makePath makePath: /collections/testSolrCloudCollection/leader_elect/shard2/election
[junit4] 2> 3780780 T13013 oasc.SolrCore.registerSearcher [testSolrCloudCollection_shard2_replica1] Registered new searcher Searcher@3e08[testSolrCloudCollection_shard2_replica1] main{UninvertingDirectoryReader()}
[junit4] 2> 3780780 T13014 oasc.SolrCore.registerSearcher [testSolrCloudCollection_shard1_replica2] Registered new searcher Searcher@781b44[testSolrCloudCollection_shard1_replica2] main{UninvertingDirectoryReader()}
[junit4] 2> 3780785 T12988 oascc.SolrZkClient.makePath makePath: /collections/testSolrCloudCollection/leader_elect/shard1/election
[junit4] 2> 3780786 T12957 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/solrCloudCollectionConfig/_rest_managed.json
[junit4] 2> 3780786 T12957 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json usin
[...truncated too long message...]
TestRandomMergePolicy, BasicFunctionalityTest, HdfsBasicDistributedZk2Test, TestManagedResource, FieldAnalysisRequestHandlerTest, TestDefaultSearchFieldResource, RangeFacetTest, TestSolrXmlPersistor, TestJmxIntegration, TestNonNRTOpen, TestSerializedLuceneMatchVersion, BadCopyFieldTest, TestSolrDeletionPolicy1, TestIndexingPerformance, SpellCheckComponentTest, TestAtomicUpdateErrorCases, TestTrie, TestStressLucene, QueryParsingTest, TestReversedWildcardFilterFactory, SolrIndexSplitterTest, TestDocSet, IndexBasedSpellCheckerTest, ShowFileRequestHandlerTest, AnalysisErrorHandlingTest, SpellPossibilityIteratorTest, TestNonDefinedSimilarityFactory, TestSimpleQParserPlugin, StandardRequestHandlerTest, TestShortCircuitedRequests, TestStressRecovery, HdfsCollectionsAPIDistributedZkTest, TestConfig, TestSolrDeletionPolicy2, BJQParserTest, OverseerCollectionProcessorTest, ClusterStateUpdateTest, SoftAutoCommitTest, TestChildDocTransformer, TestJmxMonitoredMap, ShardRoutingCustomTest, TestCollationFieldDocValues, OpenCloseCoreStressTest, OverseerStatusTest, TestSurroundQueryParser, CoreAdminCreateDiscoverTest, TestElisionMultitermQuery, XsltUpdateRequestHandlerTest, OverseerTest, TestHighlightDedupGrouping, TestPseudoReturnFields, TestSolrIndexConfig, TestCustomSort, XmlUpdateRequestHandlerTest, AssignTest, SpatialFilterTest, SolrTestCaseJ4Test, EchoParamsTest, AnalysisAfterCoreReloadTest, BadIndexSchemaTest, TestSuggestSpellingConverter, TermVectorComponentTest, TestAnalyzeInfixSuggestions, BasicDistributedZk2Test, TestSort, AutoCommitTest, TestQuerySenderListener, TestManagedStopFilterFactory, TestStandardQParsers, TestRemoteStreaming, SSLMigrationTest, TestFieldCollectionResource, TestModifyConfFiles, NoFacetTest, PreAnalyzedUpdateProcessorTest, DirectSolrConnectionTest, TestCloudManagedSchemaAddFields, AtomicUpdatesTest, TestFiltering, BasicZkTest, MultiTermTest, TestFunctionQuery, DistributedQueryComponentOptimizationTest, CustomCollectionTest, TestCollectionAPI, TestSolrCoreProperties, TestSearcherReuse, TestStressVersions, DistributedSuggestComponentTest, CoreAdminRequestStatusTest, AnalyticsMergeStrategyTest, TestSolrXml, NoCacheHeaderTest, CircularListTest, TestClassNameShortening, TestRealTimeGet, SuggesterTSTTest, TestBlendedInfixSuggestions, SOLR749Test, TestFaceting, HdfsWriteToMultipleCollectionsTest, InfoHandlerTest, TestIndexSearcher, SuggesterTest, AbstractAnalyticsFacetTest, RankQueryTest, TestAnalyzedSuggestions, StatsComponentTest, MinimalSchemaTest, FullSolrCloudDistribCmdsTest, UpdateParamsTest, HdfsLockFactoryTest, TestHighFrequencyDictionaryFactory, TestExpandComponent, ZkSolrClientTest, HdfsRecoveryZkTest, CoreContainerCoreInitFailuresTest, TestMaxScoreQueryParser, TestReRankQParserPlugin, PrimUtilsTest, DateRangeFieldTest, TestSolrQueryParserDefaultOperatorResource, TestLFUCache, TestRandomDVFaceting, SliceStateUpdateTest, QueryResultKeyTest, TestFoldingMultitermQuery, DistanceFunctionTest, RegexBoostProcessorTest, PeerSyncTest, TestAddFieldRealTimeGet, ShardSplitTest, OpenExchangeRatesOrgProviderTest, TestPHPSerializedResponseWriter, RecoveryZkTest, TestCloudInspectUtil, ZkCLITest, TestFieldSortValues, HdfsChaosMonkeySafeLeaderTest, TestDistributedMissingSort, BinaryUpdateRequestHandlerTest, TestRangeQuery, TestCoreDiscovery, TestSolrJ, TestSolr4Spatial, JSONWriterTest, DisMaxRequestHandlerTest, TestCodecSupport, TestDistributedSearch, IndexSchemaRuntimeFieldTest, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, SyncSliceTest, LeaderElectionIntegrationTest, ShardRoutingTest, TestReplicationHandler, TestRandomFaceting, LeaderElectionTest, TestZkChroot, TestRecovery, TestHashPartitioner, DistributedSpellCheckComponentTest, TestStressReorder, TestJoin, TestMultiCoreConfBootstrap, HardAutoCommitTest, DistributedTermsComponentTest, TestCoreContainer, SimpleFacetsTest, SolrCoreTest, SolrCmdDistributorTest, QueryElevationComponentTest, ConvertedLegacyTest, TestLazyCores, DirectUpdateHandlerTest, TestBadConfig, HighlighterTest, CurrencyFieldOpenExchangeTest, CurrencyFieldXmlFileTest, TestExtendedDismaxParser, SpellCheckCollatorTest, SuggesterFSTTest, CoreAdminHandlerTest, DocValuesTest, SuggesterWFSTTest, TestCSVLoader, PolyFieldTest, SchemaVersionSpecificBehaviorTest, SolrCoreCheckLockOnStartupTest, TestUpdate, FieldMutatingUpdateProcessorTest, TestWordDelimiterFilterFactory, QueryEqualityTest, DirectUpdateHandlerOptimizeTest, DefaultValueUpdateProcessorTest, SortByFunctionTest, DocValuesMultiTest, SolrInfoMBeanTest, DebugComponentTest, CacheHeaderTest, LukeRequestHandlerTest, TestQueryUtils, DirectSolrSpellCheckerTest, TestQueryTypes, PrimitiveFieldTypeTest, TestOmitPositions, FileBasedSpellCheckerTest, TermsComponentTest, RequestHandlersTest, DocumentBuilderTest, TestValueSourceCache, PathHierarchyTokenizerFactoryTest, RequiredFieldsTest, FastVectorHighlighterTest, LoggingHandlerTest, SolrPluginUtilsTest, IndexSchemaTest, TestCollationField, ReturnFieldsTest, UpdateRequestProcessorFactoryTest, TestCSVResponseWriter, MBeansHandlerTest, UniqFieldsUpdateProcessorFactoryTest, JsonLoaderTest, TestPartialUpdateDeduplication, CSVRequestHandlerTest, SearchHandlerTest, TestBinaryResponseWriter, HighlighterConfigTest, TestQuerySenderNoQuery, CopyFieldTest, ResponseLogComponentTest, SolrIndexConfigTest, SampleTest, TestBinaryField, TestSearchPerf, NumericFieldsTest, OutputWriterTest, TestFuzzyAnalyzedSuggestions, ExternalFileFieldSortTest, TestPostingsSolrHighlighter, TestCharFilters, SynonymTokenizerTest, TestXIncludeConfig, TestSweetSpotSimilarityFactory, TestDFRSimilarityFactory, TestPerFieldSimilarity, TestLMDirichletSimilarityFactory, TestLMJelinekMercerSimilarityFactory, TestBM25SimilarityFactory, TestDefaultSimilarityFactory, TestFastWriter, TimeZoneUtilsTest, ResourceLoaderTest, TestFastOutputStream, ScriptEngineTest, URLClassifyProcessorTest, PluginInfoTest, TestFastLRUCache, DateMathParserTest, TestSolrXMLSerializer, PreAnalyzedFieldTest, TestSystemIdResolver, DOMUtilTest, RAMDirectoryFactoryTest, ClusterStateTest, TestLRUCache, TestUtils, ZkNodePropsTest, SliceStateTest, UUIDFieldTest, FileUtilsTest, TestRTGBase, TestCursorMarkWithoutUniqueKey, AbstractAnalyticsStatsTest, ExpressionTest, FieldFacetExtrasTest, FieldFacetTest, QueryFacetTest, FunctionTest, AliasIntegrationTest, AsyncMigrateRouteKeyTest, CollectionsAPIAsyncDistributedZkTest, ConnectionManagerTest, DeleteInactiveReplicaTest, DeleteReplicaTest, DeleteShardTest, DistribCursorPagingTest, DistribDocExpirationUpdateProcessorTest, MigrateRouteKeyTest, OverseerRolesTest, RemoteQueryErrorTest, ReplicationFactorTest, TestDistribDocBasedVersion, TestMiniSolrCloudCluster]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestMiniSolrCloudCluster -Dtests.seed=766FE4C41ED2E910 -Dtests.slow=true -Dtests.locale=ms_MY -Dtests.timezone=America/Kentucky/Monticello -Dtests.file.encoding=Cp1252
[junit4] ERROR 0.00s | TestMiniSolrCloudCluster (suite) <<<
[junit4] > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts):
[junit4] > C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\testSolrCloudCollection_shard1_replica1\data\tlog\tlog.0000000000000000000
[junit4] > C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\testSolrCloudCollection_shard1_replica1\data\tlog
[junit4] > C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\testSolrCloudCollection_shard1_replica1\data
[junit4] > C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001\testSolrCloudCollection_shard1_replica1
[junit4] > C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.TestMiniSolrCloudCluster-766FE4C41ED2E910-001\tempDir-001
[junit4] > at __randomizedtesting.SeedInfo.seed([766FE4C41ED2E910]:0)
[junit4] > at org.apache.lucene.util.TestUtil.rm(TestUtil.java:118)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] Completed in 16.80s, 1 test, 1 error <<< FAILURES!
[...truncated 189 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:467: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:447: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:45: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:37: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:189: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:496: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1296: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:920: There were test failures: 416 suites, 1703 tests, 1 suite-level error, 42 ignored (21 assumptions)
Total time: 106 minutes 27 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.7.0_60 -server -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-trunk-Windows (64bit/jdk1.7.0_60) - Build #
4116 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/4116/
Java: 64bit/jdk1.7.0_60 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed.
REGRESSION: org.apache.solr.cloud.OverseerTest.testOverseerFailure
Error Message:
Could not register as the leader because creating the ephemeral registration node in ZooKeeper failed
Stack Trace:
org.apache.solr.common.SolrException: Could not register as the leader because creating the ephemeral registration node in ZooKeeper failed
at __randomizedtesting.SeedInfo.seed([9325B76AF24B6B24:972D3899E0EE8405]:0)
at org.apache.solr.cloud.ShardLeaderElectionContextBase.runLeaderProcess(ElectionContext.java:135)
at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:142)
at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:110)
at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:134)
at org.apache.solr.cloud.LeaderElector.joinElection(LeaderElector.java:267)
at org.apache.solr.cloud.OverseerTest$MockZKController.publishState(OverseerTest.java:155)
at org.apache.solr.cloud.OverseerTest.testOverseerFailure(OverseerTest.java:661)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:360)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:793)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:453)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:360)
at java.lang.Thread.run(Thread.java:745)
Caused by: org.apache.zookeeper.KeeperException$NodeExistsException: KeeperErrorCode = NodeExists for /collections/collection1/leaders/shard1
at org.apache.zookeeper.KeeperException.create(KeeperException.java:119)
at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
at org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:783)
at org.apache.solr.common.cloud.SolrZkClient$10.execute(SolrZkClient.java:432)
at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:429)
at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:386)
at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:373)
at org.apache.solr.cloud.ShardLeaderElectionContextBase$1.execute(ElectionContext.java:127)
at org.apache.solr.common.util.RetryUtil.retryOnThrowable(RetryUtil.java:31)
at org.apache.solr.cloud.ShardLeaderElectionContextBase.runLeaderProcess(ElectionContext.java:122)
... 46 more
Build Log:
[...truncated 10954 lines...]
[junit4] Suite: org.apache.solr.cloud.OverseerTest
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.OverseerTest-9325B76AF24B6B24-001\init-core-data-001
[junit4] 2> 1448885 T4207 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (true)
[junit4] 2> 1448885 T4207 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 1448885 T4207 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 1448888 T4207 oas.SolrTestCaseJ4.setUp ###Starting testShardAssignmentBigger
[junit4] 2> 1448890 T4207 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1448890 T4208 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 1448977 T4207 oasc.ZkTestServer.run start zk server on port:51630
[junit4] 2> 1448978 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1448982 T4214 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1a1add7e name:ZooKeeperConnection Watcher:127.0.0.1:51630 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1448983 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1448984 T4209 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1469cfc43440000, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 1448985 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1448987 T4216 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@244d3757 name:ZooKeeperConnection Watcher:127.0.0.1:51630 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1448988 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1448988 T4207 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 1448993 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1448995 T4218 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@759ad055 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1448996 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1448996 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 1449002 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449005 T4220 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5acd2576 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449005 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449006 T4207 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 1449009 T4207 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 1449014 T4207 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:51630_solr
[junit4] 2> 1449014 T4207 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 1449017 T4207 oasc.Overseer.start Overseer (id=91933449681895427-127.0.0.1:51630_solr-n_0000000000) starting
[junit4] 2> 1449020 T4207 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 1449024 T4207 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 1449028 T4207 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
[junit4] 2> 1449033 T4207 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
[junit4] 2> 1449037 T4207 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
[junit4] 2> 1449042 T4207 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 1449049 T4222 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 1449050 T4207 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 1449051 T4221 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 1449053 T4207 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 1449055 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449060 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449061 T4224 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1f6ba2bb name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449062 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449063 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449066 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node0
[junit4] 2> 1449071 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449073 T4226 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@41c6db14 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449073 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449075 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449077 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node1
[junit4] 2> 1449082 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449084 T4228 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5a39ce3f name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449084 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449086 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449088 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node2
[junit4] 2> 1449093 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449095 T4230 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@54e99fca name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449095 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449097 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449099 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node3
[junit4] 2> 1449104 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449106 T4232 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@18f846cf name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449106 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449108 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449110 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node4
[junit4] 2> 1449115 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449117 T4234 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@32444787 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449118 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449119 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449122 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node5
[junit4] 2> 1449128 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449130 T4236 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3eef628b name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449130 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449131 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449134 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node6
[junit4] 2> 1449139 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449141 T4238 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3298d088 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449141 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449142 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449146 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node7
[junit4] 2> 1449152 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449153 T4240 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@49c50491 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449153 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449154 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449158 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node8
[junit4] 2> 1449163 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449165 T4242 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5e8ef81b name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449165 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449167 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449169 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node9
[junit4] 2> 1449175 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449177 T4244 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6203bbce name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449178 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449179 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449181 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node10
[junit4] 2> 1449188 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449190 T4246 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@33625afc name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449190 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449191 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449194 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node11
[junit4] 2> 1449201 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449202 T4248 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@41bbdc16 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449202 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449204 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449206 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node12
[junit4] 2> 1449213 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449215 T4250 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6ed3464e name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449216 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449217 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449219 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node13
[junit4] 2> 1449227 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449228 T4252 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@33f9210f name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449228 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449229 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449233 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node14
[junit4] 2> 1449239 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449241 T4254 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@54343c1e name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449241 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449243 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449245 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node15
[junit4] 2> 1449253 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449255 T4256 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@41a97858 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449255 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449256 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449260 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node16
[junit4] 2> 1449266 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449268 T4258 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2234b265 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449268 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449270 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449272 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node17
[junit4] 2> 1449280 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449282 T4260 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@267a2c36 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449282 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449283 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449286 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node18
[junit4] 2> 1449294 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449296 T4262 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5311ad37 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449296 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449297 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449300 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node19
[junit4] 2> 1449309 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449310 T4264 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@23daa267 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449310 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449311 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449315 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node20
[junit4] 2> 1449324 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449326 T4266 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3e322edd name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449326 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449326 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449330 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node21
[junit4] 2> 1449336 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449338 T4268 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@25773bee name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449339 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449340 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449342 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node22
[junit4] 2> 1449350 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449352 T4270 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4d332769 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449352 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449353 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449356 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node23
[junit4] 2> 1449365 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449366 T4272 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4f66f441 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449367 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449368 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449371 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node24
[junit4] 2> 1449379 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449380 T4274 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@53310c73 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449380 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449382 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449385 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node25
[junit4] 2> 1449393 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449395 T4276 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@394d22a7 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449395 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449396 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449399 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node26
[junit4] 2> 1449407 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449409 T4278 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@59b35ffd name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449409 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449411 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449413 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node27
[junit4] 2> 1449422 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449423 T4280 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@12a3b6bb name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449424 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449425 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449429 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node28
[junit4] 2> 1449437 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449439 T4282 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4beb7d57 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449439 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449439 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449443 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node29
[junit4] 2> 1449452 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449454 T4284 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6fd5a09e name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449454 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449456 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449458 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node30
[junit4] 2> 1449467 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449469 T4286 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@74acb356 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449469 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449470 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449472 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node31
[junit4] 2> 1449483 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449484 T4288 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5d28407d name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449484 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449486 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449488 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node32
[junit4] 2> 1449498 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449500 T4290 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@51a85ce1 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449500 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449501 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449505 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node33
[junit4] 2> 1449513 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449515 T4292 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1ec39b6f name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449515 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449516 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449519 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node34
[junit4] 2> 1449529 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449531 T4294 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@ec481b4 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449532 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449532 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449535 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node35
[junit4] 2> 1449544 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449546 T4296 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@10d1b7e8 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449547 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449548 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449550 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node36
[junit4] 2> 1449562 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449564 T4298 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3e1a323f name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449564 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449565 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449568 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node37
[junit4] 2> 1449578 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449580 T4300 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@659e3b2d name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449580 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449581 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449584 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node38
[junit4] 2> 1449594 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449595 T4302 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@22ec5db6 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449596 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449597 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449599 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node39
[junit4] 2> 1449609 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449612 T4304 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5045a32 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449612 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449613 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449616 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node40
[junit4] 2> 1449626 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449628 T4306 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@645bcaf1 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449629 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449629 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449632 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node41
[junit4] 2> 1449642 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449644 T4308 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4f327233 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449645 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449646 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449649 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node42
[junit4] 2> 1449660 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449662 T4310 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2b836115 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449662 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449663 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449666 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node43
[junit4] 2> 1449678 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449679 T4312 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@45241616 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449679 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449681 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449684 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node44
[junit4] 2> 1449695 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449697 T4314 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@30f23225 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449697 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449699 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449701 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node45
[junit4] 2> 1449712 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449715 T4316 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@54871924 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449715 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449716 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449718 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node46
[junit4] 2> 1449731 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449732 T4318 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@14a6f0fd name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449732 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449733 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449736 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node47
[junit4] 2> 1449748 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449750 T4320 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@cdedbc9 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449751 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449752 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449754 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node48
[junit4] 2> 1449766 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449785 T4322 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@58076d20 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449786 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449787 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449791 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node49
[junit4] 2> 1449803 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449805 T4324 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4fb62cfe name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449806 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449819 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449822 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node50
[junit4] 2> 1449835 T4207 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1449837 T4326 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1a77c597 name:ZooKeeperConnection Watcher:127.0.0.1:51630/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1449837 T4207 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1449838 T4207 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1449841 T4207 oascc.SolrZkClient.makePath makePath: /live_nodes/node51
[junit4] 2> 1449876 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1449886 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1449901 T4221 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1449906 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node3",
[junit4] 2> "core":"core3",
[junit4] 2> "core_node_name":"node3",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node3/solr/"}
[junit4] 2> 1449906 T4221 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2, shard3, shard4, shard5, shard6, shard7, shard8, shard9]
[junit4] 2> 1449906 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard8
[junit4] 2> 1449959 T4286 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449960 T4322 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449960 T4254 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449961 T4258 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449962 T4302 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449962 T4280 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449963 T4226 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449963 T4218 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449964 T4306 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449965 T4236 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449965 T4314 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449965 T4296 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449965 T4244 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449966 T4318 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449967 T4294 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449967 T4316 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449968 T4272 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449968 T4246 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449969 T4260 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449969 T4262 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449970 T4270 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449970 T4278 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449971 T4230 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449971 T4266 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449972 T4308 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449972 T4256 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449973 T4228 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449973 T4282 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449974 T4240 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449974 T4232 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449975 T4224 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449976 T4292 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449976 T4288 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449976 T4252 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449976 T4242 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449977 T4290 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449978 T4284 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449978 T4248 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449979 T4326 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449980 T4310 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449981 T4264 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449981 T4324 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449982 T4312 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449982 T4304 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449982 T4268 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449982 T4276 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449983 T4298 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449983 T4250 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449984 T4238 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449985 T4234 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449985 T4300 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449986 T4320 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1449987 T4274 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 52)
[junit4] 2> 1450040 T4221 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1450041 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node1",
[junit4] 2> "core":"core1",
[junit4] 2> "core_node_name":"node1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node1/solr/"}
[junit4] 2> 1450041 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450041 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard4
[junit4] 2> 1450046 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450052 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node5",
[junit4] 2> "core":"core5",
[junit4] 2> "core_node_name":"node5",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node5/solr/"}
[junit4] 2> 1450052 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450052 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 1450055 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450060 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node7",
[junit4] 2> "core":"core7",
[junit4] 2> "core_node_name":"node7",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node7/solr/"}
[junit4] 2> 1450060 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450060 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard5
[junit4] 2> 1450066 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450071 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node0",
[junit4] 2> "core":"core0",
[junit4] 2> "core_node_name":"node0",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node0/solr/"}
[junit4] 2> 1450071 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450071 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 1450073 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450083 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node2",
[junit4] 2> "core":"core2",
[junit4] 2> "core_node_name":"node2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node2/solr/"}
[junit4] 2> 1450083 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450083 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard6
[junit4] 2> 1450084 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450087 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node4",
[junit4] 2> "core":"core4",
[junit4] 2> "core_node_name":"node4",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node4/solr/"}
[junit4] 2> 1450087 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450087 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard7
[junit4] 2> 1450091 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450093 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node6",
[junit4] 2> "core":"core6",
[junit4] 2> "core_node_name":"node6",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node6/solr/"}
[junit4] 2> 1450093 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450093 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard3
[junit4] 2> 1450099 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450100 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node8",
[junit4] 2> "core":"core8",
[junit4] 2> "core_node_name":"node8",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node8/solr/"}
[junit4] 2> 1450100 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450100 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard9
[junit4] 2> 1450103 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450104 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node10",
[junit4] 2> "core":"core10",
[junit4] 2> "core_node_name":"node10",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node10/solr/"}
[junit4] 2> 1450104 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450104 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard8
[junit4] 2> 1450107 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450108 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node9",
[junit4] 2> "core":"core9",
[junit4] 2> "core_node_name":"node9",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node9/solr/"}
[junit4] 2> 1450108 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450108 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard4
[junit4] 2> 1450111 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450112 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node13",
[junit4] 2> "core":"core13",
[junit4] 2> "core_node_name":"node13",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node13/solr/"}
[junit4] 2> 1450112 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450112 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 1450117 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450117 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node11",
[junit4] 2> "core":"core11",
[junit4] 2> "core_node_name":"node11",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node11/solr/"}
[junit4] 2> 1450117 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450117 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard5
[junit4] 2> 1450122 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450123 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node14",
[junit4] 2> "core":"core14",
[junit4] 2> "core_node_name":"node14",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node14/solr/"}
[junit4] 2> 1450123 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450123 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 1450127 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450128 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node15",
[junit4] 2> "core":"core15",
[junit4] 2> "core_node_name":"node15",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node15/solr/"}
[junit4] 2> 1450128 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450128 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard6
[junit4] 2> 1450132 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450133 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node17",
[junit4] 2> "core":"core17",
[junit4] 2> "core_node_name":"node17",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node17/solr/"}
[junit4] 2> 1450133 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450133 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard7
[junit4] 2> 1450137 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450139 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node19",
[junit4] 2> "core":"core19",
[junit4] 2> "core_node_name":"node19",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node19/solr/"}
[junit4] 2> 1450139 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450139 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard3
[junit4] 2> 1450142 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450144 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node21",
[junit4] 2> "core":"core21",
[junit4] 2> "core_node_name":"node21",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node21/solr/"}
[junit4] 2> 1450144 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450144 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard9
[junit4] 2> 1450147 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450149 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node23",
[junit4] 2> "core":"core23",
[junit4] 2> "core_node_name":"node23",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node23/solr/"}
[junit4] 2> 1450149 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450149 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard8
[junit4] 2> 1450152 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450153 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node37",
[junit4] 2> "core":"core37",
[junit4] 2> "core_node_name":"node37",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node37/solr/"}
[junit4] 2> 1450153 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450153 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard4
[junit4] 2> 1450158 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450159 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node25",
[junit4] 2> "core":"core25",
[junit4] 2> "core_node_name":"node25",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node25/solr/"}
[junit4] 2> 1450159 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450159 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 1450163 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450164 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node27",
[junit4] 2> "core":"core27",
[junit4] 2> "core_node_name":"node27",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node27/solr/"}
[junit4] 2> 1450164 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450164 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard5
[junit4] 2> 1450167 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450170 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node29",
[junit4] 2> "core":"core29",
[junit4] 2> "core_node_name":"node29",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node29/solr/"}
[junit4] 2> 1450170 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450170 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 1450174 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450175 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node31",
[junit4] 2> "core":"core31",
[junit4] 2> "core_node_name":"node31",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node31/solr/"}
[junit4] 2> 1450175 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450175 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard6
[junit4] 2> 1450179 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450180 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node33",
[junit4] 2> "core":"core33",
[junit4] 2> "core_node_name":"node33",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node33/solr/"}
[junit4] 2> 1450180 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450180 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard7
[junit4] 2> 1450184 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450185 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node12",
[junit4] 2> "core":"core12",
[junit4] 2> "core_node_name":"node12",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node12/solr/"}
[junit4] 2> 1450185 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450185 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard3
[junit4] 2> 1450189 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450191 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node16",
[junit4] 2> "core":"core16",
[junit4] 2> "core_node_name":"node16",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node16/solr/"}
[junit4] 2> 1450191 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450191 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard9
[junit4] 2> 1450194 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450196 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node18",
[junit4] 2> "core":"core18",
[junit4] 2> "core_node_name":"node18",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node18/solr/"}
[junit4] 2> 1450196 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450196 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard8
[junit4] 2> 1450199 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450200 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node20",
[junit4] 2> "core":"core20",
[junit4] 2> "core_node_name":"node20",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node20/solr/"}
[junit4] 2> 1450200 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450200 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard4
[junit4] 2> 1450205 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450206 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node22",
[junit4] 2> "core":"core22",
[junit4] 2> "core_node_name":"node22",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node22/solr/"}
[junit4] 2> 1450206 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450206 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 1450210 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450211 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node24",
[junit4] 2> "core":"core24",
[junit4] 2> "core_node_name":"node24",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node24/solr/"}
[junit4] 2> 1450211 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450211 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard5
[junit4] 2> 1450215 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450216 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node26",
[junit4] 2> "core":"core26",
[junit4] 2> "core_node_name":"node26",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node26/solr/"}
[junit4] 2> 1450216 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450216 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 1450220 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450221 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node28",
[junit4] 2> "core":"core28",
[junit4] 2> "core_node_name":"node28",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node28/solr/"}
[junit4] 2> 1450221 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450221 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard6
[junit4] 2> 1450226 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450227 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node30",
[junit4] 2> "core":"core30",
[junit4] 2> "core_node_name":"node30",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"9",
[junit4] 2> "base_url":"http://node30/solr/"}
[junit4] 2> 1450227 T4221 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=9
[junit4] 2> 1450227 T4221 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard7
[junit4] 2> 1450231 T4220 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1450232 T4221 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=9 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node32",
[junit4] 2> "core":"core32",
[junit4] 2> "
[...truncated too long message...]
der(LeaderElector.java:94)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:55)
[junit4] 2> at org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:303)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
[junit4] 2>
[junit4] 2> 1499866 T4559 oasc.LeaderElector$ElectionWatcher.process WARN org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/collection1/leader_elect/shard1/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:1472)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:260)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:257)
[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:257)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:94)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:55)
[junit4] 2> at org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:303)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
[junit4] 2>
[junit4] 2> 1499866 T4559 oasc.LeaderElector$ElectionWatcher.process WARN org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/collection1/leader_elect/shard3/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:1472)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:260)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:257)
[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:257)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:94)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:55)
[junit4] 2> at org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:303)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
[junit4] 2>
[junit4] 2> 1499868 T4207 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:51960 51960
[junit4] 2> 1500831 T4557 oasc.OverseerCollectionProcessor.run WARN Overseer cannot talk to ZK
[junit4] 2> 1502434 T4543 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:51960 51960
[junit4] 2> 1502437 T4207 oas.SolrTestCaseJ4.tearDown ###Ending testShardAssignment
[junit4] 2> 1502438 T4556 oasc.Overseer$ClusterStateUpdater.run Overseer Loop exiting : 127.0.0.1:51960_solr
[junit4] 2> 1502439 T4560 oasc.Overseer$ClusterStateUpdater.checkIfIamStillLeader ERROR could not read the data org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:277)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:274)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:274)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:306)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.access$300(Overseer.java:85)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater$1.run(Overseer.java:289)
[junit4] 2>
[junit4] 2> 1502439 T4207 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 1502440 T4207 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 1506053 T4207 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.cloud.OverseerTest-9325B76AF24B6B24-001
[junit4] 2> NOTE: test params are: codec=Lucene40, sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=ar_BH, timezone=Etc/GMT+6
[junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.7.0_60 (64-bit)/cpus=2,threads=1,free=103601112,total=275660800
[junit4] 2> NOTE: All tests run in this JVM: [PrimitiveFieldTypeTest, AutoCommitTest, QueryResultKeyTest, RecoveryZkTest, TestDocBasedVersionConstraints, TestRemoteStreaming, DocValuesTest, IndexSchemaRuntimeFieldTest, TestSolrDeletionPolicy2, TestInitQParser, TestStressUserVersions, TestFunctionQuery, TestDefaultSimilarityFactory, TestQuerySenderListener, DisMaxRequestHandlerTest, HighlighterTest, CoreAdminCreateDiscoverTest, TestCloudInspectUtil, DateRangeFieldTest, ChaosMonkeyNothingIsSafeTest, TestElisionMultitermQuery, TestDistribDocBasedVersion, TestSchemaResource, ParsingFieldUpdateProcessorsTest, TestReloadAndDeleteDocs, BlockDirectoryTest, FunctionTest, HdfsSyncSliceTest, TestCSVLoader, UUIDFieldTest, ReturnFieldsTest, TestModifyConfFiles, ShardSplitTest, ZkControllerTest, TestReRankQParserPlugin, DocumentAnalysisRequestHandlerTest, DistributedDebugComponentTest, MinimalSchemaTest, NoFacetTest, TestWriterPerf, TestNRTOpen, AsyncMigrateRouteKeyTest, TestMergePolicyConfig, FieldFacetTest, HighlighterMaxOffsetTest, TestNonDefinedSimilarityFactory, SolrIndexSplitterTest, DistributedSpellCheckComponentTest, OutputWriterTest, TestFaceting, ClusterStateTest, TestSolrCoreProperties, TestQuerySenderNoQuery, ZkCLITest, TestPostingsSolrHighlighter, TestFuzzyAnalyzedSuggestions, CursorPagingTest, RangeFacetTest, MoreLikeThisHandlerTest, TestArbitraryIndexDir, TestSolrXmlPersistor, SpatialFilterTest, TestFieldResource, TestCodecSupport, NoCacheHeaderTest, FieldMutatingUpdateProcessorTest, SortByFunctionTest, TestCollectionAPI, QueryElevationComponentTest, RemoteQueryErrorTest, TestClassNameShortening, ZkSolrClientTest, TestCopyFieldCollectionResource, TestManagedSchemaFieldResource, TestCollationField, TestLMJelinekMercerSimilarityFactory, TestSweetSpotSimilarityFactory, TestSearchPerf, TestRestManager, TestFreeTextSuggestions, CustomCollectionTest, TestRandomFaceting, HdfsBasicDistributedZk2Test, MergeStrategyTest, TestDistributedMissingSort, DefaultValueUpdateProcessorTest, TestOmitPositions, TestRealTimeGet, UniqFieldsUpdateProcessorFactoryTest, TestPhraseSuggestions, DirectSolrConnectionTest, TestShortCircuitedRequests, TestUtils, SpellCheckComponentTest, ResourceLoaderTest, TestShardHandlerFactory, TestStressLucene, HighlighterConfigTest, TestHighFrequencyDictionaryFactory, TestLazyCores, TestAtomicUpdateErrorCases, DebugComponentTest, ResponseHeaderTest, TestCoreDiscovery, TestReversedWildcardFilterFactory, TimeZoneUtilsTest, SynonymTokenizerTest, TestSolr4Spatial, TestDynamicFieldResource, TestComplexPhraseQParserPlugin, ChaosMonkeySafeLeaderTest, MultiTermTest, DateMathParserTest, BasicZkTest, MultiThreadedOCPTest, TestRandomMergePolicy, DistribDocExpirationUpdateProcessorTest, OpenExchangeRatesOrgProviderTest, OverseerTest]
[junit4] Completed in 57.19s, 9 tests, 1 error <<< FAILURES!
[...truncated 917 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:467: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:447: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:45: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:37: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:189: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:496: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1296: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:920: There were test failures: 416 suites, 1703 tests, 1 error, 42 ignored (21 assumptions)
Total time: 112 minutes 13 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.7.0_60 -XX:-UseCompressedOops -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any