lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-trunk-Windows (64bit/jdk1.7.0_60) - Build # 4116 - Still Failing!
Date Sun, 15 Jun 2014 01:41:10 GMT
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



Mime
View raw message